[22:36:44.113] New invocation is queued and will start shortly
[22:36:45.286] Starting the invocation (attempt 1)
[22:36:45.324] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[22:36:45.325] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[22:36:45.325] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9022976785760900096",
"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\":\"535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\",\"title\":\"535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"2dcdc6c2466ad6f313c341424e23329936840263\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2dcdc6c2466ad6f313c341424e23329936840263\",\"title\":\"2dcdc6c2466ad6f313c341424e23329936840263\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/2dcdc6c2466ad6f313c341424e23329936840263\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"00ff2bf5a8abb25f471a6d17446df6aa58f134ed\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@00ff2bf5a8abb25f471a6d17446df6aa58f134ed\",\"title\":\"00ff2bf5a8abb25f471a6d17446df6aa58f134ed\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/00ff2bf5a8abb25f471a6d17446df6aa58f134ed\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b31ac07c19b130202509b7826df3a859d311129a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b31ac07c19b130202509b7826df3a859d311129a\",\"title\":\"b31ac07c19b130202509b7826df3a859d311129a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b31ac07c19b130202509b7826df3a859d311129a\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"6d8d08a0f59146117c9d41f0e292fd7c59616303\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6d8d08a0f59146117c9d41f0e292fd7c59616303\",\"title\":\"6d8d08a0f59146117c9d41f0e292fd7c59616303\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/6d8d08a0f59146117c9d41f0e292fd7c59616303\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"a569ce758dce1671d1d4424f39e6aed48fd2ec61\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a569ce758dce1671d1d4424f39e6aed48fd2ec61\",\"title\":\"a569ce758dce1671d1d4424f39e6aed48fd2ec61\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/a569ce758dce1671d1d4424f39e6aed48fd2ec61\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"214556a5602af4da0f8c5e4c66362c967b657550\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@214556a5602af4da0f8c5e4c66362c967b657550\",\"title\":\"214556a5602af4da0f8c5e4c66362c967b657550\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/214556a5602af4da0f8c5e4c66362c967b657550\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0a37623ce10f0329a8f1ca0fb26f72bd492a1878\",\"title\":\"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}}",
"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:9022976785760900096",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/0a37623ce10f0329a8f1ca0fb26f72bd492a1878",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0a37623ce10f0329a8f1ca0fb26f72bd492a1878",
"gitiles_ref:refs/heads/master"
]
}
[22:36:45.995] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1611182205409783",
"id": "8857546723738828368",
"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\": \"535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\", \"title\": \"535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2dcdc6c2466ad6f313c341424e23329936840263\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2dcdc6c2466ad6f313c341424e23329936840263\", \"title\": \"2dcdc6c2466ad6f313c341424e23329936840263\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2dcdc6c2466ad6f313c341424e23329936840263\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"00ff2bf5a8abb25f471a6d17446df6aa58f134ed\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@00ff2bf5a8abb25f471a6d17446df6aa58f134ed\", \"title\": \"00ff2bf5a8abb25f471a6d17446df6aa58f134ed\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/00ff2bf5a8abb25f471a6d17446df6aa58f134ed\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b31ac07c19b130202509b7826df3a859d311129a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b31ac07c19b130202509b7826df3a859d311129a\", \"title\": \"b31ac07c19b130202509b7826df3a859d311129a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b31ac07c19b130202509b7826df3a859d311129a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6d8d08a0f59146117c9d41f0e292fd7c59616303\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6d8d08a0f59146117c9d41f0e292fd7c59616303\", \"title\": \"6d8d08a0f59146117c9d41f0e292fd7c59616303\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6d8d08a0f59146117c9d41f0e292fd7c59616303\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a569ce758dce1671d1d4424f39e6aed48fd2ec61\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a569ce758dce1671d1d4424f39e6aed48fd2ec61\", \"title\": \"a569ce758dce1671d1d4424f39e6aed48fd2ec61\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a569ce758dce1671d1d4424f39e6aed48fd2ec61\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"214556a5602af4da0f8c5e4c66362c967b657550\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@214556a5602af4da0f8c5e4c66362c967b657550\", \"title\": \"214556a5602af4da0f8c5e4c66362c967b657550\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/214556a5602af4da0f8c5e4c66362c967b657550\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0a37623ce10f0329a8f1ca0fb26f72bd492a1878\", \"title\": \"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611182205842742",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18395",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0a37623ce10f0329a8f1ca0fb26f72bd492a1878",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0a37623ce10f0329a8f1ca0fb26f72bd492a1878",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022976785760900096",
"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/8857546723738828368/+/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": "1611182205842845",
"url": "https://luci-milo-dev.appspot.com/b/8857546723738828368",
"utcnow_ts": "1611182205984117"
}
}
[22:36:45.995] Task URL: https://luci-milo-dev.appspot.com/b/8857546723738828368
[22:36:45.995] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:2:0) after 1m0s
[22:37:46.060] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:2:0)
[22:37:46.060] Timer tick, asking Buildbucket for the build status
[22:37:46.181] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:46.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:3:0) after 1m0s
[22:38:46.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:3:0)
[22:38:46.268] Timer tick, asking Buildbucket for the build status
[22:38:46.378] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:46.378] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:4:0) after 1m0s
[22:39:46.460] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:4:0)
[22:39:46.460] Timer tick, asking Buildbucket for the build status
[22:39:46.545] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:46.545] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:5:0) after 1m0s
[22:40:46.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:5:0)
[22:40:46.567] Timer tick, asking Buildbucket for the build status
[22:40:46.660] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:46.660] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:6:0) after 1m0s
[22:41:46.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:6:0)
[22:41:46.682] Timer tick, asking Buildbucket for the build status
[22:41:46.792] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:46.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:7:0) after 1m0s
[22:42:46.857] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:7:0)
[22:42:46.857] Timer tick, asking Buildbucket for the build status
[22:42:46.972] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:46.972] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:8:0) after 1m0s
[22:43:47.057] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:8:0)
[22:43:47.057] Timer tick, asking Buildbucket for the build status
[22:43:47.167] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:47.167] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:9:0) after 1m0s
[22:44:47.273] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:9:0)
[22:44:47.273] Timer tick, asking Buildbucket for the build status
[22:44:47.382] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:47.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:10:0) after 1m0s
[22:45:47.410] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:10:0)
[22:45:47.410] Timer tick, asking Buildbucket for the build status
[22:45:47.515] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:47.515] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:11:0) after 1m0s
[22:46:47.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:11:0)
[22:46:47.581] Timer tick, asking Buildbucket for the build status
[22:46:47.672] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:47.672] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:12:0) after 1m0s
[22:47:47.704] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:12:0)
[22:47:47.704] Timer tick, asking Buildbucket for the build status
[22:47:47.809] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:47.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:13:0) after 1m0s
[22:48:47.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:13:0)
[22:48:47.874] Timer tick, asking Buildbucket for the build status
[22:48:47.973] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:47.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:14:0) after 1m0s
[22:49:48.059] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:14:0)
[22:49:48.059] Timer tick, asking Buildbucket for the build status
[22:49:48.143] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:48.143] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:15:0) after 1m0s
[22:50:48.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:15:0)
[22:50:48.166] Timer tick, asking Buildbucket for the build status
[22:50:48.249] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:48.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:16:0) after 1m0s
[22:51:48.370] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:16:0)
[22:51:48.370] Timer tick, asking Buildbucket for the build status
[22:51:48.462] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:48.462] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:17:0) after 1m0s
[22:52:48.570] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:17:0)
[22:52:48.570] Timer tick, asking Buildbucket for the build status
[22:52:48.682] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:48.682] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:18:0) after 1m0s
[22:53:48.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:18:0)
[22:53:48.707] Timer tick, asking Buildbucket for the build status
[22:53:48.793] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:48.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:19:0) after 1m0s
[22:54:48.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:19:0)
[22:54:48.862] Timer tick, asking Buildbucket for the build status
[22:54:48.956] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:48.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:20:0) after 1m0s
[22:55:49.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:20:0)
[22:55:49.076] Timer tick, asking Buildbucket for the build status
[22:55:49.251] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:49.251] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:21:0) after 1m0s
[22:56:49.377] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:21:0)
[22:56:49.377] Timer tick, asking Buildbucket for the build status
[22:56:49.478] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:49.478] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:22:0) after 1m0s
[22:57:49.499] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:22:0)
[22:57:49.499] Timer tick, asking Buildbucket for the build status
[22:57:49.587] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:49.587] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:23:0) after 1m0s
[22:58:49.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:23:0)
[22:58:49.660] Timer tick, asking Buildbucket for the build status
[22:58:49.756] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:49.756] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:24:0) after 1m0s
[22:59:49.865] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:24:0)
[22:59:49.865] Timer tick, asking Buildbucket for the build status
[22:59:49.969] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:49.969] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:25:0) after 1m0s
[23:00:50.059] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:25:0)
[23:00:50.059] Timer tick, asking Buildbucket for the build status
[23:00:50.163] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:50.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:26:0) after 1m0s
[23:01:50.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:26:0)
[23:01:50.261] Timer tick, asking Buildbucket for the build status
[23:01:50.363] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:50.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:27:0) after 1m0s
[23:02:50.412] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:27:0)
[23:02:50.412] Timer tick, asking Buildbucket for the build status
[23:02:50.734] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:50.734] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:28:0) after 1m0s
[23:03:50.790] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:28:0)
[23:03:50.790] Timer tick, asking Buildbucket for the build status
[23:03:51.043] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:51.043] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:29:0) after 1m0s
[23:04:51.073] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:29:0)
[23:04:51.073] Timer tick, asking Buildbucket for the build status
[23:04:51.181] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:51.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:30:0) after 1m0s
[23:05:51.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:30:0)
[23:05:51.261] Timer tick, asking Buildbucket for the build status
[23:05:51.363] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:51.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:31:0) after 1m0s
[23:06:51.397] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:31:0)
[23:06:51.397] Timer tick, asking Buildbucket for the build status
[23:06:51.504] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:51.504] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:32:0) after 1m0s
[23:07:51.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:32:0)
[23:07:51.568] Timer tick, asking Buildbucket for the build status
[23:07:51.715] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:51.715] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:33:0) after 1m0s
[23:08:51.740] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:33:0)
[23:08:51.740] Timer tick, asking Buildbucket for the build status
[23:08:51.883] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:51.883] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:34:0) after 1m0s
[23:09:51.964] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:34:0)
[23:09:51.964] Timer tick, asking Buildbucket for the build status
[23:09:52.082] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:52.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:35:0) after 1m0s
[23:10:52.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:35:0)
[23:10:52.106] Timer tick, asking Buildbucket for the build status
[23:10:52.209] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:52.209] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:36:0) after 1m0s
[23:11:52.244] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:36:0)
[23:11:52.244] Timer tick, asking Buildbucket for the build status
[23:11:52.346] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:52.346] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:37:0) after 1m0s
[23:12:52.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:37:0)
[23:12:52.477] Timer tick, asking Buildbucket for the build status
[23:12:52.608] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:52.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:38:0) after 1m0s
[23:13:52.676] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:38:0)
[23:13:52.676] Timer tick, asking Buildbucket for the build status
[23:13:52.787] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:52.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:39:0) after 1m0s
[23:14:52.876] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:39:0)
[23:14:52.876] Timer tick, asking Buildbucket for the build status
[23:14:52.968] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:52.968] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:40:0) after 1m0s
[23:15:53.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:40:0)
[23:15:53.076] Timer tick, asking Buildbucket for the build status
[23:15:53.176] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:53.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:41:0) after 1m0s
[23:16:53.271] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:41:0)
[23:16:53.271] Timer tick, asking Buildbucket for the build status
[23:16:53.382] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:53.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:42:0) after 1m0s
[23:17:53.412] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:42:0)
[23:17:53.412] Timer tick, asking Buildbucket for the build status
[23:17:53.521] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:53.521] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:43:0) after 1m0s
[23:18:53.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:43:0)
[23:18:53.572] Timer tick, asking Buildbucket for the build status
[23:18:53.683] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:53.683] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:44:0) after 1m0s
[23:19:53.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:44:0)
[23:19:53.705] Timer tick, asking Buildbucket for the build status
[23:19:53.792] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:53.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:45:0) after 1m0s
[23:20:53.815] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:45:0)
[23:20:53.815] Timer tick, asking Buildbucket for the build status
[23:20:53.919] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:53.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:46:0) after 1m0s
[23:21:53.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:46:0)
[23:21:53.970] Timer tick, asking Buildbucket for the build status
[23:21:54.059] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:54.059] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:47:0) after 1m0s
[23:22:54.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:47:0)
[23:22:54.177] Timer tick, asking Buildbucket for the build status
[23:22:54.266] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:54.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:48:0) after 1m0s
[23:23:54.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:48:0)
[23:23:54.287] Timer tick, asking Buildbucket for the build status
[23:23:54.471] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:54.471] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:49:0) after 1m0s
[23:24:54.570] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:49:0)
[23:24:54.570] Timer tick, asking Buildbucket for the build status
[23:24:54.663] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:54.663] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:50:0) after 1m0s
[23:25:54.820] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:50:0)
[23:25:54.820] Timer tick, asking Buildbucket for the build status
[23:25:54.965] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:54.965] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:51:0) after 1m0s
[23:26:55.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:51:0)
[23:26:55.002] Timer tick, asking Buildbucket for the build status
[23:26:55.166] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:55.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:52:0) after 1m0s
[23:27:55.272] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:52:0)
[23:27:55.272] Timer tick, asking Buildbucket for the build status
[23:27:55.405] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:55.405] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:53:0) after 1m0s
[23:28:55.475] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:53:0)
[23:28:55.475] Timer tick, asking Buildbucket for the build status
[23:28:55.609] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:55.609] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:54:0) after 1m0s
[23:29:55.666] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:54:0)
[23:29:55.666] Timer tick, asking Buildbucket for the build status
[23:29:55.765] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:55.765] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:55:0) after 1m0s
[23:30:55.867] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:55:0)
[23:30:55.868] Timer tick, asking Buildbucket for the build status
[23:30:55.966] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:55.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:56:0) after 1m0s
[23:31:56.057] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:56:0)
[23:31:56.057] Timer tick, asking Buildbucket for the build status
[23:31:56.145] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:56.145] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:57:0) after 1m0s
[23:32:56.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:57:0)
[23:32:56.186] Timer tick, asking Buildbucket for the build status
[23:32:56.289] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:56.289] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:58:0) after 1m0s
[23:33:56.314] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:58:0)
[23:33:56.314] Timer tick, asking Buildbucket for the build status
[23:33:56.408] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:56.408] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:59:0) after 1m0s
[23:34:56.486] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:59:0)
[23:34:56.486] Timer tick, asking Buildbucket for the build status
[23:34:56.679] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:56.679] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:60:0) after 1m0s
[23:35:56.758] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:60:0)
[23:35:56.758] Timer tick, asking Buildbucket for the build status
[23:35:56.850] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:56.850] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:61:0) after 1m0s
[23:36:56.882] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:61:0)
[23:36:56.882] Timer tick, asking Buildbucket for the build status
[23:36:56.970] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:56.970] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:62:0) after 1m0s
[23:37:57.069] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:62:0)
[23:37:57.069] Timer tick, asking Buildbucket for the build status
[23:37:57.183] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:57.183] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:63:0) after 1m0s
[23:38:57.277] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:63:0)
[23:38:57.277] Timer tick, asking Buildbucket for the build status
[23:38:57.382] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:57.382] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:64:0) after 1m0s
[23:39:57.457] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:64:0)
[23:39:57.457] Timer tick, asking Buildbucket for the build status
[23:39:57.567] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:57.567] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:65:0) after 1m0s
[23:40:57.677] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:65:0)
[23:40:57.678] Timer tick, asking Buildbucket for the build status
[23:40:57.787] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:57.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:66:0) after 1m0s
[23:41:57.867] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:66:0)
[23:41:57.867] Timer tick, asking Buildbucket for the build status
[23:41:58.028] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:58.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:67:0) after 1m0s
[23:42:58.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:67:0)
[23:42:58.074] Timer tick, asking Buildbucket for the build status
[23:42:58.163] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:58.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:68:0) after 1m0s
[23:43:58.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:68:0)
[23:43:58.270] Timer tick, asking Buildbucket for the build status
[23:43:58.360] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:58.360] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:69:0) after 1m0s
[23:44:58.386] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:69:0)
[23:44:58.386] Timer tick, asking Buildbucket for the build status
[23:44:58.506] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:58.506] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:70:0) after 1m0s
[23:45:58.584] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:70:0)
[23:45:58.584] Timer tick, asking Buildbucket for the build status
[23:45:58.693] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:58.693] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:71:0) after 1m0s
[23:46:58.772] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:71:0)
[23:46:58.772] Timer tick, asking Buildbucket for the build status
[23:46:58.864] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:58.864] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:72:0) after 1m0s
[23:47:58.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:72:0)
[23:47:58.972] Timer tick, asking Buildbucket for the build status
[23:47:59.062] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:59.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:73:0) after 1m0s
[23:48:59.172] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:73:0)
[23:48:59.172] Timer tick, asking Buildbucket for the build status
[23:48:59.262] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:59.262] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:74:0) after 1m0s
[23:49:59.397] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:74:0)
[23:49:59.397] Timer tick, asking Buildbucket for the build status
[23:49:59.564] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:59.564] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:75:0) after 1m0s
[23:50:59.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:75:0)
[23:50:59.593] Timer tick, asking Buildbucket for the build status
[23:50:59.679] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:59.679] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:76:0) after 1m0s
[23:51:59.721] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:76:0)
[23:51:59.721] Timer tick, asking Buildbucket for the build status
[23:51:59.811] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:59.811] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:77:0) after 1m0s
[23:52:59.837] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:77:0)
[23:52:59.837] Timer tick, asking Buildbucket for the build status
[23:52:59.919] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:59.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:78:0) after 1m0s
[23:53:59.945] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:78:0)
[23:53:59.945] Timer tick, asking Buildbucket for the build status
[23:54:00.237] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:00.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:79:0) after 1m0s
[23:55:00.313] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:79:0)
[23:55:00.313] Timer tick, asking Buildbucket for the build status
[23:55:00.427] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:00.427] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:80:0) after 1m0s
[23:56:00.449] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:80:0)
[23:56:00.449] Timer tick, asking Buildbucket for the build status
[23:56:00.544] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:00.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:81:0) after 1m0s
[23:57:00.577] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:81:0)
[23:57:00.577] Timer tick, asking Buildbucket for the build status
[23:57:00.677] Build 8857546723738828368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:00.677] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:82:0) after 1m0s
[23:58:00.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:82:0)
[23:58:00.699] Timer tick, asking Buildbucket for the build status
[23:58:00.887] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:00.887] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:83:0) after 1m0s
[23:59:00.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:83:0)
[23:59:00.983] Timer tick, asking Buildbucket for the build status
[23:59:01.093] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:01.093] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:84:0) after 1m0s
[00:00:01.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:84:0)
[00:00:01.195] Timer tick, asking Buildbucket for the build status
[00:00:01.350] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:01.350] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:85:0) after 1m0s
[00:00:17.632] Received PubSub notification, asking Buildbucket for the build status
[00:00:17.791] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:01.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:85:0)
[00:01:01.394] Timer tick, asking Buildbucket for the build status
[00:01:01.500] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:01.500] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:87:0) after 1m0s
[00:02:01.547] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:87:0)
[00:02:01.547] Timer tick, asking Buildbucket for the build status
[00:02:01.649] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:01.649] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:88:0) after 1m0s
[00:03:01.763] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:88:0)
[00:03:01.763] Timer tick, asking Buildbucket for the build status
[00:03:01.849] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:01.849] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:89:0) after 1m0s
[00:04:01.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:89:0)
[00:04:01.982] Timer tick, asking Buildbucket for the build status
[00:04:02.161] Build 8857546723738828368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:02.162] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:90:0) after 1m0s
[00:05:02.292] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9022976785760900096:90:0)
[00:05:02.292] Timer tick, asking Buildbucket for the build status
[00:05:02.525] Build 8857546723738828368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:05:02.525] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611187465396709",
"created_by": "project:infra",
"created_ts": "1611182205409783",
"id": "8857546723738828368",
"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\": \"535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\", \"title\": \"535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/535ad359abb5e863d604b1bc0e4fb1ab9c649fbf\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2dcdc6c2466ad6f313c341424e23329936840263\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2dcdc6c2466ad6f313c341424e23329936840263\", \"title\": \"2dcdc6c2466ad6f313c341424e23329936840263\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2dcdc6c2466ad6f313c341424e23329936840263\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"00ff2bf5a8abb25f471a6d17446df6aa58f134ed\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@00ff2bf5a8abb25f471a6d17446df6aa58f134ed\", \"title\": \"00ff2bf5a8abb25f471a6d17446df6aa58f134ed\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/00ff2bf5a8abb25f471a6d17446df6aa58f134ed\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b31ac07c19b130202509b7826df3a859d311129a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b31ac07c19b130202509b7826df3a859d311129a\", \"title\": \"b31ac07c19b130202509b7826df3a859d311129a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b31ac07c19b130202509b7826df3a859d311129a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6d8d08a0f59146117c9d41f0e292fd7c59616303\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6d8d08a0f59146117c9d41f0e292fd7c59616303\", \"title\": \"6d8d08a0f59146117c9d41f0e292fd7c59616303\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6d8d08a0f59146117c9d41f0e292fd7c59616303\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a569ce758dce1671d1d4424f39e6aed48fd2ec61\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a569ce758dce1671d1d4424f39e6aed48fd2ec61\", \"title\": \"a569ce758dce1671d1d4424f39e6aed48fd2ec61\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a569ce758dce1671d1d4424f39e6aed48fd2ec61\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"214556a5602af4da0f8c5e4c66362c967b657550\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@214556a5602af4da0f8c5e4c66362c967b657550\", \"title\": \"214556a5602af4da0f8c5e4c66362c967b657550\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/214556a5602af4da0f8c5e4c66362c967b657550\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0a37623ce10f0329a8f1ca0fb26f72bd492a1878\", \"title\": \"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"0a37623ce10f0329a8f1ca0fb26f72bd492a1878\", \"got_revision_cp\": \"refs/heads/master@{#37801}\"}, \"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-kdp5\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611187056575777",
"status": "COMPLETED",
"status_changed_ts": "1611187467227394",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18395",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0a37623ce10f0329a8f1ca0fb26f72bd492a1878",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0a37623ce10f0329a8f1ca0fb26f72bd492a1878",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022976785760900096",
"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/8857546723738828368/+/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:513ac2f52b5db910",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1611187467277594",
"url": "https://luci-milo-dev.appspot.com/b/8857546723738828368",
"utcnow_ts": "1611187502507503"
}
}
[00:05:02.525] Invocation finished in 1h28m18.427674803s with status SUCCEEDED