[06:49:43.433] New invocation is queued and will start shortly
[06:49:44.502] Starting the invocation (attempt 1)
[06:49:44.531] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:49:44.531] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:49:44.531] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020409054539975648",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020409054539975648",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a",
"gitiles_ref:refs/heads/master"
]
}
[06:49:45.212] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613630984605827",
"id": "8854978992644708816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613630985028802",
"tags": [
"build_address:luci.chromium.ci/win-asan/17404",
"builder:win-asan",
"buildset:commit/git/f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020409054539975648",
"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/8854978992644708816/+/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": "1613630985028916",
"url": "https://ci.chromium.org/b/8854978992644708816",
"utcnow_ts": "1613630985204299"
}
}
[06:49:45.212] Task URL: https://ci.chromium.org/b/8854978992644708816
[06:49:45.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:2:0) after 1m0s
[06:50:45.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:2:0)
[06:50:45.293] Timer tick, asking Buildbucket for the build status
[06:50:45.533] Build 8854978992644708816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:45.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:3:0) after 1m0s
[06:51:45.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:3:0)
[06:51:45.588] Timer tick, asking Buildbucket for the build status
[06:51:45.672] Build 8854978992644708816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:45.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:4:0) after 1m0s
[06:52:45.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:4:0)
[06:52:45.684] Timer tick, asking Buildbucket for the build status
[06:52:45.752] Build 8854978992644708816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:45.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:5:0) after 1m0s
[06:53:45.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:5:0)
[06:53:45.770] Timer tick, asking Buildbucket for the build status
[06:53:45.842] Build 8854978992644708816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:45.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:6:0) after 1m0s
[06:54:10.806] Received PubSub notification, asking Buildbucket for the build status
[06:54:10.894] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:45.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:6:0)
[06:54:45.857] Timer tick, asking Buildbucket for the build status
[06:54:45.946] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:45.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:8:0) after 1m0s
[06:55:45.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:8:0)
[06:55:45.967] Timer tick, asking Buildbucket for the build status
[06:55:46.046] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:46.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:9:0) after 1m0s
[06:56:46.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:9:0)
[06:56:46.131] Timer tick, asking Buildbucket for the build status
[06:56:46.197] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:46.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:10:0) after 1m0s
[06:57:46.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:10:0)
[06:57:46.210] Timer tick, asking Buildbucket for the build status
[06:57:46.514] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:46.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:11:0) after 1m0s
[06:58:46.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:11:0)
[06:58:46.534] Timer tick, asking Buildbucket for the build status
[06:58:46.609] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:46.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:12:0) after 1m0s
[06:59:46.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:12:0)
[06:59:46.617] Timer tick, asking Buildbucket for the build status
[06:59:46.699] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:46.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:13:0) after 1m0s
[07:00:46.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:13:0)
[07:00:46.688] Timer tick, asking Buildbucket for the build status
[07:00:46.772] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:46.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:14:0) after 1m0s
[07:01:46.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:14:0)
[07:01:46.891] Timer tick, asking Buildbucket for the build status
[07:01:47.200] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:47.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:15:0) after 1m0s
[07:02:47.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:15:0)
[07:02:47.239] Timer tick, asking Buildbucket for the build status
[07:02:47.346] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:47.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:16:0) after 1m0s
[07:03:47.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:16:0)
[07:03:47.358] Timer tick, asking Buildbucket for the build status
[07:03:47.440] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:47.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:17:0) after 1m0s
[07:04:47.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:17:0)
[07:04:47.449] Timer tick, asking Buildbucket for the build status
[07:04:47.764] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:47.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:18:0) after 1m0s
[07:05:47.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:18:0)
[07:05:47.822] Timer tick, asking Buildbucket for the build status
[07:05:47.911] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:47.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:19:0) after 1m0s
[07:06:47.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:19:0)
[07:06:47.930] Timer tick, asking Buildbucket for the build status
[07:06:48.013] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:48.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:20:0) after 1m0s
[07:07:48.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:20:0)
[07:07:48.037] Timer tick, asking Buildbucket for the build status
[07:07:48.138] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:48.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:21:0) after 1m0s
[07:08:48.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:21:0)
[07:08:48.227] Timer tick, asking Buildbucket for the build status
[07:08:48.340] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:48.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:22:0) after 1m0s
[07:09:48.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:22:0)
[07:09:48.370] Timer tick, asking Buildbucket for the build status
[07:09:48.476] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:48.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:23:0) after 1m0s
[07:10:48.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:23:0)
[07:10:48.499] Timer tick, asking Buildbucket for the build status
[07:10:48.611] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:48.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:24:0) after 1m0s
[07:11:48.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:24:0)
[07:11:48.663] Timer tick, asking Buildbucket for the build status
[07:11:48.742] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:48.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:25:0) after 1m0s
[07:12:48.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:25:0)
[07:12:48.836] Timer tick, asking Buildbucket for the build status
[07:12:49.012] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:49.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:26:0) after 1m0s
[07:13:49.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:26:0)
[07:13:49.093] Timer tick, asking Buildbucket for the build status
[07:13:49.192] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:49.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:27:0) after 1m0s
[07:14:49.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:27:0)
[07:14:49.227] Timer tick, asking Buildbucket for the build status
[07:14:49.381] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:49.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:28:0) after 1m0s
[07:15:49.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:28:0)
[07:15:49.450] Timer tick, asking Buildbucket for the build status
[07:15:49.574] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:49.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:29:0) after 1m0s
[07:16:49.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:29:0)
[07:16:49.581] Timer tick, asking Buildbucket for the build status
[07:16:49.658] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:49.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:30:0) after 1m0s
[07:17:49.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:30:0)
[07:17:49.725] Timer tick, asking Buildbucket for the build status
[07:17:49.845] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:49.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:31:0) after 1m0s
[07:18:49.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:31:0)
[07:18:49.852] Timer tick, asking Buildbucket for the build status
[07:18:50.118] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:50.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:32:0) after 1m0s
[07:19:50.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:32:0)
[07:19:50.193] Timer tick, asking Buildbucket for the build status
[07:19:50.284] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:50.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:33:0) after 1m0s
[07:20:50.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:33:0)
[07:20:50.355] Timer tick, asking Buildbucket for the build status
[07:20:50.432] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:50.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:34:0) after 1m0s
[07:21:50.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:34:0)
[07:21:50.419] Timer tick, asking Buildbucket for the build status
[07:21:50.495] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:50.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:35:0) after 1m0s
[07:22:50.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:35:0)
[07:22:50.569] Timer tick, asking Buildbucket for the build status
[07:22:50.743] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:50.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:36:0) after 1m0s
[07:23:50.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:36:0)
[07:23:50.774] Timer tick, asking Buildbucket for the build status
[07:23:50.999] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:50.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:37:0) after 1m0s
[07:24:51.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:37:0)
[07:24:51.066] Timer tick, asking Buildbucket for the build status
[07:24:51.178] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:51.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:38:0) after 1m0s
[07:25:51.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:38:0)
[07:25:51.224] Timer tick, asking Buildbucket for the build status
[07:25:51.322] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:51.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:39:0) after 1m0s
[07:26:51.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:39:0)
[07:26:51.389] Timer tick, asking Buildbucket for the build status
[07:26:51.526] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:51.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:40:0) after 1m0s
[07:27:53.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:40:0)
[07:27:53.010] Timer tick, asking Buildbucket for the build status
[07:27:53.341] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:53.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:41:0) after 1m0s
[07:28:53.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:41:0)
[07:28:53.333] Timer tick, asking Buildbucket for the build status
[07:28:53.444] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:53.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:42:0) after 1m0s
[07:29:53.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:42:0)
[07:29:53.430] Timer tick, asking Buildbucket for the build status
[07:29:53.628] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:53.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:43:0) after 1m0s
[07:30:53.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:43:0)
[07:30:53.623] Timer tick, asking Buildbucket for the build status
[07:30:53.918] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:53.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:44:0) after 1m0s
[07:31:54.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:44:0)
[07:31:54.045] Timer tick, asking Buildbucket for the build status
[07:31:54.124] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:54.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:45:0) after 1m0s
[07:32:54.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:45:0)
[07:32:54.193] Timer tick, asking Buildbucket for the build status
[07:32:54.481] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:54.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:46:0) after 1m0s
[07:33:54.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:46:0)
[07:33:54.666] Timer tick, asking Buildbucket for the build status
[07:33:54.744] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:54.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:47:0) after 1m0s
[07:34:54.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:47:0)
[07:34:54.812] Timer tick, asking Buildbucket for the build status
[07:34:54.908] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:54.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:48:0) after 1m0s
[07:35:54.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:48:0)
[07:35:54.994] Timer tick, asking Buildbucket for the build status
[07:35:55.114] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:55.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:49:0) after 1m0s
[07:36:55.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:49:0)
[07:36:55.177] Timer tick, asking Buildbucket for the build status
[07:36:55.290] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:55.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:50:0) after 1m0s
[07:37:55.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:50:0)
[07:37:55.352] Timer tick, asking Buildbucket for the build status
[07:37:55.525] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:55.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:51:0) after 1m0s
[07:38:55.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:51:0)
[07:38:55.587] Timer tick, asking Buildbucket for the build status
[07:38:55.673] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:55.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:52:0) after 1m0s
[07:39:55.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:52:0)
[07:39:55.718] Timer tick, asking Buildbucket for the build status
[07:39:56.051] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:56.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:53:0) after 1m0s
[07:40:56.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:53:0)
[07:40:56.139] Timer tick, asking Buildbucket for the build status
[07:40:56.237] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:56.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:54:0) after 1m0s
[07:41:56.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:54:0)
[07:41:56.285] Timer tick, asking Buildbucket for the build status
[07:41:56.525] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:56.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:55:0) after 1m0s
[07:42:56.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:55:0)
[07:42:56.597] Timer tick, asking Buildbucket for the build status
[07:42:57.124] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:57.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:56:0) after 1m0s
[07:43:57.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:56:0)
[07:43:57.206] Timer tick, asking Buildbucket for the build status
[07:43:57.292] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:57.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:57:0) after 1m0s
[07:44:57.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:57:0)
[07:44:57.344] Timer tick, asking Buildbucket for the build status
[07:44:57.443] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:57.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:58:0) after 1m0s
[07:45:57.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:58:0)
[07:45:57.446] Timer tick, asking Buildbucket for the build status
[07:45:57.609] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:57.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:59:0) after 1m0s
[07:46:57.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:59:0)
[07:46:57.687] Timer tick, asking Buildbucket for the build status
[07:46:58.055] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:58.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:60:0) after 1m0s
[07:47:58.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:60:0)
[07:47:58.095] Timer tick, asking Buildbucket for the build status
[07:47:58.177] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:58.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:61:0) after 1m0s
[07:48:58.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:61:0)
[07:48:58.228] Timer tick, asking Buildbucket for the build status
[07:48:58.319] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:58.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:62:0) after 1m0s
[07:49:58.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:62:0)
[07:49:58.381] Timer tick, asking Buildbucket for the build status
[07:49:58.482] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:58.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:63:0) after 1m0s
[07:50:58.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:63:0)
[07:50:58.588] Timer tick, asking Buildbucket for the build status
[07:50:58.674] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:58.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:64:0) after 1m0s
[07:51:58.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:64:0)
[07:51:58.732] Timer tick, asking Buildbucket for the build status
[07:51:58.823] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:58.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:65:0) after 1m0s
[07:52:58.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:65:0)
[07:52:58.934] Timer tick, asking Buildbucket for the build status
[07:52:59.007] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:59.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:66:0) after 1m0s
[07:53:59.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:66:0)
[07:53:59.142] Timer tick, asking Buildbucket for the build status
[07:53:59.231] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:59.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:67:0) after 1m0s
[07:54:59.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:67:0)
[07:54:59.400] Timer tick, asking Buildbucket for the build status
[07:54:59.496] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:59.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:68:0) after 1m0s
[07:55:59.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:68:0)
[07:55:59.529] Timer tick, asking Buildbucket for the build status
[07:55:59.618] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:59.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:69:0) after 1m0s
[07:56:59.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:69:0)
[07:56:59.713] Timer tick, asking Buildbucket for the build status
[07:57:00.058] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:00.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:70:0) after 1m0s
[07:58:00.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:70:0)
[07:58:00.282] Timer tick, asking Buildbucket for the build status
[07:58:00.391] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:00.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:71:0) after 1m0s
[07:59:00.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:71:0)
[07:59:00.512] Timer tick, asking Buildbucket for the build status
[07:59:00.807] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:00.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:72:0) after 1m0s
[08:00:00.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:72:0)
[08:00:00.993] Timer tick, asking Buildbucket for the build status
[08:00:01.089] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:01.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:73:0) after 1m0s
[08:01:01.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:73:0)
[08:01:01.091] Timer tick, asking Buildbucket for the build status
[08:01:01.173] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:01.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:74:0) after 1m0s
[08:02:01.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:74:0)
[08:02:01.261] Timer tick, asking Buildbucket for the build status
[08:02:01.340] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:01.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:75:0) after 1m0s
[08:03:01.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:75:0)
[08:03:01.658] Timer tick, asking Buildbucket for the build status
[08:03:01.763] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:01.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:76:0) after 1m0s
[08:04:01.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:76:0)
[08:04:01.784] Timer tick, asking Buildbucket for the build status
[08:04:01.877] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:01.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:77:0) after 1m0s
[08:05:01.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:77:0)
[08:05:01.873] Timer tick, asking Buildbucket for the build status
[08:05:02.006] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:02.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:78:0) after 1m0s
[08:06:02.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:78:0)
[08:06:02.068] Timer tick, asking Buildbucket for the build status
[08:06:02.159] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:02.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:79:0) after 1m0s
[08:07:02.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:79:0)
[08:07:02.180] Timer tick, asking Buildbucket for the build status
[08:07:02.281] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:02.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:80:0) after 1m0s
[08:08:02.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:80:0)
[08:08:02.256] Timer tick, asking Buildbucket for the build status
[08:08:02.505] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:02.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:81:0) after 1m0s
[08:09:02.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:81:0)
[08:09:02.608] Timer tick, asking Buildbucket for the build status
[08:09:02.725] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:02.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:82:0) after 1m0s
[08:10:02.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:82:0)
[08:10:02.729] Timer tick, asking Buildbucket for the build status
[08:10:03.011] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:03.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:83:0) after 1m0s
[08:11:03.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:83:0)
[08:11:03.032] Timer tick, asking Buildbucket for the build status
[08:11:03.637] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:03.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:84:0) after 1m0s
[08:12:03.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:84:0)
[08:12:03.696] Timer tick, asking Buildbucket for the build status
[08:12:03.790] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:03.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:85:0) after 1m0s
[08:13:03.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:85:0)
[08:13:03.810] Timer tick, asking Buildbucket for the build status
[08:13:03.906] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:03.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:86:0) after 1m0s
[08:14:03.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:86:0)
[08:14:03.958] Timer tick, asking Buildbucket for the build status
[08:14:04.053] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:04.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:87:0) after 1m0s
[08:15:04.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:87:0)
[08:15:04.100] Timer tick, asking Buildbucket for the build status
[08:15:04.238] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:04.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:88:0) after 1m0s
[08:16:04.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:88:0)
[08:16:04.276] Timer tick, asking Buildbucket for the build status
[08:16:04.355] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:04.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:89:0) after 1m0s
[08:17:04.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:89:0)
[08:17:04.395] Timer tick, asking Buildbucket for the build status
[08:17:04.496] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:04.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:90:0) after 1m0s
[08:18:04.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:90:0)
[08:18:04.510] Timer tick, asking Buildbucket for the build status
[08:18:04.746] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:04.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:91:0) after 1m0s
[08:19:04.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:91:0)
[08:19:04.763] Timer tick, asking Buildbucket for the build status
[08:19:04.881] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:04.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:92:0) after 1m0s
[08:20:04.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:92:0)
[08:20:04.919] Timer tick, asking Buildbucket for the build status
[08:20:05.328] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:05.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:93:0) after 1m0s
[08:21:05.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:93:0)
[08:21:05.421] Timer tick, asking Buildbucket for the build status
[08:21:05.510] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:05.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:94:0) after 1m0s
[08:22:05.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:94:0)
[08:22:05.583] Timer tick, asking Buildbucket for the build status
[08:22:05.950] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:05.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:95:0) after 1m0s
[08:23:06.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:95:0)
[08:23:06.088] Timer tick, asking Buildbucket for the build status
[08:23:06.204] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:06.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:96:0) after 1m0s
[08:24:06.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:96:0)
[08:24:06.250] Timer tick, asking Buildbucket for the build status
[08:24:06.331] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:06.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:97:0) after 1m0s
[08:25:06.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:97:0)
[08:25:06.387] Timer tick, asking Buildbucket for the build status
[08:25:06.630] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:06.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:98:0) after 1m0s
[08:26:06.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:98:0)
[08:26:06.661] Timer tick, asking Buildbucket for the build status
[08:26:07.046] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:07.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:99:0) after 1m0s
[08:27:07.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:99:0)
[08:27:07.065] Timer tick, asking Buildbucket for the build status
[08:27:07.171] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:07.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:100:0) after 1m0s
[08:28:07.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:100:0)
[08:28:07.194] Timer tick, asking Buildbucket for the build status
[08:28:07.337] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:07.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:101:0) after 1m0s
[08:29:07.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:101:0)
[08:29:07.370] Timer tick, asking Buildbucket for the build status
[08:29:07.649] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:07.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:102:0) after 1m0s
[08:30:07.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:102:0)
[08:30:07.745] Timer tick, asking Buildbucket for the build status
[08:30:08.078] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:08.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:103:0) after 1m0s
[08:31:08.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:103:0)
[08:31:08.115] Timer tick, asking Buildbucket for the build status
[08:31:08.241] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:08.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:104:0) after 1m0s
[08:32:08.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:104:0)
[08:32:08.326] Timer tick, asking Buildbucket for the build status
[08:32:08.489] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:08.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:105:0) after 1m0s
[08:33:08.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:105:0)
[08:33:08.557] Timer tick, asking Buildbucket for the build status
[08:33:08.640] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:08.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:106:0) after 1m0s
[08:34:08.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:106:0)
[08:34:08.690] Timer tick, asking Buildbucket for the build status
[08:34:08.922] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:08.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:107:0) after 1m0s
[08:35:08.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:107:0)
[08:35:08.974] Timer tick, asking Buildbucket for the build status
[08:35:09.083] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:09.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:108:0) after 1m0s
[08:36:09.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:108:0)
[08:36:09.115] Timer tick, asking Buildbucket for the build status
[08:36:09.214] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:09.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:109:0) after 1m0s
[08:37:09.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:109:0)
[08:37:09.246] Timer tick, asking Buildbucket for the build status
[08:37:09.796] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:09.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:110:0) after 1m0s
[08:38:09.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:110:0)
[08:38:09.800] Timer tick, asking Buildbucket for the build status
[08:38:09.964] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:09.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:111:0) after 1m0s
[08:39:09.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:111:0)
[08:39:09.991] Timer tick, asking Buildbucket for the build status
[08:39:10.104] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:10.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:112:0) after 1m0s
[08:40:10.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:112:0)
[08:40:10.127] Timer tick, asking Buildbucket for the build status
[08:40:10.235] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:10.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:113:0) after 1m0s
[08:41:10.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:113:0)
[08:41:10.257] Timer tick, asking Buildbucket for the build status
[08:41:10.607] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:10.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:114:0) after 1m0s
[08:42:10.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:114:0)
[08:42:10.704] Timer tick, asking Buildbucket for the build status
[08:42:10.835] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:10.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:115:0) after 1m0s
[08:43:10.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:115:0)
[08:43:10.896] Timer tick, asking Buildbucket for the build status
[08:43:10.978] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:10.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:116:0) after 1m0s
[08:44:11.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:116:0)
[08:44:11.034] Timer tick, asking Buildbucket for the build status
[08:44:11.252] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:11.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:117:0) after 1m0s
[08:45:11.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:117:0)
[08:45:11.298] Timer tick, asking Buildbucket for the build status
[08:45:11.403] Build 8854978992644708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:11.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020409054539975648:118:0) after 1m0s
[08:45:24.926] Received PubSub notification, asking Buildbucket for the build status
[08:45:25.025] Build 8854978992644708816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:45:25.025] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613637922543586",
"created_by": "project:chromium",
"created_ts": "1613630984605827",
"id": "8854978992644708816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a\", \"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-4-ypnv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17404, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"0fa6c307ac7855a10ff95b661ca827add56e1576\", \"got_dawn_revision\": \"0b17eb8e19c886680b12b73ba28de8d30adccda9\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a\", \"got_revision_cp\": \"refs/heads/master@{#855151}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"b3177eb449cae97d92b18ffafe20dfcfce23683e\", \"got_v8_revision\": \"8a9cb0ecbe0624a03f9533015a0530731497c4de\", \"got_v8_revision_cp\": \"refs/heads/9.0.210@{#1}\", \"got_webrtc_revision\": \"ebc563e927b0ea1db816021e83b20150c116e573\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33287}\", \"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\": \"f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a\", \"swarm_hashes_refs/heads/master(at){#855151}_without_patch\": {\"absl_hardening_tests\": \"478edbf4609457111747dc52eb28ee16555292a4\", \"accessibility_unittests\": \"a227aa3c21a855162215b29f5194899c96048aa3\", \"angle_unittests\": \"b798346499e8d923885233bbfb1defdb85a78a48\", \"app_shell_unittests\": \"47d9453e8e80d3619ea2cd1bc4425b3b05f4a0d6\", \"aura_unittests\": \"a465d148cc362ba82b12ed22961880bf2b6a63fa\", \"base_unittests\": \"624f5461188deda17346179d3658e12102a8ddc1\", \"base_util_unittests\": \"70d438f531b5254de14c4442f505223c2d87f3b7\", \"blink_common_unittests\": \"a67e685e581393da2fd2a6252ab8099cb9b13cfe\", \"blink_fuzzer_unittests\": \"51b0184f6e8f0af994b947b5602fccdf0ec68d73\", \"blink_heap_unittests\": \"f83a2c2501660c10e6e32518ad8f0f45512e7591\", \"blink_platform_unittests\": \"3b71a0bec2ab5612089b133eb731eaa8facc68da\", \"blink_unittests\": \"6e7f1b51573658673c376d989a020899b063f464\", \"boringssl_crypto_tests\": \"f3d50b3ba9c07a805fa28bf25d6993ec86447bc2\", \"boringssl_ssl_tests\": \"902dd0cda3c0be9c9b2f1d5b4ed655cb4c50355c\", \"browser_switcher_bho_unittests\": \"fd7d599fa603f8cac2292c9fffa168a551e2a44c\", \"browser_tests\": \"b1b76e1c1a70e4c579db6f44d416ebb0f00acc2a\", \"capture_unittests\": \"913d5cb64a39a4d073a3f91b969c61a8bb2185a2\", \"cast_unittests\": \"eb46a9659266f0f971f3a40c0354fc019716d577\", \"cc_unittests\": \"a2b407d4fd01184b609fd0ba34ba0c2decfeafbf\", \"chrome_app_unittests\": \"566fb2c7be6e4704d95978ddf49d25ceea53f855\", \"chrome_cleaner_unittests\": \"223514c3d175077092e615bb50e45221eb535dd6\", \"chrome_elf_unittests\": \"aa1676333ff51108161abc327e43ff430711a3ec\", \"chromedriver_unittests\": \"90a1a7c54b641030ffc7c65149de778ff18e8d05\", \"components_browsertests\": \"4155ad929cb6dff786fec78cd430778d0ee27add\", \"components_unittests\": \"bcd36550e67387887e24b967f3ced61571bad757\", \"compositor_unittests\": \"4f2c97d49a2552e74bba0107ad7102f6811ec5a9\", \"content_browsertests\": \"ec17cc4582b45e4b0fcef6e335c4cee643e11d7d\", \"content_unittests\": \"34e158fd49a1046f4d14a47359aa5323a2211d4f\", \"courgette_unittests\": \"0c8126ec8dedd02c8163e8295f4b21796bb784e4\", \"crashpad_tests\": \"57688a8b6b8faffdaa670c6be5f894bbf6d1fdc1\", \"cronet_tests\": \"bce95d05bd40b3c07fc942ed0088a5e480b605e0\", \"cronet_unittests\": \"ece49a6ef4e51905495c36b076770e69c382a4e4\", \"crypto_unittests\": \"c8c65e439a0462d328d997df6f17e7e5006982af\", \"delayloads_unittests\": \"7210cedba706576e8280e0f35b2f1caff3d9b069\", \"device_unittests\": \"b7e621ae82d087307a6af8b0c5e79e45c21baee1\", \"display_unittests\": \"fa6d441f708e231ace1a211f5009379030da29f8\", \"elevation_service_unittests\": \"f0d39ecff260bb255d598e900ce0069066fe6106\", \"events_unittests\": \"a18a641c1cd783e3e83fd07152982be1c4cb3ec5\", \"extensions_browsertests\": \"c912a8d80c6f9939fb31acb0c99248dfa9824f5c\", \"extensions_unittests\": \"5b5d55a102f6cb8f25834ed004effa46556f5a7a\", \"filesystem_service_unittests\": \"1c7869ecb8f9ebf386e32ffa97b4e3af8a949637\", \"gcm_unit_tests\": \"026bf50ba9f23b5caad59d7d4a3cbb6dd99fc6ce\", \"gcp_unittests\": \"7debd3fb1f3b5228e6b13cb4d94a53107cad1964\", \"gfx_unittests\": \"104f3854c125accd25a138507e9cf514034bf7d4\", \"gin_unittests\": \"db9366e227cb0ba41d4247d52b11523aae460481\", \"google_apis_unittests\": \"ccb176a905133e9ee8fac4844fb06edbc44d3003\", \"gpu_unittests\": \"1ad1520de6822d2d646e15145d72546d1f79ba40\", \"gwp_asan_unittests\": \"cecfc341da2b5028566556f6bc166cdd0f8eabd9\", \"headless_browsertests\": \"4da6a37fb5f0547ab2b895249fda61e9ae846680\", \"headless_unittests\": \"9eea9b02d0944137add542a6b75cfcacccc63387\", \"install_static_unittests\": \"7923a03eb0ff11da3b16011f6b6833a464f1d9ce\", \"installer_util_unittests\": \"c6d0251d8137c637122e6fb1820446088fbf887c\", \"interactive_ui_tests\": \"c85c618271cb58459902e61e33c735c4d2854308\", \"ipc_tests\": \"b0eba9f052dc138c7d0c90c1a80678d9b5d185a9\", \"jingle_unittests\": \"4bd8f2ee96cccf616d4bb376817564348cdf4c55\", \"latency_unittests\": \"c8f4c28912a80bed2afe337f58aa9b817c3efb0b\", \"libjingle_xmpp_unittests\": \"27bf946e4684868cb51d264eb26d11871338f1ae\", \"liburlpattern_unittests\": \"32abc34f87cb3c0eee503017ad04b43754e267be\", \"media_blink_unittests\": \"1fdb98c4e20694a5b5c35b97510a3568960cc9fa\", \"media_unittests\": \"fb8f7dbfcaefb2716b0d208a8f18ff24d7b7cd24\", \"message_center_unittests\": \"a9c4b09399a450fd3e79c5da83ec86b91fcc0240\", \"midi_unittests\": \"8713fbc0b217cb0e2333671bcc4c9b6f58ce09b4\", \"mojo_core_unittests\": \"edcec1114063839ca0cd13e57c3028093d73c576\", \"mojo_unittests\": \"0455d089968dc8e09b7bf9c59872e201615797bb\", \"nacl_loader_unittests\": \"7a2ccec26757728da48c99a47a0faaf16d5adbdf\", \"native_theme_unittests\": \"2889b05f1441022d63955ad9f279f39c9146547b\", \"net_unittests\": \"2b324b607d59d3bab6fd94a097bef8a357cca676\", \"notification_helper_unittests\": \"9025e9ebf9cc2f9ae9eba979cc614970310938ad\", \"pdf_unittests\": \"158e960fde1448f03edc9939ea33fd513e2d9ba6\", \"perfetto_unittests\": \"3fb758f2009da5478504505b071d751a81b74648\", \"ppapi_unittests\": \"52ceaf805264d14f999fed164eea2c7f0ea7d845\", \"printing_unittests\": \"a9b9edcb65f847964631b7424aa70c434e4909c1\", \"remoting_unittests\": \"6b7a9232dac71d5dd0601413e2b93e12f35e2fbb\", \"sbox_integration_tests\": \"4dd910192f878f9cf6c99ec58842fbf216939d7b\", \"sbox_unittests\": \"bfb310553bfbd9d1aa4ff48556a0dee104320e41\", \"sbox_validation_tests\": \"2eee9de17de9b1007069f5541e4db654ff82f850\", \"service_manager_unittests\": \"f72f79be5064511e5c4f038956ae5bd60238896b\", \"services_unittests\": \"c8bfa0bf3fa40a5ec2a3951c4ed63838a163cee8\", \"setup_unittests\": \"6aef75e48d0fc9ff7b35b95f537e326605766811\", \"shell_dialogs_unittests\": \"09c994f5917f6d79444ef6f521f515e544225909\", \"skia_unittests\": \"b354079e4d429a7a462f5c1123863130118cf2d4\", \"snapshot_unittests\": \"f1b8af49fb25a10ef03379cb505f4037b486d5e6\", \"sql_unittests\": \"7b88e78d697b2ba8b9da5a3c1c169378385fb371\", \"storage_unittests\": \"628f11328393b0bd6dd13430c8745dd0b6c2e32e\", \"sync_integration_tests\": \"9380a23e0ea052c9c72123b13fa931ff7cddd614\", \"traffic_annotation_auditor_unittests\": \"1571fa10a924e4a63c2203dc1abadc7c08ac0597\", \"ui_base_unittests\": \"cecf0f91ab0fbb143c840694288cd16cf5135a7e\", \"ui_touch_selection_unittests\": \"f94df9a299a874afabc8edce7b6bbc7442f94551\", \"unit_tests\": \"cd94a597552b00f7319c0083cb2c22445db242a8\", \"updater_tests\": \"cc7904eac493873b457d5f21e25cf3717f84cfce\", \"url_unittests\": \"c74303070960cd543ca164e05c131314586df349\", \"views_unittests\": \"74feca49d84b8e48b59643b51b4bd7ab4629dd37\", \"viz_unittests\": \"036cb140fff0cd0ce247d995beb7030f8d60ea50\", \"vr_common_unittests\": \"91c789707cf1e343f8474fe73e2dacde03928fe3\", \"vr_pixeltests\": \"61738da3e3ebd835040f4bcd29d9ecf184f7215f\", \"weblayer_browsertests\": \"0b26526eedf3b408477865b5496eff2a21d3fa4a\", \"weblayer_unittests\": \"e07f41020426e04dd944dde77714d750cf47bf90\", \"wm_unittests\": \"4fb08e1743c69f87700ad6f22462ba2e119a8cef\", \"wtf_unittests\": \"c61d50232a2287c3aa8a36f7708dcba5a9c629c6\", \"zlib_unittests\": \"96e428ac8312f0dbe99c79ef466ef8c4dda34994\", \"zucchini_unittests\": \"f7f630081fa6feddcecc757e96f764b07fe1db9f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_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-4-ypnv\"], \"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\": [\"5700-b36f18b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613631246989559",
"status": "COMPLETED",
"status_changed_ts": "1613637922978164",
"tags": [
"build_address:luci.chromium.ci/win-asan/17404",
"builder:win-asan",
"buildset:commit/git/f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f884d0fbf6f5c7de0bfcd4d7c7bae7b88539f17a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020409054539975648",
"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/8854978992644708816/+/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:51ccb85c4377a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1613637922994717",
"url": "https://ci.chromium.org/b/8854978992644708816",
"utcnow_ts": "1613637925015386"
}
}
[08:45:25.025] Invocation finished in 1h55m41.610533245s with status SUCCEEDED