[21:57:48.488] New invocation is queued and will start shortly
[21:57:49.709] Starting the invocation (attempt 1)
[21:57:49.752] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:57:49.752] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:57:49.752] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9051698473190212672",
"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\":\"d0d2a861fe7713c818d98a787904e0e201911c9b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d0d2a861fe7713c818d98a787904e0e201911c9b\",\"title\":\"d0d2a861fe7713c818d98a787904e0e201911c9b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d0d2a861fe7713c818d98a787904e0e201911c9b\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"dd7963dd98253730afb330208ef425a9a540f9c7\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dd7963dd98253730afb330208ef425a9a540f9c7\",\"title\":\"dd7963dd98253730afb330208ef425a9a540f9c7\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/dd7963dd98253730afb330208ef425a9a540f9c7\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"dd7963dd98253730afb330208ef425a9a540f9c7\"}}",
"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:9051698473190212672",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/dd7963dd98253730afb330208ef425a9a540f9c7",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/dd7963dd98253730afb330208ef425a9a540f9c7",
"gitiles_ref:refs/heads/master"
]
}
[21:57:50.611] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1583791069963748",
"id": "8886268410980404448",
"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\": \"d0d2a861fe7713c818d98a787904e0e201911c9b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d0d2a861fe7713c818d98a787904e0e201911c9b\", \"title\": \"d0d2a861fe7713c818d98a787904e0e201911c9b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d0d2a861fe7713c818d98a787904e0e201911c9b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd7963dd98253730afb330208ef425a9a540f9c7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dd7963dd98253730afb330208ef425a9a540f9c7\", \"title\": \"dd7963dd98253730afb330208ef425a9a540f9c7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dd7963dd98253730afb330208ef425a9a540f9c7\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd7963dd98253730afb330208ef425a9a540f9c7\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583791070396502",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/14301",
"builder:infra-continuous-win10-64",
"buildset:commit/git/dd7963dd98253730afb330208ef425a9a540f9c7",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/dd7963dd98253730afb330208ef425a9a540f9c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051698473190212672",
"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/8886268410980404448/+/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": "1583791070396609",
"url": "https://luci-milo-dev.appspot.com/b/8886268410980404448",
"utcnow_ts": "1583791070603508"
}
}
[21:57:50.611] Task URL: https://luci-milo-dev.appspot.com/b/8886268410980404448
[21:57:50.611] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:2:0) after 1m0s
[21:58:51.643] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:2:0)
[21:58:51.643] Timer tick, asking Buildbucket for the build status
[21:58:51.783] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:51.783] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:3:0) after 1m0s
[21:59:51.829] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:3:0)
[21:59:51.829] Timer tick, asking Buildbucket for the build status
[21:59:51.983] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:51.983] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:4:0) after 1m0s
[22:00:52.026] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:4:0)
[22:00:52.026] Timer tick, asking Buildbucket for the build status
[22:00:52.116] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:52.116] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:5:0) after 1m0s
[22:01:52.144] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:5:0)
[22:01:52.144] Timer tick, asking Buildbucket for the build status
[22:01:52.278] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:52.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:6:0) after 1m0s
[22:02:52.386] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:6:0)
[22:02:52.386] Timer tick, asking Buildbucket for the build status
[22:02:52.459] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:52.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:7:0) after 1m0s
[22:03:52.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:7:0)
[22:03:52.638] Timer tick, asking Buildbucket for the build status
[22:03:52.799] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:52.799] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:8:0) after 1m0s
[22:04:52.821] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:8:0)
[22:04:52.821] Timer tick, asking Buildbucket for the build status
[22:04:52.902] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:52.902] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:9:0) after 1m0s
[22:05:53.028] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:9:0)
[22:05:53.028] Timer tick, asking Buildbucket for the build status
[22:05:53.221] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:53.221] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:10:0) after 1m0s
[22:06:53.241] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:10:0)
[22:06:53.241] Timer tick, asking Buildbucket for the build status
[22:06:53.318] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:53.318] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:11:0) after 1m0s
[22:07:53.428] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:11:0)
[22:07:53.428] Timer tick, asking Buildbucket for the build status
[22:07:53.521] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:53.521] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:12:0) after 1m0s
[22:08:53.543] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:12:0)
[22:08:53.543] Timer tick, asking Buildbucket for the build status
[22:08:53.670] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:53.670] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:13:0) after 1m0s
[22:09:53.696] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:13:0)
[22:09:53.696] Timer tick, asking Buildbucket for the build status
[22:09:53.771] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:53.771] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:14:0) after 1m0s
[22:10:53.845] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:14:0)
[22:10:53.845] Timer tick, asking Buildbucket for the build status
[22:10:53.919] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:53.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:15:0) after 1m0s
[22:11:54.012] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:15:0)
[22:11:54.012] Timer tick, asking Buildbucket for the build status
[22:11:54.103] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:54.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:16:0) after 1m0s
[22:12:54.255] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:16:0)
[22:12:54.255] Timer tick, asking Buildbucket for the build status
[22:12:54.406] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:54.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:17:0) after 1m0s
[22:13:54.450] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:17:0)
[22:13:54.450] Timer tick, asking Buildbucket for the build status
[22:13:54.530] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:54.530] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:18:0) after 1m0s
[22:14:54.624] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:18:0)
[22:14:54.624] Timer tick, asking Buildbucket for the build status
[22:14:54.729] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:54.729] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:19:0) after 1m0s
[22:15:54.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:19:0)
[22:15:54.756] Timer tick, asking Buildbucket for the build status
[22:15:54.917] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:54.917] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:20:0) after 1m0s
[22:16:54.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:20:0)
[22:16:54.943] Timer tick, asking Buildbucket for the build status
[22:16:55.018] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:55.018] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:21:0) after 1m0s
[22:17:55.325] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:21:0)
[22:17:55.325] Timer tick, asking Buildbucket for the build status
[22:17:55.501] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:55.501] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:22:0) after 1m0s
[22:18:55.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:22:0)
[22:18:55.648] Timer tick, asking Buildbucket for the build status
[22:18:55.796] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:55.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:23:0) after 1m0s
[22:19:55.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:23:0)
[22:19:55.873] Timer tick, asking Buildbucket for the build status
[22:19:55.960] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:55.960] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:24:0) after 1m0s
[22:20:56.131] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:24:0)
[22:20:56.131] Timer tick, asking Buildbucket for the build status
[22:20:56.294] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:56.294] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:25:0) after 1m0s
[22:21:56.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:25:0)
[22:21:56.361] Timer tick, asking Buildbucket for the build status
[22:21:56.431] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:56.431] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:26:0) after 1m0s
[22:22:56.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:26:0)
[22:22:56.616] Timer tick, asking Buildbucket for the build status
[22:22:56.690] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:56.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:27:0) after 1m0s
[22:23:56.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:27:0)
[22:23:56.791] Timer tick, asking Buildbucket for the build status
[22:23:56.877] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:56.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:28:0) after 1m0s
[22:24:57.134] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:28:0)
[22:24:57.134] Timer tick, asking Buildbucket for the build status
[22:24:57.293] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:57.293] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:29:0) after 1m0s
[22:25:57.345] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:29:0)
[22:25:57.345] Timer tick, asking Buildbucket for the build status
[22:25:57.422] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:57.422] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:30:0) after 1m0s
[22:26:57.524] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:30:0)
[22:26:57.524] Timer tick, asking Buildbucket for the build status
[22:26:57.643] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:57.643] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:31:0) after 1m0s
[22:27:57.737] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:31:0)
[22:27:57.737] Timer tick, asking Buildbucket for the build status
[22:27:57.826] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:57.826] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:32:0) after 1m0s
[22:28:57.869] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:32:0)
[22:28:57.869] Timer tick, asking Buildbucket for the build status
[22:28:58.003] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:58.003] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:33:0) after 1m0s
[22:29:58.029] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:33:0)
[22:29:58.029] Timer tick, asking Buildbucket for the build status
[22:29:58.139] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:58.139] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:34:0) after 1m0s
[22:30:58.228] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:34:0)
[22:30:58.228] Timer tick, asking Buildbucket for the build status
[22:30:58.306] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:58.306] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:35:0) after 1m0s
[22:31:58.446] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:35:0)
[22:31:58.446] Timer tick, asking Buildbucket for the build status
[22:31:58.614] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:58.614] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:36:0) after 1m0s
[22:32:58.652] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:36:0)
[22:32:58.652] Timer tick, asking Buildbucket for the build status
[22:32:58.745] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:58.745] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:37:0) after 1m0s
[22:33:58.984] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:37:0)
[22:33:58.984] Timer tick, asking Buildbucket for the build status
[22:33:59.119] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:59.119] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:38:0) after 1m0s
[22:34:59.228] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:38:0)
[22:34:59.228] Timer tick, asking Buildbucket for the build status
[22:34:59.377] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:59.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:39:0) after 1m0s
[22:35:59.408] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:39:0)
[22:35:59.408] Timer tick, asking Buildbucket for the build status
[22:35:59.498] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:59.498] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:40:0) after 1m0s
[22:36:59.520] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:40:0)
[22:36:59.520] Timer tick, asking Buildbucket for the build status
[22:36:59.593] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:59.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:41:0) after 1m0s
[22:37:59.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:41:0)
[22:37:59.618] Timer tick, asking Buildbucket for the build status
[22:37:59.724] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:59.724] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:42:0) after 1m0s
[22:38:59.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:42:0)
[22:38:59.847] Timer tick, asking Buildbucket for the build status
[22:39:00.081] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:00.081] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:43:0) after 1m0s
[22:40:00.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:43:0)
[22:40:00.113] Timer tick, asking Buildbucket for the build status
[22:40:00.220] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:00.220] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:44:0) after 1m0s
[22:41:00.258] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:44:0)
[22:41:00.258] Timer tick, asking Buildbucket for the build status
[22:41:00.448] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:00.448] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:45:0) after 1m0s
[22:42:00.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:45:0)
[22:42:00.506] Timer tick, asking Buildbucket for the build status
[22:42:00.619] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:00.619] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:46:0) after 1m0s
[22:43:00.652] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:46:0)
[22:43:00.652] Timer tick, asking Buildbucket for the build status
[22:43:00.740] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:00.740] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:47:0) after 1m0s
[22:44:00.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:47:0)
[22:44:00.765] Timer tick, asking Buildbucket for the build status
[22:44:00.853] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:00.853] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:48:0) after 1m0s
[22:45:00.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:48:0)
[22:45:00.912] Timer tick, asking Buildbucket for the build status
[22:45:01.062] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:01.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:49:0) after 1m0s
[22:46:01.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:49:0)
[22:46:01.095] Timer tick, asking Buildbucket for the build status
[22:46:01.356] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:01.356] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:50:0) after 1m0s
[22:47:01.465] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:50:0)
[22:47:01.465] Timer tick, asking Buildbucket for the build status
[22:47:01.821] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:01.821] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:51:0) after 1m0s
[22:48:01.863] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:51:0)
[22:48:01.863] Timer tick, asking Buildbucket for the build status
[22:48:02.171] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:02.171] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:52:0) after 1m0s
[22:49:02.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:52:0)
[22:49:02.198] Timer tick, asking Buildbucket for the build status
[22:49:02.442] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:02.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:53:0) after 1m0s
[22:50:02.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:53:0)
[22:50:02.488] Timer tick, asking Buildbucket for the build status
[22:50:03.032] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:03.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:54:0) after 1m0s
[22:51:03.059] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:54:0)
[22:51:03.059] Timer tick, asking Buildbucket for the build status
[22:51:03.441] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:03.441] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:55:0) after 1m0s
[22:52:03.463] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:55:0)
[22:52:03.463] Timer tick, asking Buildbucket for the build status
[22:52:03.864] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:03.864] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:56:0) after 1m0s
[22:53:03.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:56:0)
[22:53:03.891] Timer tick, asking Buildbucket for the build status
[22:53:04.201] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:04.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:57:0) after 1m0s
[22:54:04.227] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:57:0)
[22:54:04.227] Timer tick, asking Buildbucket for the build status
[22:54:04.305] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:04.305] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:58:0) after 1m0s
[22:55:04.328] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:58:0)
[22:55:04.328] Timer tick, asking Buildbucket for the build status
[22:55:04.480] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:04.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:59:0) after 1m0s
[22:56:04.524] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:59:0)
[22:56:04.524] Timer tick, asking Buildbucket for the build status
[22:56:05.752] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:05.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:60:0) after 1m0s
[22:57:06.715] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:60:0)
[22:57:06.715] Timer tick, asking Buildbucket for the build status
[22:57:06.786] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:06.786] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:61:0) after 1m0s
[22:58:07.254] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:61:0)
[22:58:07.254] Timer tick, asking Buildbucket for the build status
[22:58:07.419] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:07.419] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:62:0) after 1m0s
[22:59:07.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:62:0)
[22:59:07.581] Timer tick, asking Buildbucket for the build status
[22:59:07.676] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:07.676] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:63:0) after 1m0s
[23:00:08.147] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:63:0)
[23:00:08.147] Timer tick, asking Buildbucket for the build status
[23:00:08.259] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:08.259] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:64:0) after 1m0s
[23:01:08.566] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:64:0)
[23:01:08.566] Timer tick, asking Buildbucket for the build status
[23:01:08.696] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:08.697] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:65:0) after 1m0s
[23:02:08.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:65:0)
[23:02:08.958] Timer tick, asking Buildbucket for the build status
[23:02:09.081] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:09.081] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:66:0) after 1m0s
[23:03:09.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:66:0)
[23:03:09.458] Timer tick, asking Buildbucket for the build status
[23:03:09.549] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:09.549] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:67:0) after 1m0s
[23:04:09.577] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:67:0)
[23:04:09.578] Timer tick, asking Buildbucket for the build status
[23:04:09.943] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:09.943] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:68:0) after 1m0s
[23:05:10.056] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:68:0)
[23:05:10.056] Timer tick, asking Buildbucket for the build status
[23:05:10.333] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:10.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:69:0) after 1m0s
[23:06:10.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:69:0)
[23:06:10.362] Timer tick, asking Buildbucket for the build status
[23:06:10.795] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:10.795] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:70:0) after 1m0s
[23:07:10.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:70:0)
[23:07:10.823] Timer tick, asking Buildbucket for the build status
[23:07:11.235] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:11.235] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:71:0) after 1m0s
[23:08:11.330] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:71:0)
[23:08:11.330] Timer tick, asking Buildbucket for the build status
[23:08:11.809] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:11.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:72:0) after 1m0s
[23:09:11.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:72:0)
[23:09:11.973] Timer tick, asking Buildbucket for the build status
[23:09:12.327] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:12.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:73:0) after 1m0s
[23:10:12.360] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:73:0)
[23:10:12.360] Timer tick, asking Buildbucket for the build status
[23:10:12.745] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:12.745] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:74:0) after 1m0s
[23:11:12.828] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:74:0)
[23:11:12.828] Timer tick, asking Buildbucket for the build status
[23:11:13.013] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:13.013] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:75:0) after 1m0s
[23:12:13.132] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:75:0)
[23:12:13.132] Timer tick, asking Buildbucket for the build status
[23:12:13.214] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:13.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:76:0) after 1m0s
[23:13:13.247] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:76:0)
[23:13:13.247] Timer tick, asking Buildbucket for the build status
[23:13:13.338] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:13.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:77:0) after 1m0s
[23:14:13.365] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:77:0)
[23:14:13.365] Timer tick, asking Buildbucket for the build status
[23:14:13.451] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:13.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:78:0) after 1m0s
[23:15:13.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:78:0)
[23:15:13.488] Timer tick, asking Buildbucket for the build status
[23:15:13.607] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:13.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:79:0) after 1m0s
[23:16:13.635] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:79:0)
[23:16:13.635] Timer tick, asking Buildbucket for the build status
[23:16:13.747] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:13.747] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:80:0) after 1m0s
[23:17:13.838] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:80:0)
[23:17:13.838] Timer tick, asking Buildbucket for the build status
[23:17:14.023] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:14.023] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:81:0) after 1m0s
[23:18:14.240] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:81:0)
[23:18:14.240] Timer tick, asking Buildbucket for the build status
[23:18:14.426] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:14.426] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:82:0) after 1m0s
[23:19:14.456] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:82:0)
[23:19:14.456] Timer tick, asking Buildbucket for the build status
[23:19:14.539] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:14.539] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:83:0) after 1m0s
[23:20:14.565] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:83:0)
[23:20:14.565] Timer tick, asking Buildbucket for the build status
[23:20:14.652] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:14.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:84:0) after 1m0s
[23:21:14.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:84:0)
[23:21:14.682] Timer tick, asking Buildbucket for the build status
[23:21:14.763] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:14.763] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:85:0) after 1m0s
[23:22:14.789] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:85:0)
[23:22:14.789] Timer tick, asking Buildbucket for the build status
[23:22:14.870] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:14.870] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:86:0) after 1m0s
[23:23:14.895] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:86:0)
[23:23:14.895] Timer tick, asking Buildbucket for the build status
[23:23:14.985] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:14.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:87:0) after 1m0s
[23:24:15.058] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:87:0)
[23:24:15.058] Timer tick, asking Buildbucket for the build status
[23:24:15.169] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:15.169] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:88:0) after 1m0s
[23:25:15.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:88:0)
[23:25:15.231] Timer tick, asking Buildbucket for the build status
[23:25:15.321] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:15.321] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:89:0) after 1m0s
[23:26:15.430] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:89:0)
[23:26:15.430] Timer tick, asking Buildbucket for the build status
[23:26:15.530] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:15.531] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:90:0) after 1m0s
[23:27:15.576] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:90:0)
[23:27:15.576] Timer tick, asking Buildbucket for the build status
[23:27:15.702] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:15.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:91:0) after 1m0s
[23:28:15.729] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:91:0)
[23:28:15.729] Timer tick, asking Buildbucket for the build status
[23:28:15.923] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:15.923] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:92:0) after 1m0s
[23:29:16.052] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:92:0)
[23:29:16.052] Timer tick, asking Buildbucket for the build status
[23:29:16.202] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:16.202] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:93:0) after 1m0s
[23:30:16.233] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:93:0)
[23:30:16.233] Timer tick, asking Buildbucket for the build status
[23:30:16.390] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:16.390] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:94:0) after 1m0s
[23:31:16.422] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:94:0)
[23:31:16.422] Timer tick, asking Buildbucket for the build status
[23:31:16.526] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:16.526] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:95:0) after 1m0s
[23:32:16.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:95:0)
[23:32:16.556] Timer tick, asking Buildbucket for the build status
[23:32:16.712] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:16.712] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:96:0) after 1m0s
[23:33:16.739] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:96:0)
[23:33:16.739] Timer tick, asking Buildbucket for the build status
[23:33:16.816] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:16.816] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:97:0) after 1m0s
[23:34:16.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:97:0)
[23:34:16.855] Timer tick, asking Buildbucket for the build status
[23:34:16.989] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:16.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:98:0) after 1m0s
[23:35:17.026] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:98:0)
[23:35:17.026] Timer tick, asking Buildbucket for the build status
[23:35:17.131] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:17.131] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:99:0) after 1m0s
[23:36:17.160] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:99:0)
[23:36:17.160] Timer tick, asking Buildbucket for the build status
[23:36:17.297] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:17.297] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:100:0) after 1m0s
[23:37:17.324] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:100:0)
[23:37:17.324] Timer tick, asking Buildbucket for the build status
[23:37:17.417] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:17.417] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:101:0) after 1m0s
[23:38:17.446] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:101:0)
[23:38:17.446] Timer tick, asking Buildbucket for the build status
[23:38:17.565] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:17.565] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:102:0) after 1m0s
[23:39:17.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:102:0)
[23:39:17.602] Timer tick, asking Buildbucket for the build status
[23:39:17.686] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:17.686] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:103:0) after 1m0s
[23:40:17.740] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:103:0)
[23:40:17.740] Timer tick, asking Buildbucket for the build status
[23:40:17.901] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:17.901] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:104:0) after 1m0s
[23:41:17.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:104:0)
[23:41:17.949] Timer tick, asking Buildbucket for the build status
[23:41:18.034] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:18.034] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:105:0) after 1m0s
[23:42:18.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:105:0)
[23:42:18.078] Timer tick, asking Buildbucket for the build status
[23:42:18.225] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:18.225] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:106:0) after 1m0s
[23:43:18.255] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:106:0)
[23:43:18.255] Timer tick, asking Buildbucket for the build status
[23:43:18.347] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:18.347] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:107:0) after 1m0s
[23:44:18.371] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:107:0)
[23:44:18.371] Timer tick, asking Buildbucket for the build status
[23:44:18.514] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:18.514] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:108:0) after 1m0s
[23:45:18.538] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:108:0)
[23:45:18.538] Timer tick, asking Buildbucket for the build status
[23:45:18.647] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:18.647] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:109:0) after 1m0s
[23:46:18.671] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:109:0)
[23:46:18.671] Timer tick, asking Buildbucket for the build status
[23:46:18.767] Build 8886268410980404448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:18.767] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:110:0) after 1m0s
[23:47:18.849] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:110:0)
[23:47:18.849] Timer tick, asking Buildbucket for the build status
[23:47:19.062] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:19.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:111:0) after 1m0s
[23:47:20.131] Received PubSub notification, asking Buildbucket for the build status
[23:47:20.281] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:19.091] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:111:0)
[23:48:19.091] Timer tick, asking Buildbucket for the build status
[23:48:19.276] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:19.276] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:113:0) after 1m0s
[23:49:19.304] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:113:0)
[23:49:19.304] Timer tick, asking Buildbucket for the build status
[23:49:19.438] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:19.438] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:114:0) after 1m0s
[23:50:19.465] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:114:0)
[23:50:19.465] Timer tick, asking Buildbucket for the build status
[23:50:19.632] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:19.632] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:115:0) after 1m0s
[23:51:19.658] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:115:0)
[23:51:19.658] Timer tick, asking Buildbucket for the build status
[23:51:19.770] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:19.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:116:0) after 1m0s
[23:52:19.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:116:0)
[23:52:19.799] Timer tick, asking Buildbucket for the build status
[23:52:19.946] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:19.946] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:117:0) after 1m0s
[23:53:20.231] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:117:0)
[23:53:20.231] Timer tick, asking Buildbucket for the build status
[23:53:20.435] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:20.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:118:0) after 1m0s
[23:54:20.667] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:118:0)
[23:54:20.667] Timer tick, asking Buildbucket for the build status
[23:54:20.783] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:20.783] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:119:0) after 1m0s
[23:55:21.146] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:119:0)
[23:55:21.146] Timer tick, asking Buildbucket for the build status
[23:55:21.329] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:21.329] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:120:0) after 1m0s
[23:56:21.629] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:120:0)
[23:56:21.629] Timer tick, asking Buildbucket for the build status
[23:56:21.827] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:21.827] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:121:0) after 1m0s
[23:57:22.131] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:121:0)
[23:57:22.131] Timer tick, asking Buildbucket for the build status
[23:57:22.358] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:22.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:122:0) after 1m0s
[23:58:22.390] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:122:0)
[23:58:22.390] Timer tick, asking Buildbucket for the build status
[23:58:22.507] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:22.507] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:123:0) after 1m0s
[23:59:22.538] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:123:0)
[23:59:22.539] Timer tick, asking Buildbucket for the build status
[23:59:22.663] Build 8886268410980404448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:22.663] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9051698473190212672:124:0) after 1m0s
[00:00:02.041] Received PubSub notification, asking Buildbucket for the build status
[00:00:02.212] Build 8886268410980404448: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:00:02.212] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1583798400127646",
"created_by": "project:infra",
"created_ts": "1583791069963748",
"id": "8886268410980404448",
"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\": \"d0d2a861fe7713c818d98a787904e0e201911c9b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d0d2a861fe7713c818d98a787904e0e201911c9b\", \"title\": \"d0d2a861fe7713c818d98a787904e0e201911c9b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d0d2a861fe7713c818d98a787904e0e201911c9b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd7963dd98253730afb330208ef425a9a540f9c7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dd7963dd98253730afb330208ef425a9a540f9c7\", \"title\": \"dd7963dd98253730afb330208ef425a9a540f9c7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dd7963dd98253730afb330208ef425a9a540f9c7\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd7963dd98253730afb330208ef425a9a540f9c7\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d0d2a861fe7713c818d98a787904e0e201911c9b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d0d2a861fe7713c818d98a787904e0e201911c9b\", \"title\": \"d0d2a861fe7713c818d98a787904e0e201911c9b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d0d2a861fe7713c818d98a787904e0e201911c9b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd7963dd98253730afb330208ef425a9a540f9c7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dd7963dd98253730afb330208ef425a9a540f9c7\", \"title\": \"dd7963dd98253730afb330208ef425a9a540f9c7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/dd7963dd98253730afb330208ef425a9a540f9c7\"}]}, \"bot_id\": \"dev-chrome-win10-4-lx3o\", \"branch\": \"refs/heads/master\", \"buildername\": \"infra-continuous-win10-64\", \"buildnumber\": 14301, \"got_revision\": \"dd7963dd98253730afb330208ef425a9a540f9c7\", \"got_revision_cp\": \"refs/heads/master@{#29575}\", \"path_config\": \"generic\", \"recipe\": \"infra_continuous\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"dd7963dd98253730afb330208ef425a9a540f9c7\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-chrome-win10-4-lx3o\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"chromium.tests\", \"chromium.tests.template\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4859-e161763\"], \"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": "1583797638431123",
"status": "COMPLETED",
"status_changed_ts": "1583798400502088",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/14301",
"builder:infra-continuous-win10-64",
"buildset:commit/git/dd7963dd98253730afb330208ef425a9a540f9c7",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/dd7963dd98253730afb330208ef425a9a540f9c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051698473190212672",
"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/8886268410980404448/+/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:4ada838a69f9e110",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1583798400522482",
"url": "https://luci-milo-dev.appspot.com/b/8886268410980404448",
"utcnow_ts": "1583798402201502"
}
}
[00:00:02.212] Invocation finished in 2h2m13.740651415s with status SUCCEEDED