[06:21:44.238] New invocation is queued and will start shortly
[06:21:45.395] Starting the invocation (attempt 1)
[06:21:45.451] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:21:45.451] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[06:21:45.451] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019142457769767392",
"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\":\"8f5a25bb2a4499e58e10407704dc9dbb4ed22372\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8f5a25bb2a4499e58e10407704dc9dbb4ed22372\",\"title\":\"8f5a25bb2a4499e58e10407704dc9dbb4ed22372\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/8f5a25bb2a4499e58e10407704dc9dbb4ed22372\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5e85ad1ac58be855e847be8a0bc3ee849c0493ad\",\"title\":\"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}}",
"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:9019142457769767392",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/5e85ad1ac58be855e847be8a0bc3ee849c0493ad",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/5e85ad1ac58be855e847be8a0bc3ee849c0493ad",
"gitiles_ref:refs/heads/master"
]
}
[06:21:46.117] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1614838905561931",
"id": "8853712395720482336",
"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\": \"8f5a25bb2a4499e58e10407704dc9dbb4ed22372\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8f5a25bb2a4499e58e10407704dc9dbb4ed22372\", \"title\": \"8f5a25bb2a4499e58e10407704dc9dbb4ed22372\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8f5a25bb2a4499e58e10407704dc9dbb4ed22372\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5e85ad1ac58be855e847be8a0bc3ee849c0493ad\", \"title\": \"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614838905984511",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18777",
"builder:infra-continuous-win10-64",
"buildset:commit/git/5e85ad1ac58be855e847be8a0bc3ee849c0493ad",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/5e85ad1ac58be855e847be8a0bc3ee849c0493ad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019142457769767392",
"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/8853712395720482336/+/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": "1614838905984990",
"url": "https://luci-milo-dev.appspot.com/b/8853712395720482336",
"utcnow_ts": "1614838906110847"
}
}
[06:21:46.117] Task URL: https://luci-milo-dev.appspot.com/b/8853712395720482336
[06:21:46.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:2:0) after 1m0s
[06:22:46.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:2:0)
[06:22:46.201] Timer tick, asking Buildbucket for the build status
[06:22:46.267] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:46.268] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:3:0) after 1m0s
[06:23:46.285] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:3:0)
[06:23:46.285] Timer tick, asking Buildbucket for the build status
[06:23:46.385] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:46.385] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:4:0) after 1m0s
[06:24:46.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:4:0)
[06:24:46.405] Timer tick, asking Buildbucket for the build status
[06:24:46.481] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:46.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:5:0) after 1m0s
[06:25:46.586] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:5:0)
[06:25:46.586] Timer tick, asking Buildbucket for the build status
[06:25:46.659] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:46.659] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:6:0) after 1m0s
[06:26:46.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:6:0)
[06:26:46.700] Timer tick, asking Buildbucket for the build status
[06:26:46.770] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:46.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:7:0) after 1m0s
[06:27:46.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:7:0)
[06:27:46.803] Timer tick, asking Buildbucket for the build status
[06:27:46.923] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:46.923] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:8:0) after 1m0s
[06:28:46.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:8:0)
[06:28:46.997] Timer tick, asking Buildbucket for the build status
[06:28:47.092] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:47.092] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:9:0) after 1m0s
[06:29:47.115] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:9:0)
[06:29:47.115] Timer tick, asking Buildbucket for the build status
[06:29:47.195] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:29:47.195] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:10:0) after 1m0s
[06:30:47.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:10:0)
[06:30:47.305] Timer tick, asking Buildbucket for the build status
[06:30:47.380] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:47.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:11:0) after 1m0s
[06:31:47.427] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:11:0)
[06:31:47.427] Timer tick, asking Buildbucket for the build status
[06:31:47.499] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:31:47.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:12:0) after 1m0s
[06:32:47.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:12:0)
[06:32:47.603] Timer tick, asking Buildbucket for the build status
[06:32:47.683] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:32:47.683] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:13:0) after 1m0s
[06:33:47.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:13:0)
[06:33:47.786] Timer tick, asking Buildbucket for the build status
[06:33:47.902] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:33:47.902] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:14:0) after 1m0s
[06:34:48.023] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:14:0)
[06:34:48.023] Timer tick, asking Buildbucket for the build status
[06:34:48.109] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:34:48.109] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:15:0) after 1m0s
[06:35:48.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:15:0)
[06:35:48.195] Timer tick, asking Buildbucket for the build status
[06:35:48.291] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:48.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:16:0) after 1m0s
[06:36:48.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:16:0)
[06:36:48.394] Timer tick, asking Buildbucket for the build status
[06:36:48.486] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:48.486] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:17:0) after 1m0s
[06:37:48.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:17:0)
[06:37:48.502] Timer tick, asking Buildbucket for the build status
[06:37:48.557] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:48.557] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:18:0) after 1m0s
[06:38:48.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:18:0)
[06:38:48.572] Timer tick, asking Buildbucket for the build status
[06:38:48.644] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:48.644] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:19:0) after 1m0s
[06:39:48.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:19:0)
[06:39:48.699] Timer tick, asking Buildbucket for the build status
[06:39:48.781] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:48.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:20:0) after 1m0s
[06:40:48.886] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:20:0)
[06:40:48.886] Timer tick, asking Buildbucket for the build status
[06:40:48.954] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:48.954] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:21:0) after 1m0s
[06:41:48.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:21:0)
[06:41:48.996] Timer tick, asking Buildbucket for the build status
[06:41:49.083] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:49.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:22:0) after 1m0s
[06:42:49.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:22:0)
[06:42:49.186] Timer tick, asking Buildbucket for the build status
[06:42:49.250] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:49.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:23:0) after 1m0s
[06:43:49.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:23:0)
[06:43:49.270] Timer tick, asking Buildbucket for the build status
[06:43:49.357] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:49.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:24:0) after 1m0s
[06:44:49.386] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:24:0)
[06:44:49.386] Timer tick, asking Buildbucket for the build status
[06:44:49.461] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:49.461] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:25:0) after 1m0s
[06:45:49.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:25:0)
[06:45:49.496] Timer tick, asking Buildbucket for the build status
[06:45:49.575] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:49.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:26:0) after 1m0s
[06:46:49.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:26:0)
[06:46:49.595] Timer tick, asking Buildbucket for the build status
[06:46:49.682] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:49.682] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:27:0) after 1m0s
[06:47:49.790] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:27:0)
[06:47:49.790] Timer tick, asking Buildbucket for the build status
[06:47:49.857] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:49.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:28:0) after 1m0s
[06:48:49.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:28:0)
[06:48:49.897] Timer tick, asking Buildbucket for the build status
[06:48:49.978] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:48:49.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:29:0) after 1m0s
[06:49:49.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:29:0)
[06:49:49.995] Timer tick, asking Buildbucket for the build status
[06:49:50.095] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:49:50.095] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:30:0) after 1m0s
[06:50:50.119] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:30:0)
[06:50:50.119] Timer tick, asking Buildbucket for the build status
[06:50:50.179] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:50.179] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:31:0) after 1m0s
[06:51:50.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:31:0)
[06:51:50.297] Timer tick, asking Buildbucket for the build status
[06:51:50.366] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:50.366] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:32:0) after 1m0s
[06:52:50.396] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:32:0)
[06:52:50.396] Timer tick, asking Buildbucket for the build status
[06:52:50.473] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:50.473] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:33:0) after 1m0s
[06:53:50.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:33:0)
[06:53:50.494] Timer tick, asking Buildbucket for the build status
[06:53:50.578] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:50.578] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:34:0) after 1m0s
[06:54:50.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:34:0)
[06:54:50.597] Timer tick, asking Buildbucket for the build status
[06:54:50.717] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:54:50.717] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:35:0) after 1m0s
[06:55:50.817] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:35:0)
[06:55:50.817] Timer tick, asking Buildbucket for the build status
[06:55:50.889] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:50.889] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:36:0) after 1m0s
[06:56:50.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:36:0)
[06:56:50.995] Timer tick, asking Buildbucket for the build status
[06:56:51.071] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:51.071] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:37:0) after 1m0s
[06:57:51.097] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:37:0)
[06:57:51.097] Timer tick, asking Buildbucket for the build status
[06:57:51.186] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:51.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:38:0) after 1m0s
[06:58:51.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:38:0)
[06:58:51.289] Timer tick, asking Buildbucket for the build status
[06:58:51.401] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:51.401] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:39:0) after 1m0s
[06:59:51.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:39:0)
[06:59:51.495] Timer tick, asking Buildbucket for the build status
[06:59:51.561] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:51.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:40:0) after 1m0s
[07:00:51.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:40:0)
[07:00:51.588] Timer tick, asking Buildbucket for the build status
[07:00:51.657] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:51.657] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:41:0) after 1m0s
[07:01:51.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:41:0)
[07:01:51.688] Timer tick, asking Buildbucket for the build status
[07:01:51.768] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:51.768] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:42:0) after 1m0s
[07:02:51.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:42:0)
[07:02:51.801] Timer tick, asking Buildbucket for the build status
[07:02:51.882] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:51.882] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:43:0) after 1m0s
[07:03:51.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:43:0)
[07:03:51.904] Timer tick, asking Buildbucket for the build status
[07:03:51.971] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:51.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:44:0) after 1m0s
[07:04:52.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:44:0)
[07:04:52.002] Timer tick, asking Buildbucket for the build status
[07:04:52.117] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:52.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:45:0) after 1m0s
[07:05:52.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:45:0)
[07:05:52.198] Timer tick, asking Buildbucket for the build status
[07:05:52.260] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:52.260] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:46:0) after 1m0s
[07:06:52.288] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:46:0)
[07:06:52.288] Timer tick, asking Buildbucket for the build status
[07:06:52.410] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:52.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:47:0) after 1m0s
[07:07:52.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:47:0)
[07:07:52.502] Timer tick, asking Buildbucket for the build status
[07:07:52.570] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:52.570] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:48:0) after 1m0s
[07:08:52.609] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:48:0)
[07:08:52.609] Timer tick, asking Buildbucket for the build status
[07:08:52.677] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:52.677] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:49:0) after 1m0s
[07:09:52.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:49:0)
[07:09:52.700] Timer tick, asking Buildbucket for the build status
[07:09:52.781] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:52.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:50:0) after 1m0s
[07:10:52.813] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:50:0)
[07:10:52.813] Timer tick, asking Buildbucket for the build status
[07:10:52.891] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:52.891] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:51:0) after 1m0s
[07:11:52.911] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:51:0)
[07:11:52.911] Timer tick, asking Buildbucket for the build status
[07:11:53.015] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:53.015] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:52:0) after 1m0s
[07:12:53.089] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:52:0)
[07:12:53.089] Timer tick, asking Buildbucket for the build status
[07:12:53.177] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:53.177] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:53:0) after 1m0s
[07:13:53.243] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:53:0)
[07:13:53.243] Timer tick, asking Buildbucket for the build status
[07:13:53.406] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:53.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:54:0) after 1m0s
[07:14:53.500] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:54:0)
[07:14:53.500] Timer tick, asking Buildbucket for the build status
[07:14:53.581] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:53.581] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:55:0) after 1m0s
[07:15:53.619] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:55:0)
[07:15:53.619] Timer tick, asking Buildbucket for the build status
[07:15:53.702] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:53.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:56:0) after 1m0s
[07:16:53.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:56:0)
[07:16:53.805] Timer tick, asking Buildbucket for the build status
[07:16:53.883] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:53.883] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:57:0) after 1m0s
[07:17:53.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:57:0)
[07:17:53.910] Timer tick, asking Buildbucket for the build status
[07:17:54.006] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:54.006] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:58:0) after 1m0s
[07:18:54.041] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:58:0)
[07:18:54.041] Timer tick, asking Buildbucket for the build status
[07:18:54.122] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:54.122] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:59:0) after 1m0s
[07:19:54.196] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:59:0)
[07:19:54.196] Timer tick, asking Buildbucket for the build status
[07:19:54.281] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:54.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:60:0) after 1m0s
[07:20:54.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:60:0)
[07:20:54.303] Timer tick, asking Buildbucket for the build status
[07:20:54.379] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:54.379] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:61:0) after 1m0s
[07:21:54.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:61:0)
[07:21:54.498] Timer tick, asking Buildbucket for the build status
[07:21:54.566] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:54.566] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:62:0) after 1m0s
[07:22:54.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:62:0)
[07:22:54.705] Timer tick, asking Buildbucket for the build status
[07:22:54.782] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:54.782] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:63:0) after 1m0s
[07:23:54.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:63:0)
[07:23:54.804] Timer tick, asking Buildbucket for the build status
[07:23:54.898] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:54.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:64:0) after 1m0s
[07:24:55.017] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:64:0)
[07:24:55.017] Timer tick, asking Buildbucket for the build status
[07:24:55.106] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:55.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:65:0) after 1m0s
[07:25:55.188] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:65:0)
[07:25:55.188] Timer tick, asking Buildbucket for the build status
[07:25:55.262] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:55.262] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:66:0) after 1m0s
[07:26:55.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:66:0)
[07:26:55.306] Timer tick, asking Buildbucket for the build status
[07:26:55.381] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:55.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:67:0) after 1m0s
[07:27:55.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:67:0)
[07:27:55.398] Timer tick, asking Buildbucket for the build status
[07:27:55.499] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:55.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:68:0) after 1m0s
[07:28:55.527] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:68:0)
[07:28:55.527] Timer tick, asking Buildbucket for the build status
[07:28:55.627] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:55.627] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:69:0) after 1m0s
[07:29:55.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:69:0)
[07:29:55.698] Timer tick, asking Buildbucket for the build status
[07:29:55.783] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:55.783] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:70:0) after 1m0s
[07:30:55.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:70:0)
[07:30:55.823] Timer tick, asking Buildbucket for the build status
[07:30:55.906] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:55.906] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:71:0) after 1m0s
[07:31:55.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:71:0)
[07:31:55.998] Timer tick, asking Buildbucket for the build status
[07:31:56.079] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:56.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:72:0) after 1m0s
[07:32:56.123] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:72:0)
[07:32:56.123] Timer tick, asking Buildbucket for the build status
[07:32:56.205] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:56.205] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:73:0) after 1m0s
[07:33:56.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:73:0)
[07:33:56.306] Timer tick, asking Buildbucket for the build status
[07:33:56.375] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:56.375] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:74:0) after 1m0s
[07:34:56.428] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:74:0)
[07:34:56.428] Timer tick, asking Buildbucket for the build status
[07:34:56.569] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:56.569] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:75:0) after 1m0s
[07:35:56.586] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:75:0)
[07:35:56.586] Timer tick, asking Buildbucket for the build status
[07:35:56.696] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:56.696] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:76:0) after 1m0s
[07:36:56.815] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:76:0)
[07:36:56.815] Timer tick, asking Buildbucket for the build status
[07:36:56.887] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:36:56.887] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:77:0) after 1m0s
[07:37:57.013] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:77:0)
[07:37:57.013] Timer tick, asking Buildbucket for the build status
[07:37:57.081] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:37:57.081] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:78:0) after 1m0s
[07:38:57.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:78:0)
[07:38:57.099] Timer tick, asking Buildbucket for the build status
[07:38:57.173] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:38:57.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:79:0) after 1m0s
[07:39:57.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:79:0)
[07:39:57.198] Timer tick, asking Buildbucket for the build status
[07:39:57.278] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:39:57.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:80:0) after 1m0s
[07:40:57.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:80:0)
[07:40:57.298] Timer tick, asking Buildbucket for the build status
[07:40:57.361] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:40:57.361] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:81:0) after 1m0s
[07:41:57.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:81:0)
[07:41:57.389] Timer tick, asking Buildbucket for the build status
[07:41:57.451] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:41:57.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:82:0) after 1m0s
[07:42:57.470] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:82:0)
[07:42:57.470] Timer tick, asking Buildbucket for the build status
[07:42:57.539] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:42:57.539] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:83:0) after 1m0s
[07:43:57.564] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:83:0)
[07:43:57.564] Timer tick, asking Buildbucket for the build status
[07:43:57.651] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:43:57.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:84:0) after 1m0s
[07:44:57.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:84:0)
[07:44:57.699] Timer tick, asking Buildbucket for the build status
[07:44:57.776] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:44:57.776] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:85:0) after 1m0s
[07:45:57.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:85:0)
[07:45:57.796] Timer tick, asking Buildbucket for the build status
[07:45:57.877] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:57.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:86:0) after 1m0s
[07:46:57.920] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:86:0)
[07:46:57.920] Timer tick, asking Buildbucket for the build status
[07:46:58.001] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:58.001] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:87:0) after 1m0s
[07:47:58.035] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:87:0)
[07:47:58.035] Timer tick, asking Buildbucket for the build status
[07:47:58.115] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:58.115] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:88:0) after 1m0s
[07:48:58.161] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:88:0)
[07:48:58.161] Timer tick, asking Buildbucket for the build status
[07:48:58.270] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:48:58.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:89:0) after 1m0s
[07:49:58.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:89:0)
[07:49:58.388] Timer tick, asking Buildbucket for the build status
[07:49:58.464] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:49:58.464] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:90:0) after 1m0s
[07:50:58.669] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:90:0)
[07:50:58.669] Timer tick, asking Buildbucket for the build status
[07:50:58.733] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:50:58.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:91:0) after 1m0s
[07:51:58.818] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:91:0)
[07:51:58.818] Timer tick, asking Buildbucket for the build status
[07:51:58.888] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:51:58.888] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:92:0) after 1m0s
[07:52:59.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:92:0)
[07:52:59.112] Timer tick, asking Buildbucket for the build status
[07:52:59.190] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:52:59.190] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:93:0) after 1m0s
[07:53:59.317] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:93:0)
[07:53:59.317] Timer tick, asking Buildbucket for the build status
[07:53:59.428] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:53:59.428] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:94:0) after 1m0s
[07:54:59.503] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:94:0)
[07:54:59.503] Timer tick, asking Buildbucket for the build status
[07:54:59.585] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:54:59.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:95:0) after 1m0s
[07:55:59.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:95:0)
[07:55:59.619] Timer tick, asking Buildbucket for the build status
[07:55:59.699] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:59.699] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:96:0) after 1m0s
[07:56:59.792] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:96:0)
[07:56:59.792] Timer tick, asking Buildbucket for the build status
[07:56:59.906] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:59.906] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:97:0) after 1m0s
[07:57:59.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:97:0)
[07:57:59.955] Timer tick, asking Buildbucket for the build status
[07:58:00.176] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:00.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:98:0) after 1m0s
[07:59:00.202] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:98:0)
[07:59:00.202] Timer tick, asking Buildbucket for the build status
[07:59:00.282] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:00.282] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:99:0) after 1m0s
[08:00:00.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:99:0)
[08:00:00.405] Timer tick, asking Buildbucket for the build status
[08:00:00.509] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:00.509] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:100:0) after 1m0s
[08:01:00.555] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:100:0)
[08:01:00.555] Timer tick, asking Buildbucket for the build status
[08:01:00.635] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:00.635] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:101:0) after 1m0s
[08:02:00.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:101:0)
[08:02:00.664] Timer tick, asking Buildbucket for the build status
[08:02:00.744] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:00.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:102:0) after 1m0s
[08:03:00.830] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:102:0)
[08:03:00.830] Timer tick, asking Buildbucket for the build status
[08:03:00.961] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:00.961] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:103:0) after 1m0s
[08:04:01.039] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:103:0)
[08:04:01.039] Timer tick, asking Buildbucket for the build status
[08:04:01.202] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:01.202] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:104:0) after 1m0s
[08:05:01.241] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:104:0)
[08:05:01.241] Timer tick, asking Buildbucket for the build status
[08:05:01.325] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:01.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:105:0) after 1m0s
[08:06:01.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:105:0)
[08:06:01.358] Timer tick, asking Buildbucket for the build status
[08:06:01.470] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:01.470] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:106:0) after 1m0s
[08:07:01.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:106:0)
[08:07:01.489] Timer tick, asking Buildbucket for the build status
[08:07:01.568] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:01.568] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:107:0) after 1m0s
[08:08:01.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:107:0)
[08:08:01.593] Timer tick, asking Buildbucket for the build status
[08:08:01.770] Build 8853712395720482336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:01.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:108:0) after 1m0s
[08:09:01.053] Received PubSub notification, asking Buildbucket for the build status
[08:09:01.134] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:01.825] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:108:0)
[08:09:01.825] Timer tick, asking Buildbucket for the build status
[08:09:01.920] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:01.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:110:0) after 1m0s
[08:10:02.016] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:110:0)
[08:10:02.016] Timer tick, asking Buildbucket for the build status
[08:10:02.133] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:02.133] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:111:0) after 1m0s
[08:11:02.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:111:0)
[08:11:02.203] Timer tick, asking Buildbucket for the build status
[08:11:02.310] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:02.310] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:112:0) after 1m0s
[08:12:02.365] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:112:0)
[08:12:02.365] Timer tick, asking Buildbucket for the build status
[08:12:02.536] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:02.536] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:113:0) after 1m0s
[08:13:02.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:113:0)
[08:13:02.595] Timer tick, asking Buildbucket for the build status
[08:13:02.711] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:02.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:114:0) after 1m0s
[08:14:02.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:114:0)
[08:14:02.800] Timer tick, asking Buildbucket for the build status
[08:14:02.904] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:02.904] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:115:0) after 1m0s
[08:15:02.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:115:0)
[08:15:02.972] Timer tick, asking Buildbucket for the build status
[08:15:03.147] Build 8853712395720482336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:03.147] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019142457769767392:116:0) after 1m0s
[08:15:07.324] Received PubSub notification, asking Buildbucket for the build status
[08:15:07.423] Build 8853712395720482336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:15:07.423] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1614845705660288",
"created_by": "project:infra",
"created_ts": "1614838905561931",
"id": "8853712395720482336",
"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\": \"8f5a25bb2a4499e58e10407704dc9dbb4ed22372\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8f5a25bb2a4499e58e10407704dc9dbb4ed22372\", \"title\": \"8f5a25bb2a4499e58e10407704dc9dbb4ed22372\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8f5a25bb2a4499e58e10407704dc9dbb4ed22372\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5e85ad1ac58be855e847be8a0bc3ee849c0493ad\", \"title\": \"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"5e85ad1ac58be855e847be8a0bc3ee849c0493ad\", \"got_revision_cp\": \"refs/heads/master@{#39031}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-ly51\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5735-dbf61ac\"], \"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": "1614845339410978",
"status": "COMPLETED",
"status_changed_ts": "1614845339801292",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18777",
"builder:infra-continuous-win10-64",
"buildset:commit/git/5e85ad1ac58be855e847be8a0bc3ee849c0493ad",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/5e85ad1ac58be855e847be8a0bc3ee849c0493ad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019142457769767392",
"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/8853712395720482336/+/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:5214b7c5c2117b10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614845705660862",
"url": "https://luci-milo-dev.appspot.com/b/8853712395720482336",
"utcnow_ts": "1614845707416119"
}
}
[08:15:07.423] Invocation finished in 1h53m23.200432593s with status SUCCEEDED