[19:08:04.328] New invocation is queued and will start shortly
[19:08:05.475] Starting the invocation (attempt 1)
[19:08:05.535] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:08:05.535] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:08:05.535] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9040112740368058336",
"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\":\"68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\",\"title\":\"68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"dad58be43eff09766ed80fe15bee0d39ca5d7559\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dad58be43eff09766ed80fe15bee0d39ca5d7559\",\"title\":\"dad58be43eff09766ed80fe15bee0d39ca5d7559\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/dad58be43eff09766ed80fe15bee0d39ca5d7559\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"abd4a8b185deb6cdab2c437417c7996109697a8b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@abd4a8b185deb6cdab2c437417c7996109697a8b\",\"title\":\"abd4a8b185deb6cdab2c437417c7996109697a8b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/abd4a8b185deb6cdab2c437417c7996109697a8b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"abd4a8b185deb6cdab2c437417c7996109697a8b\"}}",
"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:9040112740368058336",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/abd4a8b185deb6cdab2c437417c7996109697a8b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/abd4a8b185deb6cdab2c437417c7996109697a8b",
"gitiles_ref:refs/heads/master"
]
}
[19:08:06.795] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1594840085674023",
"id": "8874682678282962976",
"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\": \"68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\", \"title\": \"68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dad58be43eff09766ed80fe15bee0d39ca5d7559\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dad58be43eff09766ed80fe15bee0d39ca5d7559\", \"title\": \"dad58be43eff09766ed80fe15bee0d39ca5d7559\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dad58be43eff09766ed80fe15bee0d39ca5d7559\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"abd4a8b185deb6cdab2c437417c7996109697a8b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@abd4a8b185deb6cdab2c437417c7996109697a8b\", \"title\": \"abd4a8b185deb6cdab2c437417c7996109697a8b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/abd4a8b185deb6cdab2c437417c7996109697a8b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"abd4a8b185deb6cdab2c437417c7996109697a8b\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594840086560661",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17281",
"builder:infra-continuous-win10-64",
"buildset:commit/git/abd4a8b185deb6cdab2c437417c7996109697a8b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/abd4a8b185deb6cdab2c437417c7996109697a8b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040112740368058336",
"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/8874682678282962976/+/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": "1594840086560817",
"url": "https://luci-milo-dev.appspot.com/b/8874682678282962976",
"utcnow_ts": "1594840086779382"
}
}
[19:08:06.795] Task URL: https://luci-milo-dev.appspot.com/b/8874682678282962976
[19:08:06.795] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:2:0) after 1m0s
[19:09:06.821] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:2:0)
[19:09:06.821] Timer tick, asking Buildbucket for the build status
[19:09:07.014] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:07.014] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:3:0) after 1m0s
[19:10:07.046] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:3:0)
[19:10:07.046] Timer tick, asking Buildbucket for the build status
[19:10:07.256] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:07.256] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:4:0) after 1m0s
[19:11:07.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:4:0)
[19:11:07.361] Timer tick, asking Buildbucket for the build status
[19:11:07.587] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:07.587] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:5:0) after 1m0s
[19:12:07.659] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:5:0)
[19:12:07.659] Timer tick, asking Buildbucket for the build status
[19:12:07.835] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:07.835] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:6:0) after 1m0s
[19:13:07.877] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:6:0)
[19:13:07.877] Timer tick, asking Buildbucket for the build status
[19:13:08.049] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:08.049] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:7:0) after 1m0s
[19:14:08.126] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:7:0)
[19:14:08.126] Timer tick, asking Buildbucket for the build status
[19:14:08.364] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:08.364] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:8:0) after 1m0s
[19:15:08.475] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:8:0)
[19:15:08.475] Timer tick, asking Buildbucket for the build status
[19:15:08.718] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:08.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:9:0) after 1m0s
[19:16:08.746] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:9:0)
[19:16:08.746] Timer tick, asking Buildbucket for the build status
[19:16:08.906] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:08.906] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:10:0) after 1m0s
[19:17:08.929] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:10:0)
[19:17:08.929] Timer tick, asking Buildbucket for the build status
[19:17:09.355] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:09.355] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:11:0) after 1m0s
[19:18:09.406] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:11:0)
[19:18:09.406] Timer tick, asking Buildbucket for the build status
[19:18:09.561] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:09.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:12:0) after 1m0s
[19:19:09.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:12:0)
[19:19:09.590] Timer tick, asking Buildbucket for the build status
[19:19:09.859] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:09.859] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:13:0) after 1m0s
[19:20:09.969] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:13:0)
[19:20:09.969] Timer tick, asking Buildbucket for the build status
[19:20:10.253] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:10.253] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:14:0) after 1m0s
[19:21:10.288] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:14:0)
[19:21:10.288] Timer tick, asking Buildbucket for the build status
[19:21:10.566] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:10.566] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:15:0) after 1m0s
[19:22:10.670] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:15:0)
[19:22:10.670] Timer tick, asking Buildbucket for the build status
[19:22:10.925] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:10.925] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:16:0) after 1m0s
[19:23:10.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:16:0)
[19:23:10.949] Timer tick, asking Buildbucket for the build status
[19:23:11.191] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:11.191] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:17:0) after 1m0s
[19:24:11.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:17:0)
[19:24:11.289] Timer tick, asking Buildbucket for the build status
[19:24:11.528] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:11.528] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:18:0) after 1m0s
[19:25:11.631] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:18:0)
[19:25:11.631] Timer tick, asking Buildbucket for the build status
[19:25:11.862] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:11.862] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:19:0) after 1m0s
[19:26:11.893] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:19:0)
[19:26:11.893] Timer tick, asking Buildbucket for the build status
[19:26:12.053] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:12.053] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:20:0) after 1m0s
[19:27:12.169] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:20:0)
[19:27:12.169] Timer tick, asking Buildbucket for the build status
[19:27:12.340] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:12.340] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:21:0) after 1m0s
[19:28:12.374] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:21:0)
[19:28:12.374] Timer tick, asking Buildbucket for the build status
[19:28:12.662] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:12.662] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:22:0) after 1m0s
[19:29:12.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:22:0)
[19:29:12.682] Timer tick, asking Buildbucket for the build status
[19:29:12.842] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:12.842] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:23:0) after 1m0s
[19:30:12.872] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:23:0)
[19:30:12.872] Timer tick, asking Buildbucket for the build status
[19:30:13.206] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:13.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:24:0) after 1m0s
[19:31:13.245] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:24:0)
[19:31:13.245] Timer tick, asking Buildbucket for the build status
[19:31:13.451] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:13.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:25:0) after 1m0s
[19:32:13.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:25:0)
[19:32:13.581] Timer tick, asking Buildbucket for the build status
[19:32:13.814] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:13.814] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:26:0) after 1m0s
[19:33:17.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:26:0)
[19:33:17.638] Timer tick, asking Buildbucket for the build status
[19:33:17.865] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:17.865] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:27:0) after 1m0s
[19:34:17.969] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:27:0)
[19:34:17.969] Timer tick, asking Buildbucket for the build status
[19:34:18.515] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:18.515] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:28:0) after 1m0s
[19:35:18.535] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:28:0)
[19:35:18.535] Timer tick, asking Buildbucket for the build status
[19:35:18.669] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:18.669] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:29:0) after 1m0s
[19:36:18.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:29:0)
[19:36:18.705] Timer tick, asking Buildbucket for the build status
[19:36:18.891] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:18.891] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:30:0) after 1m0s
[19:37:18.920] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:30:0)
[19:37:18.920] Timer tick, asking Buildbucket for the build status
[19:37:19.105] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:19.105] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:31:0) after 1m0s
[19:38:19.188] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:31:0)
[19:38:19.188] Timer tick, asking Buildbucket for the build status
[19:38:19.416] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:19.416] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:32:0) after 1m0s
[19:39:19.469] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:32:0)
[19:39:19.469] Timer tick, asking Buildbucket for the build status
[19:39:19.621] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:19.621] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:33:0) after 1m0s
[19:40:19.646] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:33:0)
[19:40:19.646] Timer tick, asking Buildbucket for the build status
[19:40:19.796] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:19.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:34:0) after 1m0s
[19:41:19.835] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:34:0)
[19:41:19.835] Timer tick, asking Buildbucket for the build status
[19:41:19.956] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:19.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:35:0) after 1m0s
[19:42:19.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:35:0)
[19:42:19.982] Timer tick, asking Buildbucket for the build status
[19:42:20.123] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:20.123] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:36:0) after 1m0s
[19:43:20.196] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:36:0)
[19:43:20.196] Timer tick, asking Buildbucket for the build status
[19:43:20.346] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:20.346] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:37:0) after 1m0s
[19:44:20.371] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:37:0)
[19:44:20.371] Timer tick, asking Buildbucket for the build status
[19:44:20.575] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:20.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:38:0) after 1m0s
[19:45:20.613] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:38:0)
[19:45:20.613] Timer tick, asking Buildbucket for the build status
[19:45:20.741] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:20.741] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:39:0) after 1m0s
[19:46:20.766] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:39:0)
[19:46:20.766] Timer tick, asking Buildbucket for the build status
[19:46:20.905] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:20.905] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:40:0) after 1m0s
[19:47:20.969] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:40:0)
[19:47:20.969] Timer tick, asking Buildbucket for the build status
[19:47:21.152] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:21.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:41:0) after 1m0s
[19:48:21.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:41:0)
[19:48:21.183] Timer tick, asking Buildbucket for the build status
[19:48:21.326] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:21.326] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:42:0) after 1m0s
[19:49:21.347] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:42:0)
[19:49:21.347] Timer tick, asking Buildbucket for the build status
[19:49:21.459] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:21.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:43:0) after 1m0s
[19:50:21.538] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:43:0)
[19:50:21.539] Timer tick, asking Buildbucket for the build status
[19:50:21.655] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:21.655] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:44:0) after 1m0s
[19:51:21.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:44:0)
[19:51:21.693] Timer tick, asking Buildbucket for the build status
[19:51:21.809] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:21.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:45:0) after 1m0s
[19:52:21.875] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:45:0)
[19:52:21.875] Timer tick, asking Buildbucket for the build status
[19:52:22.003] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:22.003] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:46:0) after 1m0s
[19:53:22.063] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:46:0)
[19:53:22.063] Timer tick, asking Buildbucket for the build status
[19:53:22.251] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:22.251] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:47:0) after 1m0s
[19:54:22.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:47:0)
[19:54:22.291] Timer tick, asking Buildbucket for the build status
[19:54:22.457] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:22.457] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:48:0) after 1m0s
[19:55:22.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:48:0)
[19:55:22.488] Timer tick, asking Buildbucket for the build status
[19:55:22.612] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:22.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:49:0) after 1m0s
[19:56:22.643] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:49:0)
[19:56:22.643] Timer tick, asking Buildbucket for the build status
[19:56:22.795] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:22.795] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:50:0) after 1m0s
[19:57:22.830] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:50:0)
[19:57:22.830] Timer tick, asking Buildbucket for the build status
[19:57:22.954] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:22.954] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:51:0) after 1m0s
[19:58:22.989] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:51:0)
[19:58:22.989] Timer tick, asking Buildbucket for the build status
[19:58:23.164] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:23.165] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:52:0) after 1m0s
[19:59:23.191] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:52:0)
[19:59:23.191] Timer tick, asking Buildbucket for the build status
[19:59:23.343] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:23.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:53:0) after 1m0s
[20:00:23.374] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:53:0)
[20:00:23.374] Timer tick, asking Buildbucket for the build status
[20:00:23.512] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:23.512] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:54:0) after 1m0s
[20:01:23.540] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:54:0)
[20:01:23.540] Timer tick, asking Buildbucket for the build status
[20:01:23.687] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:23.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:55:0) after 1m0s
[20:02:23.781] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:55:0)
[20:02:23.781] Timer tick, asking Buildbucket for the build status
[20:02:23.952] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:23.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:56:0) after 1m0s
[20:03:23.979] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:56:0)
[20:03:23.979] Timer tick, asking Buildbucket for the build status
[20:03:24.107] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:24.107] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:57:0) after 1m0s
[20:04:24.158] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:57:0)
[20:04:24.158] Timer tick, asking Buildbucket for the build status
[20:04:24.361] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:24.361] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:58:0) after 1m0s
[20:05:24.432] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:58:0)
[20:05:24.432] Timer tick, asking Buildbucket for the build status
[20:05:24.611] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:24.611] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:59:0) after 1m0s
[20:06:24.637] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:59:0)
[20:06:24.637] Timer tick, asking Buildbucket for the build status
[20:06:24.789] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:24.789] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:60:0) after 1m0s
[20:07:24.817] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:60:0)
[20:07:24.817] Timer tick, asking Buildbucket for the build status
[20:07:24.914] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:24.914] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:61:0) after 1m0s
[20:08:24.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:61:0)
[20:08:24.943] Timer tick, asking Buildbucket for the build status
[20:08:25.036] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:25.036] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:62:0) after 1m0s
[20:09:25.068] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:62:0)
[20:09:25.068] Timer tick, asking Buildbucket for the build status
[20:09:25.163] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:25.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:63:0) after 1m0s
[20:10:25.208] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:63:0)
[20:10:25.208] Timer tick, asking Buildbucket for the build status
[20:10:25.302] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:25.302] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:64:0) after 1m0s
[20:11:25.335] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:64:0)
[20:11:25.335] Timer tick, asking Buildbucket for the build status
[20:11:25.460] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:25.460] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:65:0) after 1m0s
[20:12:25.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:65:0)
[20:12:25.604] Timer tick, asking Buildbucket for the build status
[20:12:25.697] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:25.697] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:66:0) after 1m0s
[20:13:25.767] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:66:0)
[20:13:25.767] Timer tick, asking Buildbucket for the build status
[20:13:25.900] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:25.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:67:0) after 1m0s
[20:14:25.963] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:67:0)
[20:14:25.963] Timer tick, asking Buildbucket for the build status
[20:14:26.152] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:26.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:68:0) after 1m0s
[20:15:26.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:68:0)
[20:15:26.231] Timer tick, asking Buildbucket for the build status
[20:15:26.458] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:26.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:69:0) after 1m0s
[20:16:26.513] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:69:0)
[20:16:26.513] Timer tick, asking Buildbucket for the build status
[20:16:26.707] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:26.707] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:70:0) after 1m0s
[20:17:26.744] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:70:0)
[20:17:26.744] Timer tick, asking Buildbucket for the build status
[20:17:26.957] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:26.957] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:71:0) after 1m0s
[20:18:27.068] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:71:0)
[20:18:27.068] Timer tick, asking Buildbucket for the build status
[20:18:27.376] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:27.376] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:72:0) after 1m0s
[20:19:27.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:72:0)
[20:19:27.403] Timer tick, asking Buildbucket for the build status
[20:19:27.578] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:27.578] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:73:0) after 1m0s
[20:20:27.608] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:73:0)
[20:20:27.608] Timer tick, asking Buildbucket for the build status
[20:20:27.835] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:27.835] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:74:0) after 1m0s
[20:21:27.861] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:74:0)
[20:21:27.861] Timer tick, asking Buildbucket for the build status
[20:21:28.277] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:28.277] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:75:0) after 1m0s
[20:22:28.313] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:75:0)
[20:22:28.313] Timer tick, asking Buildbucket for the build status
[20:22:28.583] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:28.583] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:76:0) after 1m0s
[20:23:28.617] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:76:0)
[20:23:28.617] Timer tick, asking Buildbucket for the build status
[20:23:28.764] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:28.764] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:77:0) after 1m0s
[20:24:28.871] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:77:0)
[20:24:28.871] Timer tick, asking Buildbucket for the build status
[20:24:29.154] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:29.154] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:78:0) after 1m0s
[20:25:29.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:78:0)
[20:25:29.265] Timer tick, asking Buildbucket for the build status
[20:25:29.438] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:29.438] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:79:0) after 1m0s
[20:26:29.470] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:79:0)
[20:26:29.470] Timer tick, asking Buildbucket for the build status
[20:26:29.618] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:29.618] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:80:0) after 1m0s
[20:27:29.645] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:80:0)
[20:27:29.645] Timer tick, asking Buildbucket for the build status
[20:27:29.815] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:29.815] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:81:0) after 1m0s
[20:28:29.902] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:81:0)
[20:28:29.902] Timer tick, asking Buildbucket for the build status
[20:28:30.097] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:30.097] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:82:0) after 1m0s
[20:29:30.162] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:82:0)
[20:29:30.162] Timer tick, asking Buildbucket for the build status
[20:29:30.323] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:30.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:83:0) after 1m0s
[20:30:30.351] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:83:0)
[20:30:30.351] Timer tick, asking Buildbucket for the build status
[20:30:30.555] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:30.555] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:84:0) after 1m0s
[20:31:30.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:84:0)
[20:31:30.585] Timer tick, asking Buildbucket for the build status
[20:31:30.778] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:30.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:85:0) after 1m0s
[20:32:30.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:85:0)
[20:32:30.803] Timer tick, asking Buildbucket for the build status
[20:32:31.102] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:31.102] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:86:0) after 1m0s
[20:33:31.129] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:86:0)
[20:33:31.129] Timer tick, asking Buildbucket for the build status
[20:33:31.349] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:31.349] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:87:0) after 1m0s
[20:34:31.476] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:87:0)
[20:34:31.476] Timer tick, asking Buildbucket for the build status
[20:34:31.643] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:31.643] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:88:0) after 1m0s
[20:35:31.671] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:88:0)
[20:35:31.671] Timer tick, asking Buildbucket for the build status
[20:35:31.831] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:31.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:89:0) after 1m0s
[20:36:31.859] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:89:0)
[20:36:31.859] Timer tick, asking Buildbucket for the build status
[20:36:32.136] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:32.136] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:90:0) after 1m0s
[20:37:32.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:90:0)
[20:37:32.179] Timer tick, asking Buildbucket for the build status
[20:37:32.353] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:32.353] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:91:0) after 1m0s
[20:38:32.393] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:91:0)
[20:38:32.393] Timer tick, asking Buildbucket for the build status
[20:38:32.545] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:32.545] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:92:0) after 1m0s
[20:39:32.589] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:92:0)
[20:39:32.589] Timer tick, asking Buildbucket for the build status
[20:39:32.791] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:32.791] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:93:0) after 1m0s
[20:40:32.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:93:0)
[20:40:32.862] Timer tick, asking Buildbucket for the build status
[20:40:33.257] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:33.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:94:0) after 1m0s
[20:41:33.284] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:94:0)
[20:41:33.284] Timer tick, asking Buildbucket for the build status
[20:41:33.478] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:33.478] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:95:0) after 1m0s
[20:42:33.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:95:0)
[20:42:33.508] Timer tick, asking Buildbucket for the build status
[20:42:33.777] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:33.777] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:96:0) after 1m0s
[20:43:33.811] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:96:0)
[20:43:33.811] Timer tick, asking Buildbucket for the build status
[20:43:34.002] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:34.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:97:0) after 1m0s
[20:44:34.091] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:97:0)
[20:44:34.091] Timer tick, asking Buildbucket for the build status
[20:44:34.306] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:34.306] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:98:0) after 1m0s
[20:45:34.334] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:98:0)
[20:45:34.334] Timer tick, asking Buildbucket for the build status
[20:45:34.543] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:34.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:99:0) after 1m0s
[20:46:34.582] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:99:0)
[20:46:34.582] Timer tick, asking Buildbucket for the build status
[20:46:34.823] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:34.823] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:100:0) after 1m0s
[20:47:34.961] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:100:0)
[20:47:34.961] Timer tick, asking Buildbucket for the build status
[20:47:35.231] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:35.231] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:101:0) after 1m0s
[20:48:35.323] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:101:0)
[20:48:35.323] Timer tick, asking Buildbucket for the build status
[20:48:35.499] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:35.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:102:0) after 1m0s
[20:49:35.525] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:102:0)
[20:49:35.525] Timer tick, asking Buildbucket for the build status
[20:49:35.690] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:35.690] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:103:0) after 1m0s
[20:50:35.719] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:103:0)
[20:50:35.719] Timer tick, asking Buildbucket for the build status
[20:50:35.963] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:35.963] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:104:0) after 1m0s
[20:51:36.049] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:104:0)
[20:51:36.049] Timer tick, asking Buildbucket for the build status
[20:51:36.383] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:36.383] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:105:0) after 1m0s
[20:52:36.410] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:105:0)
[20:52:36.410] Timer tick, asking Buildbucket for the build status
[20:52:36.535] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:36.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:106:0) after 1m0s
[20:53:36.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:106:0)
[20:53:36.581] Timer tick, asking Buildbucket for the build status
[20:53:37.145] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:37.145] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:107:0) after 1m0s
[20:54:37.196] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:107:0)
[20:54:37.196] Timer tick, asking Buildbucket for the build status
[20:54:37.344] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:37.344] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:108:0) after 1m0s
[20:55:37.373] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:108:0)
[20:55:37.373] Timer tick, asking Buildbucket for the build status
[20:55:37.562] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:37.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:109:0) after 1m0s
[20:56:37.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:109:0)
[20:56:37.590] Timer tick, asking Buildbucket for the build status
[20:56:37.749] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:37.749] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:110:0) after 1m0s
[20:57:37.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:110:0)
[20:57:37.791] Timer tick, asking Buildbucket for the build status
[20:57:37.952] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:37.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:111:0) after 1m0s
[20:58:37.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:111:0)
[20:58:37.985] Timer tick, asking Buildbucket for the build status
[20:58:38.176] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:38.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:112:0) after 1m0s
[20:59:38.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:112:0)
[20:59:38.200] Timer tick, asking Buildbucket for the build status
[20:59:38.392] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:38.392] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:113:0) after 1m0s
[21:00:38.421] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:113:0)
[21:00:38.421] Timer tick, asking Buildbucket for the build status
[21:00:38.610] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:38.610] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:114:0) after 1m0s
[21:01:38.636] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:114:0)
[21:01:38.636] Timer tick, asking Buildbucket for the build status
[21:01:38.790] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:38.790] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:115:0) after 1m0s
[21:02:38.816] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:115:0)
[21:02:38.816] Timer tick, asking Buildbucket for the build status
[21:02:38.942] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:38.942] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:116:0) after 1m0s
[21:03:38.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:116:0)
[21:03:38.971] Timer tick, asking Buildbucket for the build status
[21:03:39.101] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:39.101] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:117:0) after 1m0s
[21:04:39.128] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:117:0)
[21:04:39.128] Timer tick, asking Buildbucket for the build status
[21:04:39.321] Build 8874682678282962976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:39.321] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:118:0) after 1m0s
[21:05:19.472] Received PubSub notification, asking Buildbucket for the build status
[21:05:19.736] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:39.299] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:118:0)
[21:05:39.299] Timer tick, asking Buildbucket for the build status
[21:05:39.472] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:39.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:120:0) after 1m0s
[21:06:39.559] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:120:0)
[21:06:39.559] Timer tick, asking Buildbucket for the build status
[21:06:39.799] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:39.799] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:121:0) after 1m0s
[21:07:39.868] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:121:0)
[21:07:39.868] Timer tick, asking Buildbucket for the build status
[21:07:40.109] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:40.109] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:122:0) after 1m0s
[21:08:40.140] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:122:0)
[21:08:40.140] Timer tick, asking Buildbucket for the build status
[21:08:40.287] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:40.287] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:123:0) after 1m0s
[21:09:40.323] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:123:0)
[21:09:40.323] Timer tick, asking Buildbucket for the build status
[21:09:40.455] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:40.455] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:124:0) after 1m0s
[21:10:40.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:124:0)
[21:10:40.494] Timer tick, asking Buildbucket for the build status
[21:10:40.648] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:40.648] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:125:0) after 1m0s
[21:11:40.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:125:0)
[21:11:40.678] Timer tick, asking Buildbucket for the build status
[21:11:41.277] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:41.277] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:126:0) after 1m0s
[21:12:41.279] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:126:0)
[21:12:41.279] Timer tick, asking Buildbucket for the build status
[21:12:41.468] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:41.468] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:127:0) after 1m0s
[21:13:41.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:127:0)
[21:13:41.495] Timer tick, asking Buildbucket for the build status
[21:13:41.639] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:41.639] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:128:0) after 1m0s
[21:14:41.763] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:128:0)
[21:14:41.763] Timer tick, asking Buildbucket for the build status
[21:14:41.902] Build 8874682678282962976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:41.902] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040112740368058336:129:0) after 1m0s
[21:15:15.174] Received PubSub notification, asking Buildbucket for the build status
[21:15:15.260] Build 8874682678282962976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:15:15.260] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1594847712206893",
"created_by": "project:infra",
"created_ts": "1594840085674023",
"id": "8874682678282962976",
"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\": \"68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\", \"title\": \"68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/68b57e3782544dee8d31f7ae2ecc7cdcbd582a62\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dad58be43eff09766ed80fe15bee0d39ca5d7559\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dad58be43eff09766ed80fe15bee0d39ca5d7559\", \"title\": \"dad58be43eff09766ed80fe15bee0d39ca5d7559\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dad58be43eff09766ed80fe15bee0d39ca5d7559\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"abd4a8b185deb6cdab2c437417c7996109697a8b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@abd4a8b185deb6cdab2c437417c7996109697a8b\", \"title\": \"abd4a8b185deb6cdab2c437417c7996109697a8b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/abd4a8b185deb6cdab2c437417c7996109697a8b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"abd4a8b185deb6cdab2c437417c7996109697a8b\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"abd4a8b185deb6cdab2c437417c7996109697a8b\", \"got_revision_cp\": \"refs/heads/master@{#33324}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-53cv\"], \"image\": [\"chrome-win10-1703-20071500-cd7f9e157d4\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5417-05dbccc\"], \"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": "1594847116728287",
"status": "COMPLETED",
"status_changed_ts": "1594847712702468",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17281",
"builder:infra-continuous-win10-64",
"buildset:commit/git/abd4a8b185deb6cdab2c437417c7996109697a8b",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/abd4a8b185deb6cdab2c437417c7996109697a8b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040112740368058336",
"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/8874682678282962976/+/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:4d6cb2024fa40f10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1594847712772238",
"url": "https://luci-milo-dev.appspot.com/b/8874682678282962976",
"utcnow_ts": "1594847715252992"
}
}
[21:15:15.260] Invocation finished in 2h7m10.957887954s with status SUCCEEDED