[02:19:12.188] New invocation is queued and will start shortly
[02:19:13.308] Starting the invocation (attempt 1)
[02:19:13.361] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[02:19:13.363] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[02:19:13.364] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9026133683116483856",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler-dev.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"9b3b228446253aef2a076f5acceb609a4b63dac0\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9b3b228446253aef2a076f5acceb609a4b63dac0\",\"title\":\"9b3b228446253aef2a076f5acceb609a4b63dac0\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/9b3b228446253aef2a076f5acceb609a4b63dac0\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"d86f55356b5134249c13f0baf9d6b76294f9bdb7\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d86f55356b5134249c13f0baf9d6b76294f9bdb7\",\"title\":\"d86f55356b5134249c13f0baf9d6b76294f9bdb7\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d86f55356b5134249c13f0baf9d6b76294f9bdb7\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f055fe4ca165c9ca993672375613c52900f8f3f3\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f055fe4ca165c9ca993672375613c52900f8f3f3\",\"title\":\"f055fe4ca165c9ca993672375613c52900f8f3f3\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/f055fe4ca165c9ca993672375613c52900f8f3f3\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f055fe4ca165c9ca993672375613c52900f8f3f3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:infra-continuous-win10-64",
"scheduler_invocation_id:9026133683116483856",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/f055fe4ca165c9ca993672375613c52900f8f3f3",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f055fe4ca165c9ca993672375613c52900f8f3f3",
"gitiles_ref:refs/heads/master"
]
}
[02:19:14.103] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1608171553463837",
"id": "8860703621113788224",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9b3b228446253aef2a076f5acceb609a4b63dac0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9b3b228446253aef2a076f5acceb609a4b63dac0\", \"title\": \"9b3b228446253aef2a076f5acceb609a4b63dac0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9b3b228446253aef2a076f5acceb609a4b63dac0\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d86f55356b5134249c13f0baf9d6b76294f9bdb7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d86f55356b5134249c13f0baf9d6b76294f9bdb7\", \"title\": \"d86f55356b5134249c13f0baf9d6b76294f9bdb7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d86f55356b5134249c13f0baf9d6b76294f9bdb7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f055fe4ca165c9ca993672375613c52900f8f3f3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f055fe4ca165c9ca993672375613c52900f8f3f3\", \"title\": \"f055fe4ca165c9ca993672375613c52900f8f3f3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f055fe4ca165c9ca993672375613c52900f8f3f3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f055fe4ca165c9ca993672375613c52900f8f3f3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608171553937369",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18177",
"builder:infra-continuous-win10-64",
"buildset:commit/git/f055fe4ca165c9ca993672375613c52900f8f3f3",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f055fe4ca165c9ca993672375613c52900f8f3f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026133683116483856",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8860703621113788224/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1608171553937473",
"url": "https://luci-milo-dev.appspot.com/b/8860703621113788224",
"utcnow_ts": "1608171554094725"
}
}
[02:19:14.103] Task URL: https://luci-milo-dev.appspot.com/b/8860703621113788224
[02:19:14.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:2:0) after 1m0s
[02:20:14.134] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:2:0)
[02:20:14.134] Timer tick, asking Buildbucket for the build status
[02:20:14.216] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:14.216] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:3:0) after 1m0s
[02:21:14.246] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:3:0)
[02:21:14.246] Timer tick, asking Buildbucket for the build status
[02:21:14.338] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:14.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:4:0) after 1m0s
[02:22:14.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:4:0)
[02:22:14.363] Timer tick, asking Buildbucket for the build status
[02:22:14.439] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:14.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:5:0) after 1m0s
[02:23:14.473] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:5:0)
[02:23:14.473] Timer tick, asking Buildbucket for the build status
[02:23:14.547] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:14.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:6:0) after 1m0s
[02:24:14.566] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:6:0)
[02:24:14.566] Timer tick, asking Buildbucket for the build status
[02:24:14.659] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:14.659] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:7:0) after 1m0s
[02:25:14.692] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:7:0)
[02:25:14.692] Timer tick, asking Buildbucket for the build status
[02:25:14.781] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:14.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:8:0) after 1m0s
[02:26:14.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:8:0)
[02:26:14.805] Timer tick, asking Buildbucket for the build status
[02:26:14.890] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:14.890] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:9:0) after 1m0s
[02:27:14.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:9:0)
[02:27:14.908] Timer tick, asking Buildbucket for the build status
[02:27:14.978] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:14.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:10:0) after 1m0s
[02:28:15.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:10:0)
[02:28:15.014] Timer tick, asking Buildbucket for the build status
[02:28:15.104] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:15.104] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:11:0) after 1m0s
[02:29:15.130] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:11:0)
[02:29:15.130] Timer tick, asking Buildbucket for the build status
[02:29:15.224] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:15.224] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:12:0) after 1m0s
[02:30:15.260] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:12:0)
[02:30:15.260] Timer tick, asking Buildbucket for the build status
[02:30:15.355] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:15.355] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:13:0) after 1m0s
[02:31:15.375] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:13:0)
[02:31:15.375] Timer tick, asking Buildbucket for the build status
[02:31:15.460] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:15.460] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:14:0) after 1m0s
[02:32:15.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:14:0)
[02:32:15.484] Timer tick, asking Buildbucket for the build status
[02:32:15.562] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:15.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:15:0) after 1m0s
[02:33:15.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:15:0)
[02:33:15.581] Timer tick, asking Buildbucket for the build status
[02:33:15.672] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:15.672] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:16:0) after 1m0s
[02:34:15.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:16:0)
[02:34:15.705] Timer tick, asking Buildbucket for the build status
[02:34:15.813] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:15.813] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:17:0) after 1m0s
[02:35:15.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:17:0)
[02:35:15.847] Timer tick, asking Buildbucket for the build status
[02:35:15.975] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:15.975] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:18:0) after 1m0s
[02:36:16.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:18:0)
[02:36:16.005] Timer tick, asking Buildbucket for the build status
[02:36:16.120] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:16.120] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:19:0) after 1m0s
[02:37:16.160] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:19:0)
[02:37:16.160] Timer tick, asking Buildbucket for the build status
[02:37:16.253] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:16.253] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:20:0) after 1m0s
[02:38:16.284] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:20:0)
[02:38:16.284] Timer tick, asking Buildbucket for the build status
[02:38:16.367] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:16.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:21:0) after 1m0s
[02:39:16.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:21:0)
[02:39:16.388] Timer tick, asking Buildbucket for the build status
[02:39:16.466] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:16.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:22:0) after 1m0s
[02:40:16.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:22:0)
[02:40:16.497] Timer tick, asking Buildbucket for the build status
[02:40:16.603] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:16.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:23:0) after 1m0s
[02:41:16.623] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:23:0)
[02:41:16.623] Timer tick, asking Buildbucket for the build status
[02:41:16.928] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:16.928] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:24:0) after 1m0s
[02:42:16.950] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:24:0)
[02:42:16.950] Timer tick, asking Buildbucket for the build status
[02:42:17.098] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:17.098] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:25:0) after 1m0s
[02:43:17.127] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:25:0)
[02:43:17.127] Timer tick, asking Buildbucket for the build status
[02:43:17.208] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:17.208] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:26:0) after 1m0s
[02:44:17.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:26:0)
[02:44:17.229] Timer tick, asking Buildbucket for the build status
[02:44:17.311] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:17.311] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:27:0) after 1m0s
[02:45:17.374] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:27:0)
[02:45:17.374] Timer tick, asking Buildbucket for the build status
[02:45:17.463] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:17.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:28:0) after 1m0s
[02:46:17.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:28:0)
[02:46:17.494] Timer tick, asking Buildbucket for the build status
[02:46:17.576] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:17.576] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:29:0) after 1m0s
[02:47:17.601] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:29:0)
[02:47:17.601] Timer tick, asking Buildbucket for the build status
[02:47:17.755] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:17.755] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:30:0) after 1m0s
[02:48:17.785] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:30:0)
[02:48:17.785] Timer tick, asking Buildbucket for the build status
[02:48:17.955] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:17.955] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:31:0) after 1m0s
[02:49:17.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:31:0)
[02:49:17.985] Timer tick, asking Buildbucket for the build status
[02:49:18.064] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:18.064] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:32:0) after 1m0s
[02:50:18.098] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:32:0)
[02:50:18.098] Timer tick, asking Buildbucket for the build status
[02:50:18.178] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:18.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:33:0) after 1m0s
[02:51:18.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:33:0)
[02:51:18.203] Timer tick, asking Buildbucket for the build status
[02:51:18.279] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:18.279] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:34:0) after 1m0s
[02:52:18.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:34:0)
[02:52:18.301] Timer tick, asking Buildbucket for the build status
[02:52:18.393] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:18.393] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:35:0) after 1m0s
[02:53:18.421] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:35:0)
[02:53:18.421] Timer tick, asking Buildbucket for the build status
[02:53:18.515] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:18.515] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:36:0) after 1m0s
[02:54:18.547] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:36:0)
[02:54:18.547] Timer tick, asking Buildbucket for the build status
[02:54:18.650] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:18.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:37:0) after 1m0s
[02:55:18.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:37:0)
[02:55:18.678] Timer tick, asking Buildbucket for the build status
[02:55:18.779] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:18.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:38:0) after 1m0s
[02:56:18.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:38:0)
[02:56:18.807] Timer tick, asking Buildbucket for the build status
[02:56:18.946] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:18.946] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:39:0) after 1m0s
[02:57:18.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:39:0)
[02:57:18.992] Timer tick, asking Buildbucket for the build status
[02:57:19.113] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:19.113] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:40:0) after 1m0s
[02:58:19.135] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:40:0)
[02:58:19.135] Timer tick, asking Buildbucket for the build status
[02:58:19.218] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:19.218] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:41:0) after 1m0s
[02:59:19.250] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:41:0)
[02:59:19.250] Timer tick, asking Buildbucket for the build status
[02:59:19.335] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:19.335] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:42:0) after 1m0s
[03:00:19.373] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:42:0)
[03:00:19.373] Timer tick, asking Buildbucket for the build status
[03:00:19.476] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:19.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:43:0) after 1m0s
[03:01:19.504] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:43:0)
[03:01:19.504] Timer tick, asking Buildbucket for the build status
[03:01:19.647] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:19.647] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:44:0) after 1m0s
[03:02:19.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:44:0)
[03:02:19.685] Timer tick, asking Buildbucket for the build status
[03:02:19.786] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:19.786] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:45:0) after 1m0s
[03:03:19.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:45:0)
[03:03:19.810] Timer tick, asking Buildbucket for the build status
[03:03:19.876] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:19.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:46:0) after 1m0s
[03:04:19.907] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:46:0)
[03:04:19.907] Timer tick, asking Buildbucket for the build status
[03:04:20.012] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:20.012] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:47:0) after 1m0s
[03:05:20.045] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:47:0)
[03:05:20.045] Timer tick, asking Buildbucket for the build status
[03:05:20.160] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:20.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:48:0) after 1m0s
[03:06:20.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:48:0)
[03:06:20.183] Timer tick, asking Buildbucket for the build status
[03:06:20.285] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:20.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:49:0) after 1m0s
[03:07:20.346] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:49:0)
[03:07:20.346] Timer tick, asking Buildbucket for the build status
[03:07:20.421] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:20.421] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:50:0) after 1m0s
[03:08:20.445] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:50:0)
[03:08:20.445] Timer tick, asking Buildbucket for the build status
[03:08:20.524] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:20.524] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:51:0) after 1m0s
[03:09:20.549] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:51:0)
[03:09:20.549] Timer tick, asking Buildbucket for the build status
[03:09:20.629] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:20.629] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:52:0) after 1m0s
[03:10:20.654] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:52:0)
[03:10:20.654] Timer tick, asking Buildbucket for the build status
[03:10:20.732] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:20.732] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:53:0) after 1m0s
[03:11:20.755] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:53:0)
[03:11:20.755] Timer tick, asking Buildbucket for the build status
[03:11:20.842] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:20.842] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:54:0) after 1m0s
[03:12:20.866] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:54:0)
[03:12:20.866] Timer tick, asking Buildbucket for the build status
[03:12:20.959] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:20.959] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:55:0) after 1m0s
[03:13:20.984] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:55:0)
[03:13:20.984] Timer tick, asking Buildbucket for the build status
[03:13:21.067] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:21.067] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:56:0) after 1m0s
[03:14:21.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:56:0)
[03:14:21.092] Timer tick, asking Buildbucket for the build status
[03:14:21.161] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:21.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:57:0) after 1m0s
[03:15:21.187] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:57:0)
[03:15:21.187] Timer tick, asking Buildbucket for the build status
[03:15:21.268] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:21.268] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:58:0) after 1m0s
[03:16:21.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:58:0)
[03:16:21.296] Timer tick, asking Buildbucket for the build status
[03:16:21.382] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:21.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:59:0) after 1m0s
[03:17:21.408] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:59:0)
[03:17:21.408] Timer tick, asking Buildbucket for the build status
[03:17:21.492] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:21.492] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:60:0) after 1m0s
[03:18:21.522] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:60:0)
[03:18:21.522] Timer tick, asking Buildbucket for the build status
[03:18:21.605] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:21.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:61:0) after 1m0s
[03:19:21.646] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:61:0)
[03:19:21.646] Timer tick, asking Buildbucket for the build status
[03:19:21.719] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:21.719] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:62:0) after 1m0s
[03:20:21.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:62:0)
[03:20:21.756] Timer tick, asking Buildbucket for the build status
[03:20:21.846] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:21.846] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:63:0) after 1m0s
[03:21:21.868] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:63:0)
[03:21:21.868] Timer tick, asking Buildbucket for the build status
[03:21:21.943] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:21.943] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:64:0) after 1m0s
[03:22:21.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:64:0)
[03:22:21.965] Timer tick, asking Buildbucket for the build status
[03:22:22.039] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:22.039] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:65:0) after 1m0s
[03:23:22.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:65:0)
[03:23:22.065] Timer tick, asking Buildbucket for the build status
[03:23:22.151] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:22.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:66:0) after 1m0s
[03:24:22.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:66:0)
[03:24:22.183] Timer tick, asking Buildbucket for the build status
[03:24:22.268] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:22.268] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:67:0) after 1m0s
[03:25:22.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:67:0)
[03:25:22.294] Timer tick, asking Buildbucket for the build status
[03:25:22.382] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:22.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:68:0) after 1m0s
[03:26:22.406] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:68:0)
[03:26:22.406] Timer tick, asking Buildbucket for the build status
[03:26:22.487] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:22.487] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:69:0) after 1m0s
[03:27:22.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:69:0)
[03:27:22.506] Timer tick, asking Buildbucket for the build status
[03:27:22.584] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:22.584] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:70:0) after 1m0s
[03:28:22.609] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:70:0)
[03:28:22.609] Timer tick, asking Buildbucket for the build status
[03:28:22.691] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:22.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:71:0) after 1m0s
[03:29:22.719] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:71:0)
[03:29:22.719] Timer tick, asking Buildbucket for the build status
[03:29:22.792] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:22.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:72:0) after 1m0s
[03:30:22.824] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:72:0)
[03:30:22.824] Timer tick, asking Buildbucket for the build status
[03:30:22.938] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:22.938] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:73:0) after 1m0s
[03:31:22.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:73:0)
[03:31:22.971] Timer tick, asking Buildbucket for the build status
[03:31:23.057] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:23.057] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:74:0) after 1m0s
[03:32:23.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:74:0)
[03:32:23.092] Timer tick, asking Buildbucket for the build status
[03:32:23.173] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:23.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:75:0) after 1m0s
[03:33:23.199] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:75:0)
[03:33:23.199] Timer tick, asking Buildbucket for the build status
[03:33:23.276] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:23.276] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:76:0) after 1m0s
[03:34:23.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:76:0)
[03:34:23.309] Timer tick, asking Buildbucket for the build status
[03:34:23.393] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:23.393] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:77:0) after 1m0s
[03:35:23.432] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:77:0)
[03:35:23.432] Timer tick, asking Buildbucket for the build status
[03:35:23.513] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:23.513] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:78:0) after 1m0s
[03:36:23.536] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:78:0)
[03:36:23.536] Timer tick, asking Buildbucket for the build status
[03:36:23.614] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:23.614] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:79:0) after 1m0s
[03:37:23.641] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:79:0)
[03:37:23.641] Timer tick, asking Buildbucket for the build status
[03:37:23.717] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:23.717] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:80:0) after 1m0s
[03:38:23.743] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:80:0)
[03:38:23.743] Timer tick, asking Buildbucket for the build status
[03:38:23.808] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:23.808] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:81:0) after 1m0s
[03:39:23.845] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:81:0)
[03:39:23.845] Timer tick, asking Buildbucket for the build status
[03:39:23.930] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:23.930] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:82:0) after 1m0s
[03:40:23.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:82:0)
[03:40:23.953] Timer tick, asking Buildbucket for the build status
[03:40:24.032] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:24.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:83:0) after 1m0s
[03:41:24.058] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:83:0)
[03:41:24.058] Timer tick, asking Buildbucket for the build status
[03:41:24.400] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:24.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:84:0) after 1m0s
[03:42:24.426] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:84:0)
[03:42:24.426] Timer tick, asking Buildbucket for the build status
[03:42:24.527] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:24.527] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:85:0) after 1m0s
[03:43:24.550] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:85:0)
[03:43:24.550] Timer tick, asking Buildbucket for the build status
[03:43:24.634] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:24.634] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:86:0) after 1m0s
[03:44:24.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:86:0)
[03:44:24.664] Timer tick, asking Buildbucket for the build status
[03:44:24.747] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:24.747] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:87:0) after 1m0s
[03:45:24.771] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:87:0)
[03:45:24.771] Timer tick, asking Buildbucket for the build status
[03:45:24.853] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:24.853] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:88:0) after 1m0s
[03:46:24.888] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:88:0)
[03:46:24.888] Timer tick, asking Buildbucket for the build status
[03:46:24.966] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:24.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:89:0) after 1m0s
[03:47:24.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:89:0)
[03:47:24.993] Timer tick, asking Buildbucket for the build status
[03:47:25.082] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:25.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:90:0) after 1m0s
[03:48:25.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:90:0)
[03:48:25.114] Timer tick, asking Buildbucket for the build status
[03:48:25.194] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:25.194] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:91:0) after 1m0s
[03:49:25.223] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:91:0)
[03:49:25.223] Timer tick, asking Buildbucket for the build status
[03:49:25.322] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:25.322] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:92:0) after 1m0s
[03:50:25.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:92:0)
[03:50:25.355] Timer tick, asking Buildbucket for the build status
[03:50:25.440] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:25.440] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:93:0) after 1m0s
[03:51:25.466] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:93:0)
[03:51:25.466] Timer tick, asking Buildbucket for the build status
[03:51:25.604] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:25.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:94:0) after 1m0s
[03:52:25.636] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:94:0)
[03:52:25.636] Timer tick, asking Buildbucket for the build status
[03:52:25.837] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:25.837] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:95:0) after 1m0s
[03:53:25.860] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:95:0)
[03:53:25.860] Timer tick, asking Buildbucket for the build status
[03:53:25.951] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:25.951] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:96:0) after 1m0s
[03:54:25.979] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:96:0)
[03:54:25.979] Timer tick, asking Buildbucket for the build status
[03:54:26.111] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:26.111] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:97:0) after 1m0s
[03:55:26.143] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:97:0)
[03:55:26.143] Timer tick, asking Buildbucket for the build status
[03:55:26.210] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:26.210] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:98:0) after 1m0s
[03:56:26.237] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:98:0)
[03:56:26.237] Timer tick, asking Buildbucket for the build status
[03:56:26.310] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:26.310] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:99:0) after 1m0s
[03:57:26.342] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:99:0)
[03:57:26.342] Timer tick, asking Buildbucket for the build status
[03:57:26.439] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:26.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:100:0) after 1m0s
[03:58:26.465] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:100:0)
[03:58:26.465] Timer tick, asking Buildbucket for the build status
[03:58:26.552] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:26.552] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:101:0) after 1m0s
[03:59:26.577] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:101:0)
[03:59:26.577] Timer tick, asking Buildbucket for the build status
[03:59:26.647] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:26.647] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:102:0) after 1m0s
[04:00:26.669] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:102:0)
[04:00:26.669] Timer tick, asking Buildbucket for the build status
[04:00:26.828] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:26.828] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:103:0) after 1m0s
[04:01:26.852] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:103:0)
[04:01:26.853] Timer tick, asking Buildbucket for the build status
[04:01:27.037] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:27.037] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:104:0) after 1m0s
[04:02:27.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:104:0)
[04:02:27.079] Timer tick, asking Buildbucket for the build status
[04:02:27.192] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:27.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:105:0) after 1m0s
[04:03:27.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:105:0)
[04:03:27.229] Timer tick, asking Buildbucket for the build status
[04:03:27.304] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:27.304] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:106:0) after 1m0s
[04:04:27.330] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:106:0)
[04:04:27.330] Timer tick, asking Buildbucket for the build status
[04:04:27.400] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:27.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:107:0) after 1m0s
[04:05:27.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:107:0)
[04:05:27.477] Timer tick, asking Buildbucket for the build status
[04:05:27.544] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:27.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:108:0) after 1m0s
[04:06:27.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:108:0)
[04:06:27.572] Timer tick, asking Buildbucket for the build status
[04:06:27.641] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:27.641] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:109:0) after 1m0s
[04:07:27.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:109:0)
[04:07:27.784] Timer tick, asking Buildbucket for the build status
[04:07:27.886] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:27.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:110:0) after 1m0s
[04:08:27.914] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:110:0)
[04:08:27.914] Timer tick, asking Buildbucket for the build status
[04:08:27.991] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:27.991] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:111:0) after 1m0s
[04:09:28.017] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:111:0)
[04:09:28.017] Timer tick, asking Buildbucket for the build status
[04:09:28.106] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:28.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:112:0) after 1m0s
[04:10:28.134] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:112:0)
[04:10:28.134] Timer tick, asking Buildbucket for the build status
[04:10:28.220] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:28.220] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:113:0) after 1m0s
[04:11:28.281] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:113:0)
[04:11:28.281] Timer tick, asking Buildbucket for the build status
[04:11:28.597] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:28.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:114:0) after 1m0s
[04:12:28.633] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:114:0)
[04:12:28.633] Timer tick, asking Buildbucket for the build status
[04:12:28.711] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:28.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:115:0) after 1m0s
[04:13:28.741] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:115:0)
[04:13:28.741] Timer tick, asking Buildbucket for the build status
[04:13:28.831] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:28.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:116:0) after 1m0s
[04:14:28.857] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:116:0)
[04:14:28.857] Timer tick, asking Buildbucket for the build status
[04:14:28.932] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:28.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:117:0) after 1m0s
[04:15:28.961] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:117:0)
[04:15:28.961] Timer tick, asking Buildbucket for the build status
[04:15:29.070] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:29.070] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:118:0) after 1m0s
[04:16:29.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:118:0)
[04:16:29.107] Timer tick, asking Buildbucket for the build status
[04:16:29.208] Build 8860703621113788224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:29.208] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:119:0) after 1m0s
[04:17:02.891] Received PubSub notification, asking Buildbucket for the build status
[04:17:02.973] Build 8860703621113788224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:29.236] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:119:0)
[04:17:29.236] Timer tick, asking Buildbucket for the build status
[04:17:29.312] Build 8860703621113788224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:29.312] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:121:0) after 1m0s
[04:18:29.339] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:121:0)
[04:18:29.339] Timer tick, asking Buildbucket for the build status
[04:18:29.423] Build 8860703621113788224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:29.423] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:122:0) after 1m0s
[04:19:29.452] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:122:0)
[04:19:29.452] Timer tick, asking Buildbucket for the build status
[04:19:29.591] Build 8860703621113788224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:29.591] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:123:0) after 1m0s
[04:20:29.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:123:0)
[04:20:29.621] Timer tick, asking Buildbucket for the build status
[04:20:29.711] Build 8860703621113788224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:29.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:124:0) after 1m0s
[04:21:29.740] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:124:0)
[04:21:29.740] Timer tick, asking Buildbucket for the build status
[04:21:29.821] Build 8860703621113788224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:29.821] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:125:0) after 1m0s
[04:22:29.853] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:125:0)
[04:22:29.853] Timer tick, asking Buildbucket for the build status
[04:22:29.970] Build 8860703621113788224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:29.970] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9026133683116483856:126:0) after 1m0s
[04:23:00.476] Received PubSub notification, asking Buildbucket for the build status
[04:23:00.618] Build 8860703621113788224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:23:00.618] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1608178977657605",
"created_by": "project:infra",
"created_ts": "1608171553463837",
"id": "8860703621113788224",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9b3b228446253aef2a076f5acceb609a4b63dac0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9b3b228446253aef2a076f5acceb609a4b63dac0\", \"title\": \"9b3b228446253aef2a076f5acceb609a4b63dac0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9b3b228446253aef2a076f5acceb609a4b63dac0\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d86f55356b5134249c13f0baf9d6b76294f9bdb7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d86f55356b5134249c13f0baf9d6b76294f9bdb7\", \"title\": \"d86f55356b5134249c13f0baf9d6b76294f9bdb7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d86f55356b5134249c13f0baf9d6b76294f9bdb7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f055fe4ca165c9ca993672375613c52900f8f3f3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f055fe4ca165c9ca993672375613c52900f8f3f3\", \"title\": \"f055fe4ca165c9ca993672375613c52900f8f3f3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f055fe4ca165c9ca993672375613c52900f8f3f3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f055fe4ca165c9ca993672375613c52900f8f3f3\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"f055fe4ca165c9ca993672375613c52900f8f3f3\", \"got_revision_cp\": \"refs/heads/master@{#37147}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-zimb\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1608178616648208",
"status": "COMPLETED",
"status_changed_ts": "1608178977995752",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18177",
"builder:infra-continuous-win10-64",
"buildset:commit/git/f055fe4ca165c9ca993672375613c52900f8f3f3",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f055fe4ca165c9ca993672375613c52900f8f3f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026133683116483856",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8860703621113788224/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:5087500eafe9c110",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1608178978020114",
"url": "https://luci-milo-dev.appspot.com/b/8860703621113788224",
"utcnow_ts": "1608178980611911"
}
}
[04:23:00.618] Invocation finished in 2h3m48.448006082s with status SUCCEEDED