[21:09:56.108] New invocation is queued and will start shortly
[21:09:57.199] Starting the invocation (attempt 1)
[21:09:57.277] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:09:57.279] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:09:57.280] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9023435231587547280",
"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\":\"ccaa21b45ea2a85a46bbe151c53e64baacc462d9\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ccaa21b45ea2a85a46bbe151c53e64baacc462d9\",\"title\":\"ccaa21b45ea2a85a46bbe151c53e64baacc462d9\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ccaa21b45ea2a85a46bbe151c53e64baacc462d9\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\",\"title\":\"b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"4537dff2afd922fd166fb28175fb6ef66ce8eafb\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4537dff2afd922fd166fb28175fb6ef66ce8eafb\",\"title\":\"4537dff2afd922fd166fb28175fb6ef66ce8eafb\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/4537dff2afd922fd166fb28175fb6ef66ce8eafb\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ae343bef79f6c8a4559231a5b9a7d8f034d64f65\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ae343bef79f6c8a4559231a5b9a7d8f034d64f65\",\"title\":\"ae343bef79f6c8a4559231a5b9a7d8f034d64f65\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ae343bef79f6c8a4559231a5b9a7d8f034d64f65\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\",\"title\":\"05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"4c17671fa0b952f32b2394da2744720cea4beaab\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4c17671fa0b952f32b2394da2744720cea4beaab\",\"title\":\"4c17671fa0b952f32b2394da2744720cea4beaab\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/4c17671fa0b952f32b2394da2744720cea4beaab\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"97be5f7f13cc051f4c68363049eeb2f976b827df\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@97be5f7f13cc051f4c68363049eeb2f976b827df\",\"title\":\"97be5f7f13cc051f4c68363049eeb2f976b827df\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/97be5f7f13cc051f4c68363049eeb2f976b827df\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"29abc3c63e6100d05c40c6f79dd1627e90e46e51\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@29abc3c63e6100d05c40c6f79dd1627e90e46e51\",\"title\":\"29abc3c63e6100d05c40c6f79dd1627e90e46e51\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/29abc3c63e6100d05c40c6f79dd1627e90e46e51\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"66bba9d8f11ab40b9c20e3948fe250082eb96f7d\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66bba9d8f11ab40b9c20e3948fe250082eb96f7d\",\"title\":\"66bba9d8f11ab40b9c20e3948fe250082eb96f7d\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/66bba9d8f11ab40b9c20e3948fe250082eb96f7d\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"6f908c06727e883801f1f65fb874ad6b9b360298\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6f908c06727e883801f1f65fb874ad6b9b360298\",\"title\":\"6f908c06727e883801f1f65fb874ad6b9b360298\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/6f908c06727e883801f1f65fb874ad6b9b360298\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"6f908c06727e883801f1f65fb874ad6b9b360298\"}}",
"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:9023435231587547280",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/6f908c06727e883801f1f65fb874ad6b9b360298",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6f908c06727e883801f1f65fb874ad6b9b360298",
"gitiles_ref:refs/heads/master"
]
}
[21:09:58.161] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1610744997395257",
"id": "8858005169569875712",
"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\": \"ccaa21b45ea2a85a46bbe151c53e64baacc462d9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ccaa21b45ea2a85a46bbe151c53e64baacc462d9\", \"title\": \"ccaa21b45ea2a85a46bbe151c53e64baacc462d9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ccaa21b45ea2a85a46bbe151c53e64baacc462d9\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\", \"title\": \"b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4537dff2afd922fd166fb28175fb6ef66ce8eafb\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4537dff2afd922fd166fb28175fb6ef66ce8eafb\", \"title\": \"4537dff2afd922fd166fb28175fb6ef66ce8eafb\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4537dff2afd922fd166fb28175fb6ef66ce8eafb\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ae343bef79f6c8a4559231a5b9a7d8f034d64f65\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ae343bef79f6c8a4559231a5b9a7d8f034d64f65\", \"title\": \"ae343bef79f6c8a4559231a5b9a7d8f034d64f65\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ae343bef79f6c8a4559231a5b9a7d8f034d64f65\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\", \"title\": \"05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4c17671fa0b952f32b2394da2744720cea4beaab\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4c17671fa0b952f32b2394da2744720cea4beaab\", \"title\": \"4c17671fa0b952f32b2394da2744720cea4beaab\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4c17671fa0b952f32b2394da2744720cea4beaab\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"97be5f7f13cc051f4c68363049eeb2f976b827df\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@97be5f7f13cc051f4c68363049eeb2f976b827df\", \"title\": \"97be5f7f13cc051f4c68363049eeb2f976b827df\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/97be5f7f13cc051f4c68363049eeb2f976b827df\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"29abc3c63e6100d05c40c6f79dd1627e90e46e51\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@29abc3c63e6100d05c40c6f79dd1627e90e46e51\", \"title\": \"29abc3c63e6100d05c40c6f79dd1627e90e46e51\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/29abc3c63e6100d05c40c6f79dd1627e90e46e51\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"66bba9d8f11ab40b9c20e3948fe250082eb96f7d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66bba9d8f11ab40b9c20e3948fe250082eb96f7d\", \"title\": \"66bba9d8f11ab40b9c20e3948fe250082eb96f7d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/66bba9d8f11ab40b9c20e3948fe250082eb96f7d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6f908c06727e883801f1f65fb874ad6b9b360298\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6f908c06727e883801f1f65fb874ad6b9b360298\", \"title\": \"6f908c06727e883801f1f65fb874ad6b9b360298\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6f908c06727e883801f1f65fb874ad6b9b360298\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6f908c06727e883801f1f65fb874ad6b9b360298\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610744998028908",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18357",
"builder:infra-continuous-win10-64",
"buildset:commit/git/6f908c06727e883801f1f65fb874ad6b9b360298",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6f908c06727e883801f1f65fb874ad6b9b360298",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023435231587547280",
"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/8858005169569875712/+/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": "1610744998029030",
"url": "https://luci-milo-dev.appspot.com/b/8858005169569875712",
"utcnow_ts": "1610744998150079"
}
}
[21:09:58.161] Task URL: https://luci-milo-dev.appspot.com/b/8858005169569875712
[21:09:58.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:2:0) after 1m0s
[21:10:58.188] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:2:0)
[21:10:58.188] Timer tick, asking Buildbucket for the build status
[21:10:58.284] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:58.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:3:0) after 1m0s
[21:11:58.356] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:3:0)
[21:11:58.356] Timer tick, asking Buildbucket for the build status
[21:11:58.483] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:58.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:4:0) after 1m0s
[21:12:58.553] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:4:0)
[21:12:58.553] Timer tick, asking Buildbucket for the build status
[21:12:58.654] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:58.654] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:5:0) after 1m0s
[21:13:58.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:5:0)
[21:13:58.693] Timer tick, asking Buildbucket for the build status
[21:13:58.797] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:58.797] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:6:0) after 1m0s
[21:14:58.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:6:0)
[21:14:58.847] Timer tick, asking Buildbucket for the build status
[21:14:59.025] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:59.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:7:0) after 1m0s
[21:15:59.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:7:0)
[21:15:59.072] Timer tick, asking Buildbucket for the build status
[21:15:59.171] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:59.171] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:8:0) after 1m0s
[21:16:59.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:8:0)
[21:16:59.197] Timer tick, asking Buildbucket for the build status
[21:16:59.303] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:59.303] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:9:0) after 1m0s
[21:17:59.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:9:0)
[21:17:59.362] Timer tick, asking Buildbucket for the build status
[21:17:59.528] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:59.528] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:10:0) after 1m0s
[21:18:59.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:10:0)
[21:18:59.557] Timer tick, asking Buildbucket for the build status
[21:18:59.672] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:59.672] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:11:0) after 1m0s
[21:19:59.745] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:11:0)
[21:19:59.745] Timer tick, asking Buildbucket for the build status
[21:19:59.833] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:59.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:12:0) after 1m0s
[21:20:59.875] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:12:0)
[21:20:59.875] Timer tick, asking Buildbucket for the build status
[21:21:00.282] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:00.282] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:13:0) after 1m0s
[21:22:00.348] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:13:0)
[21:22:00.348] Timer tick, asking Buildbucket for the build status
[21:22:00.443] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:00.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:14:0) after 1m0s
[21:23:00.546] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:14:0)
[21:23:00.546] Timer tick, asking Buildbucket for the build status
[21:23:00.637] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:00.637] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:15:0) after 1m0s
[21:24:00.777] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:15:0)
[21:24:00.778] Timer tick, asking Buildbucket for the build status
[21:24:00.896] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:00.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:16:0) after 1m0s
[21:25:00.947] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:16:0)
[21:25:00.947] Timer tick, asking Buildbucket for the build status
[21:25:01.038] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:01.039] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:17:0) after 1m0s
[21:26:01.176] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:17:0)
[21:26:01.176] Timer tick, asking Buildbucket for the build status
[21:26:01.476] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:01.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:18:0) after 1m0s
[21:27:01.541] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:18:0)
[21:27:01.541] Timer tick, asking Buildbucket for the build status
[21:27:01.658] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:01.658] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:19:0) after 1m0s
[21:28:01.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:19:0)
[21:28:01.710] Timer tick, asking Buildbucket for the build status
[21:28:01.872] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:01.873] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:20:0) after 1m0s
[21:29:01.909] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:20:0)
[21:29:01.909] Timer tick, asking Buildbucket for the build status
[21:29:02.028] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:02.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:21:0) after 1m0s
[21:30:02.089] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:21:0)
[21:30:02.089] Timer tick, asking Buildbucket for the build status
[21:30:02.237] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:02.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:22:0) after 1m0s
[21:31:02.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:22:0)
[21:31:02.385] Timer tick, asking Buildbucket for the build status
[21:31:02.469] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:02.469] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:23:0) after 1m0s
[21:32:02.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:23:0)
[21:32:02.497] Timer tick, asking Buildbucket for the build status
[21:32:02.586] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:02.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:24:0) after 1m0s
[21:33:02.669] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:24:0)
[21:33:02.669] Timer tick, asking Buildbucket for the build status
[21:33:02.765] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:02.765] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:25:0) after 1m0s
[21:34:02.856] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:25:0)
[21:34:02.856] Timer tick, asking Buildbucket for the build status
[21:34:02.949] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:02.949] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:26:0) after 1m0s
[21:35:03.049] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:26:0)
[21:35:03.049] Timer tick, asking Buildbucket for the build status
[21:35:03.257] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:03.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:27:0) after 1m0s
[21:36:03.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:27:0)
[21:36:03.289] Timer tick, asking Buildbucket for the build status
[21:36:03.463] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:03.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:28:0) after 1m0s
[21:37:03.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:28:0)
[21:37:03.574] Timer tick, asking Buildbucket for the build status
[21:37:03.676] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:03.676] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:29:0) after 1m0s
[21:38:03.709] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:29:0)
[21:38:03.709] Timer tick, asking Buildbucket for the build status
[21:38:03.798] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:03.798] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:30:0) after 1m0s
[21:39:03.846] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:30:0)
[21:39:03.846] Timer tick, asking Buildbucket for the build status
[21:39:03.962] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:03.962] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:31:0) after 1m0s
[21:40:04.063] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:31:0)
[21:40:04.063] Timer tick, asking Buildbucket for the build status
[21:40:04.160] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:04.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:32:0) after 1m0s
[21:41:04.244] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:32:0)
[21:41:04.244] Timer tick, asking Buildbucket for the build status
[21:41:04.377] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:04.377] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:33:0) after 1m0s
[21:42:04.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:33:0)
[21:42:04.414] Timer tick, asking Buildbucket for the build status
[21:42:04.509] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:04.509] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:34:0) after 1m0s
[21:43:04.564] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:34:0)
[21:43:04.565] Timer tick, asking Buildbucket for the build status
[21:43:04.672] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:04.672] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:35:0) after 1m0s
[21:44:04.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:35:0)
[21:44:04.700] Timer tick, asking Buildbucket for the build status
[21:44:04.834] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:04.834] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:36:0) after 1m0s
[21:45:04.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:36:0)
[21:45:04.949] Timer tick, asking Buildbucket for the build status
[21:45:05.046] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:05.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:37:0) after 1m0s
[21:46:05.087] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:37:0)
[21:46:05.087] Timer tick, asking Buildbucket for the build status
[21:46:05.214] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:05.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:38:0) after 1m0s
[21:47:05.249] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:38:0)
[21:47:05.249] Timer tick, asking Buildbucket for the build status
[21:47:05.361] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:05.361] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:39:0) after 1m0s
[21:48:05.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:39:0)
[21:48:05.471] Timer tick, asking Buildbucket for the build status
[21:48:05.559] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:05.559] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:40:0) after 1m0s
[21:49:05.688] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:40:0)
[21:49:05.688] Timer tick, asking Buildbucket for the build status
[21:49:05.805] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:05.805] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:41:0) after 1m0s
[21:50:05.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:41:0)
[21:50:05.874] Timer tick, asking Buildbucket for the build status
[21:50:06.028] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:06.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:42:0) after 1m0s
[21:51:06.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:42:0)
[21:51:06.065] Timer tick, asking Buildbucket for the build status
[21:51:06.156] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:06.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:43:0) after 1m0s
[21:52:06.217] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:43:0)
[21:52:06.217] Timer tick, asking Buildbucket for the build status
[21:52:06.307] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:06.307] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:44:0) after 1m0s
[21:53:06.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:44:0)
[21:53:06.362] Timer tick, asking Buildbucket for the build status
[21:53:06.521] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:06.521] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:45:0) after 1m0s
[21:54:06.563] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:45:0)
[21:54:06.563] Timer tick, asking Buildbucket for the build status
[21:54:06.656] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:06.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:46:0) after 1m0s
[21:55:06.686] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:46:0)
[21:55:06.686] Timer tick, asking Buildbucket for the build status
[21:55:06.776] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:06.776] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:47:0) after 1m0s
[21:56:06.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:47:0)
[21:56:06.809] Timer tick, asking Buildbucket for the build status
[21:56:06.909] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:06.909] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:48:0) after 1m0s
[21:57:06.945] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:48:0)
[21:57:06.945] Timer tick, asking Buildbucket for the build status
[21:57:07.054] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:07.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:49:0) after 1m0s
[21:58:07.088] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:49:0)
[21:58:07.088] Timer tick, asking Buildbucket for the build status
[21:58:07.213] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:07.213] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:50:0) after 1m0s
[21:59:07.247] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:50:0)
[21:59:07.247] Timer tick, asking Buildbucket for the build status
[21:59:07.333] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:07.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:51:0) after 1m0s
[22:00:07.378] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:51:0)
[22:00:07.378] Timer tick, asking Buildbucket for the build status
[22:00:07.472] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:07.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:52:0) after 1m0s
[22:01:07.571] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:52:0)
[22:01:07.571] Timer tick, asking Buildbucket for the build status
[22:01:07.671] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:07.671] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:53:0) after 1m0s
[22:02:07.764] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:53:0)
[22:02:07.764] Timer tick, asking Buildbucket for the build status
[22:02:07.905] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:07.905] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:54:0) after 1m0s
[22:03:07.950] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:54:0)
[22:03:07.950] Timer tick, asking Buildbucket for the build status
[22:03:08.041] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:08.041] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:55:0) after 1m0s
[22:04:08.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:55:0)
[22:04:08.174] Timer tick, asking Buildbucket for the build status
[22:04:08.317] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:08.317] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:56:0) after 1m0s
[22:05:08.432] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:56:0)
[22:05:08.432] Timer tick, asking Buildbucket for the build status
[22:05:08.543] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:08.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:57:0) after 1m0s
[22:06:08.727] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:57:0)
[22:06:08.727] Timer tick, asking Buildbucket for the build status
[22:06:08.827] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:08.827] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:58:0) after 1m0s
[22:07:08.860] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:58:0)
[22:07:08.860] Timer tick, asking Buildbucket for the build status
[22:07:08.952] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:08.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:59:0) after 1m0s
[22:08:09.047] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:59:0)
[22:08:09.047] Timer tick, asking Buildbucket for the build status
[22:08:09.154] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:09.154] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:60:0) after 1m0s
[22:09:09.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:60:0)
[22:09:09.203] Timer tick, asking Buildbucket for the build status
[22:09:09.303] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:09.303] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:61:0) after 1m0s
[22:10:09.396] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:61:0)
[22:10:09.396] Timer tick, asking Buildbucket for the build status
[22:10:09.541] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:09.541] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:62:0) after 1m0s
[22:11:09.670] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:62:0)
[22:11:09.670] Timer tick, asking Buildbucket for the build status
[22:11:09.788] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:09.788] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:63:0) after 1m0s
[22:12:09.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:63:0)
[22:12:09.863] Timer tick, asking Buildbucket for the build status
[22:12:09.959] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:09.959] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:64:0) after 1m0s
[22:13:09.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:64:0)
[22:13:09.991] Timer tick, asking Buildbucket for the build status
[22:13:10.084] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:10.084] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:65:0) after 1m0s
[22:14:10.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:65:0)
[22:14:10.166] Timer tick, asking Buildbucket for the build status
[22:14:10.263] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:10.263] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:66:0) after 1m0s
[22:15:10.372] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:66:0)
[22:15:10.372] Timer tick, asking Buildbucket for the build status
[22:15:10.466] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:10.467] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:67:0) after 1m0s
[22:16:10.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:67:0)
[22:16:10.567] Timer tick, asking Buildbucket for the build status
[22:16:10.656] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:10.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:68:0) after 1m0s
[22:17:10.690] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:68:0)
[22:17:10.690] Timer tick, asking Buildbucket for the build status
[22:17:10.849] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:10.849] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:69:0) after 1m0s
[22:18:10.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:69:0)
[22:18:10.972] Timer tick, asking Buildbucket for the build status
[22:18:11.103] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:11.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:70:0) after 1m0s
[22:19:11.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:70:0)
[22:19:11.133] Timer tick, asking Buildbucket for the build status
[22:19:11.232] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:11.232] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:71:0) after 1m0s
[22:20:11.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:71:0)
[22:20:11.265] Timer tick, asking Buildbucket for the build status
[22:20:11.366] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:11.366] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:72:0) after 1m0s
[22:21:11.473] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:72:0)
[22:21:11.473] Timer tick, asking Buildbucket for the build status
[22:21:11.562] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:11.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:73:0) after 1m0s
[22:22:11.667] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:73:0)
[22:22:11.667] Timer tick, asking Buildbucket for the build status
[22:22:11.768] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:11.768] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:74:0) after 1m0s
[22:23:11.852] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:74:0)
[22:23:11.852] Timer tick, asking Buildbucket for the build status
[22:23:12.113] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:12.113] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:75:0) after 1m0s
[22:24:12.153] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:75:0)
[22:24:12.153] Timer tick, asking Buildbucket for the build status
[22:24:12.249] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:12.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:76:0) after 1m0s
[22:25:12.373] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:76:0)
[22:25:12.373] Timer tick, asking Buildbucket for the build status
[22:25:12.466] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:12.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:77:0) after 1m0s
[22:26:12.561] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:77:0)
[22:26:12.561] Timer tick, asking Buildbucket for the build status
[22:26:12.657] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:12.657] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:78:0) after 1m0s
[22:27:12.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:78:0)
[22:27:12.702] Timer tick, asking Buildbucket for the build status
[22:27:12.792] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:12.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:79:0) after 1m0s
[22:28:12.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:79:0)
[22:28:12.873] Timer tick, asking Buildbucket for the build status
[22:28:13.046] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:13.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:80:0) after 1m0s
[22:29:13.181] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:80:0)
[22:29:13.181] Timer tick, asking Buildbucket for the build status
[22:29:13.285] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:13.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:81:0) after 1m0s
[22:30:13.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:81:0)
[22:30:13.382] Timer tick, asking Buildbucket for the build status
[22:30:13.469] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:13.469] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:82:0) after 1m0s
[22:31:13.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:82:0)
[22:31:13.502] Timer tick, asking Buildbucket for the build status
[22:31:13.615] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:13.615] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:83:0) after 1m0s
[22:32:13.667] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:83:0)
[22:32:13.667] Timer tick, asking Buildbucket for the build status
[22:32:13.778] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:13.778] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:84:0) after 1m0s
[22:33:13.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:84:0)
[22:33:13.847] Timer tick, asking Buildbucket for the build status
[22:33:13.958] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:13.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:85:0) after 1m0s
[22:34:14.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:85:0)
[22:34:14.067] Timer tick, asking Buildbucket for the build status
[22:34:14.273] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:14.273] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:86:0) after 1m0s
[22:35:14.369] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:86:0)
[22:35:14.369] Timer tick, asking Buildbucket for the build status
[22:35:14.464] Build 8858005169569875712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:14.464] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:87:0) after 1m0s
[22:35:26.476] Received PubSub notification, asking Buildbucket for the build status
[22:35:26.592] Build 8858005169569875712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:14.550] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:87:0)
[22:36:14.550] Timer tick, asking Buildbucket for the build status
[22:36:14.662] Build 8858005169569875712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:14.662] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:89:0) after 1m0s
[22:37:14.701] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:89:0)
[22:37:14.701] Timer tick, asking Buildbucket for the build status
[22:37:14.817] Build 8858005169569875712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:14.817] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:90:0) after 1m0s
[22:38:14.872] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:90:0)
[22:38:14.873] Timer tick, asking Buildbucket for the build status
[22:38:15.007] Build 8858005169569875712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:15.007] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:91:0) after 1m0s
[22:39:15.069] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:91:0)
[22:39:15.069] Timer tick, asking Buildbucket for the build status
[22:39:15.189] Build 8858005169569875712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:15.189] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:92:0) after 1m0s
[22:40:15.275] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:92:0)
[22:40:15.275] Timer tick, asking Buildbucket for the build status
[22:40:15.462] Build 8858005169569875712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:15.462] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:93:0) after 1m0s
[22:41:15.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:93:0)
[22:41:15.569] Timer tick, asking Buildbucket for the build status
[22:41:15.700] Build 8858005169569875712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:15.700] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9023435231587547280:94:0) after 1m0s
[22:41:44.760] Received PubSub notification, asking Buildbucket for the build status
[22:41:44.867] Build 8858005169569875712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:41:44.867] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1610750500252913",
"created_by": "project:infra",
"created_ts": "1610744997395257",
"id": "8858005169569875712",
"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\": \"ccaa21b45ea2a85a46bbe151c53e64baacc462d9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ccaa21b45ea2a85a46bbe151c53e64baacc462d9\", \"title\": \"ccaa21b45ea2a85a46bbe151c53e64baacc462d9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ccaa21b45ea2a85a46bbe151c53e64baacc462d9\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\", \"title\": \"b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b88bfe87cf0372c936ba8bfd4dd45ecd863a1230\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4537dff2afd922fd166fb28175fb6ef66ce8eafb\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4537dff2afd922fd166fb28175fb6ef66ce8eafb\", \"title\": \"4537dff2afd922fd166fb28175fb6ef66ce8eafb\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4537dff2afd922fd166fb28175fb6ef66ce8eafb\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ae343bef79f6c8a4559231a5b9a7d8f034d64f65\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ae343bef79f6c8a4559231a5b9a7d8f034d64f65\", \"title\": \"ae343bef79f6c8a4559231a5b9a7d8f034d64f65\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ae343bef79f6c8a4559231a5b9a7d8f034d64f65\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\", \"title\": \"05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/05bbfdae23f8cf8705bf4a6b00bf8cc4254f9eb5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4c17671fa0b952f32b2394da2744720cea4beaab\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4c17671fa0b952f32b2394da2744720cea4beaab\", \"title\": \"4c17671fa0b952f32b2394da2744720cea4beaab\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4c17671fa0b952f32b2394da2744720cea4beaab\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"97be5f7f13cc051f4c68363049eeb2f976b827df\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@97be5f7f13cc051f4c68363049eeb2f976b827df\", \"title\": \"97be5f7f13cc051f4c68363049eeb2f976b827df\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/97be5f7f13cc051f4c68363049eeb2f976b827df\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"29abc3c63e6100d05c40c6f79dd1627e90e46e51\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@29abc3c63e6100d05c40c6f79dd1627e90e46e51\", \"title\": \"29abc3c63e6100d05c40c6f79dd1627e90e46e51\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/29abc3c63e6100d05c40c6f79dd1627e90e46e51\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"66bba9d8f11ab40b9c20e3948fe250082eb96f7d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66bba9d8f11ab40b9c20e3948fe250082eb96f7d\", \"title\": \"66bba9d8f11ab40b9c20e3948fe250082eb96f7d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/66bba9d8f11ab40b9c20e3948fe250082eb96f7d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6f908c06727e883801f1f65fb874ad6b9b360298\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6f908c06727e883801f1f65fb874ad6b9b360298\", \"title\": \"6f908c06727e883801f1f65fb874ad6b9b360298\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6f908c06727e883801f1f65fb874ad6b9b360298\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6f908c06727e883801f1f65fb874ad6b9b360298\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"6f908c06727e883801f1f65fb874ad6b9b360298\", \"got_revision_cp\": \"refs/heads/master@{#37694}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-r1cr\"], \"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\": [\"5667-488cbf9\"], \"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": "1610750121420465",
"status": "COMPLETED",
"status_changed_ts": "1610750500563334",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18357",
"builder:infra-continuous-win10-64",
"buildset:commit/git/6f908c06727e883801f1f65fb874ad6b9b360298",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6f908c06727e883801f1f65fb874ad6b9b360298",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023435231587547280",
"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/8858005169569875712/+/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:5120b3b29ef56210",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1610750500630248",
"url": "https://luci-milo-dev.appspot.com/b/8858005169569875712",
"utcnow_ts": "1610750504858591"
}
}
[22:41:44.867] Invocation finished in 1h31m48.780569866s with status SUCCEEDED