[23:24:06.125] New invocation is queued and will start shortly
[23:24:07.273] Starting the invocation (attempt 1)
[23:24:07.304] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:24:07.304] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:24:07.304] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019712314905598144",
"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\":\"28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\",\"title\":\"28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"202406bee91adb6bb96df07757d6b6e2f321acc7\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@202406bee91adb6bb96df07757d6b6e2f321acc7\",\"title\":\"202406bee91adb6bb96df07757d6b6e2f321acc7\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/202406bee91adb6bb96df07757d6b6e2f321acc7\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0780a7264bbc2a1f410b374ba847391e6b8f3299\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0780a7264bbc2a1f410b374ba847391e6b8f3299\",\"title\":\"0780a7264bbc2a1f410b374ba847391e6b8f3299\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0780a7264bbc2a1f410b374ba847391e6b8f3299\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0780a7264bbc2a1f410b374ba847391e6b8f3299\"}}",
"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:9019712314905598144",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/0780a7264bbc2a1f410b374ba847391e6b8f3299",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0780a7264bbc2a1f410b374ba847391e6b8f3299",
"gitiles_ref:refs/heads/master"
]
}
[23:24:08.164] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1614295447382117",
"id": "8854282252924740976",
"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\": \"28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\", \"title\": \"28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"202406bee91adb6bb96df07757d6b6e2f321acc7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@202406bee91adb6bb96df07757d6b6e2f321acc7\", \"title\": \"202406bee91adb6bb96df07757d6b6e2f321acc7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/202406bee91adb6bb96df07757d6b6e2f321acc7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0780a7264bbc2a1f410b374ba847391e6b8f3299\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0780a7264bbc2a1f410b374ba847391e6b8f3299\", \"title\": \"0780a7264bbc2a1f410b374ba847391e6b8f3299\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0780a7264bbc2a1f410b374ba847391e6b8f3299\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0780a7264bbc2a1f410b374ba847391e6b8f3299\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614295448029349",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18715",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0780a7264bbc2a1f410b374ba847391e6b8f3299",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0780a7264bbc2a1f410b374ba847391e6b8f3299",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019712314905598144",
"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/8854282252924740976/+/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": "1614295448029674",
"url": "https://luci-milo-dev.appspot.com/b/8854282252924740976",
"utcnow_ts": "1614295448157096"
}
}
[23:24:08.164] Task URL: https://luci-milo-dev.appspot.com/b/8854282252924740976
[23:24:08.164] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:2:0) after 1m0s
[23:25:08.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:2:0)
[23:25:08.186] Timer tick, asking Buildbucket for the build status
[23:25:08.280] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:08.280] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:3:0) after 1m0s
[23:26:08.368] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:3:0)
[23:26:08.368] Timer tick, asking Buildbucket for the build status
[23:26:08.461] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:08.461] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:4:0) after 1m0s
[23:27:08.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:4:0)
[23:27:08.479] Timer tick, asking Buildbucket for the build status
[23:27:08.590] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:08.590] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:5:0) after 1m0s
[23:28:08.659] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:5:0)
[23:28:08.659] Timer tick, asking Buildbucket for the build status
[23:28:08.742] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:08.742] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:6:0) after 1m0s
[23:29:08.769] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:6:0)
[23:29:08.769] Timer tick, asking Buildbucket for the build status
[23:29:08.880] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:08.880] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:7:0) after 1m0s
[23:30:08.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:7:0)
[23:30:08.966] Timer tick, asking Buildbucket for the build status
[23:30:09.046] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:09.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:8:0) after 1m0s
[23:31:09.069] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:8:0)
[23:31:09.069] Timer tick, asking Buildbucket for the build status
[23:31:09.176] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:09.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:9:0) after 1m0s
[23:32:09.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:9:0)
[23:32:09.195] Timer tick, asking Buildbucket for the build status
[23:32:09.342] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:09.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:10:0) after 1m0s
[23:33:09.366] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:10:0)
[23:33:09.366] Timer tick, asking Buildbucket for the build status
[23:33:09.483] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:09.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:11:0) after 1m0s
[23:34:09.566] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:11:0)
[23:34:09.566] Timer tick, asking Buildbucket for the build status
[23:34:09.638] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:09.638] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:12:0) after 1m0s
[23:35:09.655] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:12:0)
[23:35:09.655] Timer tick, asking Buildbucket for the build status
[23:35:09.777] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:09.777] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:13:0) after 1m0s
[23:36:09.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:13:0)
[23:36:09.798] Timer tick, asking Buildbucket for the build status
[23:36:09.874] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:09.874] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:14:0) after 1m0s
[23:37:09.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:14:0)
[23:37:09.980] Timer tick, asking Buildbucket for the build status
[23:37:10.074] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:10.074] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:15:0) after 1m0s
[23:38:10.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:15:0)
[23:38:10.100] Timer tick, asking Buildbucket for the build status
[23:38:10.240] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:10.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:16:0) after 1m0s
[23:39:10.277] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:16:0)
[23:39:10.277] Timer tick, asking Buildbucket for the build status
[23:39:10.360] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:10.360] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:17:0) after 1m0s
[23:40:10.460] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:17:0)
[23:40:10.460] Timer tick, asking Buildbucket for the build status
[23:40:10.544] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:10.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:18:0) after 1m0s
[23:41:10.561] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:18:0)
[23:41:10.561] Timer tick, asking Buildbucket for the build status
[23:41:10.654] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:10.654] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:19:0) after 1m0s
[23:42:10.770] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:19:0)
[23:42:10.770] Timer tick, asking Buildbucket for the build status
[23:42:10.851] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:10.851] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:20:0) after 1m0s
[23:43:10.868] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:20:0)
[23:43:10.868] Timer tick, asking Buildbucket for the build status
[23:43:10.983] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:10.983] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:21:0) after 1m0s
[23:44:11.009] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:21:0)
[23:44:11.009] Timer tick, asking Buildbucket for the build status
[23:44:11.086] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:11.086] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:22:0) after 1m0s
[23:45:11.157] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:22:0)
[23:45:11.157] Timer tick, asking Buildbucket for the build status
[23:45:11.249] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:11.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:23:0) after 1m0s
[23:46:11.271] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:23:0)
[23:46:11.271] Timer tick, asking Buildbucket for the build status
[23:46:11.353] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:11.353] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:24:0) after 1m0s
[23:47:11.383] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:24:0)
[23:47:11.383] Timer tick, asking Buildbucket for the build status
[23:47:11.480] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:11.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:25:0) after 1m0s
[23:48:11.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:25:0)
[23:48:11.581] Timer tick, asking Buildbucket for the build status
[23:48:11.702] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:11.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:26:0) after 1m0s
[23:49:11.757] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:26:0)
[23:49:11.757] Timer tick, asking Buildbucket for the build status
[23:49:11.877] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:11.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:27:0) after 1m0s
[23:50:11.978] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:27:0)
[23:50:11.978] Timer tick, asking Buildbucket for the build status
[23:50:12.094] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:12.094] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:28:0) after 1m0s
[23:51:12.173] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:28:0)
[23:51:12.173] Timer tick, asking Buildbucket for the build status
[23:51:12.279] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:12.280] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:29:0) after 1m0s
[23:52:12.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:29:0)
[23:52:12.305] Timer tick, asking Buildbucket for the build status
[23:52:12.399] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:12.399] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:30:0) after 1m0s
[23:53:12.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:30:0)
[23:53:12.477] Timer tick, asking Buildbucket for the build status
[23:53:12.546] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:12.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:31:0) after 1m0s
[23:54:12.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:31:0)
[23:54:12.567] Timer tick, asking Buildbucket for the build status
[23:54:12.655] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:12.655] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:32:0) after 1m0s
[23:55:12.762] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:32:0)
[23:55:12.762] Timer tick, asking Buildbucket for the build status
[23:55:12.854] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:12.854] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:33:0) after 1m0s
[23:56:12.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:33:0)
[23:56:12.958] Timer tick, asking Buildbucket for the build status
[23:56:13.046] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:13.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:34:0) after 1m0s
[23:57:13.108] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:34:0)
[23:57:13.108] Timer tick, asking Buildbucket for the build status
[23:57:13.244] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:13.244] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:35:0) after 1m0s
[23:58:13.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:35:0)
[23:58:13.261] Timer tick, asking Buildbucket for the build status
[23:58:13.346] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:13.346] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:36:0) after 1m0s
[23:59:13.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:36:0)
[23:59:13.367] Timer tick, asking Buildbucket for the build status
[23:59:13.449] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:13.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:37:0) after 1m0s
[00:00:13.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:37:0)
[00:00:13.471] Timer tick, asking Buildbucket for the build status
[00:00:13.595] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:13.595] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:38:0) after 1m0s
[00:01:13.672] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:38:0)
[00:01:13.672] Timer tick, asking Buildbucket for the build status
[00:01:13.775] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:13.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:39:0) after 1m0s
[00:02:13.868] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:39:0)
[00:02:13.868] Timer tick, asking Buildbucket for the build status
[00:02:13.976] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:13.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:40:0) after 1m0s
[00:03:14.066] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:40:0)
[00:03:14.066] Timer tick, asking Buildbucket for the build status
[00:03:14.167] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:14.167] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:41:0) after 1m0s
[00:04:14.264] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:41:0)
[00:04:14.264] Timer tick, asking Buildbucket for the build status
[00:04:14.362] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:14.362] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:42:0) after 1m0s
[00:05:14.473] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:42:0)
[00:05:14.473] Timer tick, asking Buildbucket for the build status
[00:05:14.552] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:14.552] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:43:0) after 1m0s
[00:06:14.674] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:43:0)
[00:06:14.674] Timer tick, asking Buildbucket for the build status
[00:06:14.765] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:14.765] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:44:0) after 1m0s
[00:07:14.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:44:0)
[00:07:14.874] Timer tick, asking Buildbucket for the build status
[00:07:14.971] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:14.972] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:45:0) after 1m0s
[00:08:14.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:45:0)
[00:08:14.991] Timer tick, asking Buildbucket for the build status
[00:08:15.075] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:15.075] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:46:0) after 1m0s
[00:09:15.167] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:46:0)
[00:09:15.167] Timer tick, asking Buildbucket for the build status
[00:09:15.249] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:15.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:47:0) after 1m0s
[00:10:15.283] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:47:0)
[00:10:15.283] Timer tick, asking Buildbucket for the build status
[00:10:15.367] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:15.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:48:0) after 1m0s
[00:11:15.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:48:0)
[00:11:15.461] Timer tick, asking Buildbucket for the build status
[00:11:16.972] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:16.972] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:49:0) after 1m0s
[00:12:17.000] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:49:0)
[00:12:17.000] Timer tick, asking Buildbucket for the build status
[00:12:17.090] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:17.090] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:50:0) after 1m0s
[00:13:17.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:50:0)
[00:13:17.113] Timer tick, asking Buildbucket for the build status
[00:13:17.212] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:17.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:51:0) after 1m0s
[00:14:17.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:51:0)
[00:14:17.198] Timer tick, asking Buildbucket for the build status
[00:14:17.299] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:17.299] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:52:0) after 1m0s
[00:15:17.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:52:0)
[00:15:17.392] Timer tick, asking Buildbucket for the build status
[00:15:17.484] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:17.484] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:53:0) after 1m0s
[00:16:17.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:53:0)
[00:16:17.507] Timer tick, asking Buildbucket for the build status
[00:16:17.593] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:17.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:54:0) after 1m0s
[00:17:17.696] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:54:0)
[00:17:17.696] Timer tick, asking Buildbucket for the build status
[00:17:17.775] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:17.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:55:0) after 1m0s
[00:18:17.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:55:0)
[00:18:17.801] Timer tick, asking Buildbucket for the build status
[00:18:17.881] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:17.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:56:0) after 1m0s
[00:19:17.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:56:0)
[00:19:17.900] Timer tick, asking Buildbucket for the build status
[00:19:18.014] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:18.014] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:57:0) after 1m0s
[00:20:18.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:57:0)
[00:20:18.112] Timer tick, asking Buildbucket for the build status
[00:20:18.201] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:18.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:58:0) after 1m0s
[00:21:18.223] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:58:0)
[00:21:18.223] Timer tick, asking Buildbucket for the build status
[00:21:18.298] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:18.298] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:59:0) after 1m0s
[00:22:18.322] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:59:0)
[00:22:18.322] Timer tick, asking Buildbucket for the build status
[00:22:18.409] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:18.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:60:0) after 1m0s
[00:23:18.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:60:0)
[00:23:18.493] Timer tick, asking Buildbucket for the build status
[00:23:18.711] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:18.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:61:0) after 1m0s
[00:24:18.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:61:0)
[00:24:18.793] Timer tick, asking Buildbucket for the build status
[00:24:18.883] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:18.883] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:62:0) after 1m0s
[00:25:18.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:62:0)
[00:25:18.901] Timer tick, asking Buildbucket for the build status
[00:25:18.982] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:18.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:63:0) after 1m0s
[00:26:19.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:63:0)
[00:26:19.004] Timer tick, asking Buildbucket for the build status
[00:26:19.078] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:19.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:64:0) after 1m0s
[00:27:19.096] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:64:0)
[00:27:19.096] Timer tick, asking Buildbucket for the build status
[00:27:19.182] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:19.182] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:65:0) after 1m0s
[00:28:19.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:65:0)
[00:28:19.209] Timer tick, asking Buildbucket for the build status
[00:28:19.333] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:19.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:66:0) after 1m0s
[00:29:19.351] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:66:0)
[00:29:19.351] Timer tick, asking Buildbucket for the build status
[00:29:19.444] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:19.444] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:67:0) after 1m0s
[00:30:19.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:67:0)
[00:30:19.497] Timer tick, asking Buildbucket for the build status
[00:30:19.624] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:19.624] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:68:0) after 1m0s
[00:31:19.645] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:68:0)
[00:31:19.645] Timer tick, asking Buildbucket for the build status
[00:31:19.767] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:19.767] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:69:0) after 1m0s
[00:32:19.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:69:0)
[00:32:19.786] Timer tick, asking Buildbucket for the build status
[00:32:19.881] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:19.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:70:0) after 1m0s
[00:33:19.902] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:70:0)
[00:33:19.902] Timer tick, asking Buildbucket for the build status
[00:33:19.984] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:19.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:71:0) after 1m0s
[00:34:20.015] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:71:0)
[00:34:20.015] Timer tick, asking Buildbucket for the build status
[00:34:20.099] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:20.099] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:72:0) after 1m0s
[00:35:20.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:72:0)
[00:35:20.117] Timer tick, asking Buildbucket for the build status
[00:35:20.189] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:20.189] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:73:0) after 1m0s
[00:36:20.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:73:0)
[00:36:20.293] Timer tick, asking Buildbucket for the build status
[00:36:20.367] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:20.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:74:0) after 1m0s
[00:37:20.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:74:0)
[00:37:20.388] Timer tick, asking Buildbucket for the build status
[00:37:20.481] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:20.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:75:0) after 1m0s
[00:38:20.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:75:0)
[00:38:20.503] Timer tick, asking Buildbucket for the build status
[00:38:20.586] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:20.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:76:0) after 1m0s
[00:39:20.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:76:0)
[00:39:20.705] Timer tick, asking Buildbucket for the build status
[00:39:20.785] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:20.785] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:77:0) after 1m0s
[00:40:20.808] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:77:0)
[00:40:20.808] Timer tick, asking Buildbucket for the build status
[00:40:20.895] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:20.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:78:0) after 1m0s
[00:41:21.001] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:78:0)
[00:41:21.001] Timer tick, asking Buildbucket for the build status
[00:41:21.091] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:21.091] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:79:0) after 1m0s
[00:42:21.249] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:79:0)
[00:42:21.249] Timer tick, asking Buildbucket for the build status
[00:42:21.331] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:21.331] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:80:0) after 1m0s
[00:43:21.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:80:0)
[00:43:21.415] Timer tick, asking Buildbucket for the build status
[00:43:21.497] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:21.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:81:0) after 1m0s
[00:44:21.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:81:0)
[00:44:21.519] Timer tick, asking Buildbucket for the build status
[00:44:21.593] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:21.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:82:0) after 1m0s
[00:45:21.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:82:0)
[00:45:21.621] Timer tick, asking Buildbucket for the build status
[00:45:21.695] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:21.695] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:83:0) after 1m0s
[00:46:21.795] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:83:0)
[00:46:21.795] Timer tick, asking Buildbucket for the build status
[00:46:21.874] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:21.874] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:84:0) after 1m0s
[00:47:21.909] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:84:0)
[00:47:21.909] Timer tick, asking Buildbucket for the build status
[00:47:21.989] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:21.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:85:0) after 1m0s
[00:48:22.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:85:0)
[00:48:22.106] Timer tick, asking Buildbucket for the build status
[00:48:22.193] Build 8854282252924740976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:22.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:86:0) after 1m0s
[00:48:45.387] Received PubSub notification, asking Buildbucket for the build status
[00:48:45.484] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:22.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:86:0)
[00:49:22.302] Timer tick, asking Buildbucket for the build status
[00:49:22.471] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:22.471] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:88:0) after 1m0s
[00:50:22.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:88:0)
[00:50:22.489] Timer tick, asking Buildbucket for the build status
[00:50:22.581] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:22.581] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:89:0) after 1m0s
[00:51:22.598] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:89:0)
[00:51:22.598] Timer tick, asking Buildbucket for the build status
[00:51:22.695] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:22.695] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:90:0) after 1m0s
[00:52:22.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:90:0)
[00:52:22.798] Timer tick, asking Buildbucket for the build status
[00:52:22.889] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:22.889] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:91:0) after 1m0s
[00:53:23.001] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:91:0)
[00:53:23.001] Timer tick, asking Buildbucket for the build status
[00:53:23.178] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:23.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:92:0) after 1m0s
[00:54:23.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:92:0)
[00:54:23.200] Timer tick, asking Buildbucket for the build status
[00:54:23.286] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:23.286] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:93:0) after 1m0s
[00:55:23.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:93:0)
[00:55:23.311] Timer tick, asking Buildbucket for the build status
[00:55:23.401] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:23.401] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:94:0) after 1m0s
[00:56:23.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:94:0)
[00:56:23.501] Timer tick, asking Buildbucket for the build status
[00:56:23.585] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:23.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:95:0) after 1m0s
[00:57:23.605] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:95:0)
[00:57:23.605] Timer tick, asking Buildbucket for the build status
[00:57:23.716] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:23.716] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:96:0) after 1m0s
[00:58:23.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:96:0)
[00:58:23.800] Timer tick, asking Buildbucket for the build status
[00:58:23.895] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:23.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:97:0) after 1m0s
[00:59:23.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:97:0)
[00:59:23.993] Timer tick, asking Buildbucket for the build status
[00:59:24.155] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:24.155] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:98:0) after 1m0s
[01:00:24.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:98:0)
[01:00:24.197] Timer tick, asking Buildbucket for the build status
[01:00:24.278] Build 8854282252924740976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:24.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019712314905598144:99:0) after 1m0s
[01:01:01.890] Received PubSub notification, asking Buildbucket for the build status
[01:01:01.964] Build 8854282252924740976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:01:01.964] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614301260489513",
"created_by": "project:infra",
"created_ts": "1614295447382117",
"id": "8854282252924740976",
"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\": \"28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\", \"title\": \"28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/28dab6fc01c8b2958c3c1bac73f0c7be5ea3720b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"202406bee91adb6bb96df07757d6b6e2f321acc7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@202406bee91adb6bb96df07757d6b6e2f321acc7\", \"title\": \"202406bee91adb6bb96df07757d6b6e2f321acc7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/202406bee91adb6bb96df07757d6b6e2f321acc7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0780a7264bbc2a1f410b374ba847391e6b8f3299\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0780a7264bbc2a1f410b374ba847391e6b8f3299\", \"title\": \"0780a7264bbc2a1f410b374ba847391e6b8f3299\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0780a7264bbc2a1f410b374ba847391e6b8f3299\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0780a7264bbc2a1f410b374ba847391e6b8f3299\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"0780a7264bbc2a1f410b374ba847391e6b8f3299\", \"got_revision_cp\": \"refs/heads/master@{#38826}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"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-obcp\"], \"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\": [\"5715-a5b34b3\"], \"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": "1614300522155140",
"status": "COMPLETED",
"status_changed_ts": "1614300522520399",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18715",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0780a7264bbc2a1f410b374ba847391e6b8f3299",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0780a7264bbc2a1f410b374ba847391e6b8f3299",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019712314905598144",
"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/8854282252924740976/+/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:51f4534353f8a010",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614301260490825",
"url": "https://luci-milo-dev.appspot.com/b/8854282252924740976",
"utcnow_ts": "1614301261956400"
}
}
[01:01:01.964] Invocation finished in 1h36m55.855136258s with status SUCCEEDED