[21:46:08.150] New invocation is queued and will start shortly
[21:46:09.394] Starting the invocation (attempt 1)
[21:46:09.442] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:46:09.446] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:46:09.447] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9021802208637496208",
"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\":\"4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\",\"title\":\"4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"78190fe72292979e57a1c812f2e575789f24f1c1\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@78190fe72292979e57a1c812f2e575789f24f1c1\",\"title\":\"78190fe72292979e57a1c812f2e575789f24f1c1\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/78190fe72292979e57a1c812f2e575789f24f1c1\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\",\"title\":\"bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\",\"title\":\"bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"be9e42e12b519001c81f6088700e4c79b23a187c\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@be9e42e12b519001c81f6088700e4c79b23a187c\",\"title\":\"be9e42e12b519001c81f6088700e4c79b23a187c\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/be9e42e12b519001c81f6088700e4c79b23a187c\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\",\"title\":\"78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"c61dc08322e854d9ac9424a8e078aa674745227c\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c61dc08322e854d9ac9424a8e078aa674745227c\",\"title\":\"c61dc08322e854d9ac9424a8e078aa674745227c\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/c61dc08322e854d9ac9424a8e078aa674745227c\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f8e4644a152494778755d8b7d132717830cfc649\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f8e4644a152494778755d8b7d132717830cfc649\",\"title\":\"f8e4644a152494778755d8b7d132717830cfc649\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/f8e4644a152494778755d8b7d132717830cfc649\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f8e4644a152494778755d8b7d132717830cfc649\"}}",
"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:9021802208637496208",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/f8e4644a152494778755d8b7d132717830cfc649",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f8e4644a152494778755d8b7d132717830cfc649",
"gitiles_ref:refs/heads/master"
]
}
[21:46:10.546] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1612302369538334",
"id": "8856372146518048832",
"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\": \"4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\", \"title\": \"4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"78190fe72292979e57a1c812f2e575789f24f1c1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@78190fe72292979e57a1c812f2e575789f24f1c1\", \"title\": \"78190fe72292979e57a1c812f2e575789f24f1c1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/78190fe72292979e57a1c812f2e575789f24f1c1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\", \"title\": \"bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\", \"title\": \"bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"be9e42e12b519001c81f6088700e4c79b23a187c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@be9e42e12b519001c81f6088700e4c79b23a187c\", \"title\": \"be9e42e12b519001c81f6088700e4c79b23a187c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/be9e42e12b519001c81f6088700e4c79b23a187c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\", \"title\": \"78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c61dc08322e854d9ac9424a8e078aa674745227c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c61dc08322e854d9ac9424a8e078aa674745227c\", \"title\": \"c61dc08322e854d9ac9424a8e078aa674745227c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c61dc08322e854d9ac9424a8e078aa674745227c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8e4644a152494778755d8b7d132717830cfc649\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f8e4644a152494778755d8b7d132717830cfc649\", \"title\": \"f8e4644a152494778755d8b7d132717830cfc649\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f8e4644a152494778755d8b7d132717830cfc649\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8e4644a152494778755d8b7d132717830cfc649\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612302370380208",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18500",
"builder:infra-continuous-win10-64",
"buildset:commit/git/f8e4644a152494778755d8b7d132717830cfc649",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f8e4644a152494778755d8b7d132717830cfc649",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021802208637496208",
"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/8856372146518048832/+/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": "1612302370380314",
"url": "https://luci-milo-dev.appspot.com/b/8856372146518048832",
"utcnow_ts": "1612302370535952"
}
}
[21:46:10.546] Task URL: https://luci-milo-dev.appspot.com/b/8856372146518048832
[21:46:10.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:2:0) after 1m0s
[21:47:10.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:2:0)
[21:47:10.603] Timer tick, asking Buildbucket for the build status
[21:47:10.721] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:10.721] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:3:0) after 1m0s
[21:48:10.802] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:3:0)
[21:48:10.802] Timer tick, asking Buildbucket for the build status
[21:48:10.901] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:10.901] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:4:0) after 1m0s
[21:49:11.009] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:4:0)
[21:49:11.009] Timer tick, asking Buildbucket for the build status
[21:49:11.113] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:11.113] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:5:0) after 1m0s
[21:50:11.143] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:5:0)
[21:50:11.143] Timer tick, asking Buildbucket for the build status
[21:50:11.243] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:11.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:6:0) after 1m0s
[21:51:11.318] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:6:0)
[21:51:11.318] Timer tick, asking Buildbucket for the build status
[21:51:11.420] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:11.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:7:0) after 1m0s
[21:52:11.510] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:7:0)
[21:52:11.510] Timer tick, asking Buildbucket for the build status
[21:52:11.612] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:11.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:8:0) after 1m0s
[21:53:11.635] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:8:0)
[21:53:11.635] Timer tick, asking Buildbucket for the build status
[21:53:11.732] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:11.732] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:9:0) after 1m0s
[21:54:11.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:9:0)
[21:54:11.799] Timer tick, asking Buildbucket for the build status
[21:54:11.920] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:11.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:10:0) after 1m0s
[21:55:12.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:10:0)
[21:55:12.008] Timer tick, asking Buildbucket for the build status
[21:55:12.112] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:12.113] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:11:0) after 1m0s
[21:56:12.139] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:11:0)
[21:56:12.139] Timer tick, asking Buildbucket for the build status
[21:56:12.322] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:12.322] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:12:0) after 1m0s
[21:57:12.349] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:12:0)
[21:57:12.349] Timer tick, asking Buildbucket for the build status
[21:57:12.459] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:12.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:13:0) after 1m0s
[21:58:12.504] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:13:0)
[21:58:12.504] Timer tick, asking Buildbucket for the build status
[21:58:12.638] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:12.638] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:14:0) after 1m0s
[21:59:12.718] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:14:0)
[21:59:12.718] Timer tick, asking Buildbucket for the build status
[21:59:12.829] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:12.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:15:0) after 1m0s
[22:00:12.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:15:0)
[22:00:12.898] Timer tick, asking Buildbucket for the build status
[22:00:13.014] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:13.014] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:16:0) after 1m0s
[22:01:13.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:16:0)
[22:01:13.114] Timer tick, asking Buildbucket for the build status
[22:01:13.238] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:13.238] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:17:0) after 1m0s
[22:02:13.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:17:0)
[22:02:13.300] Timer tick, asking Buildbucket for the build status
[22:02:13.448] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:13.448] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:18:0) after 1m0s
[22:03:13.473] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:18:0)
[22:03:13.473] Timer tick, asking Buildbucket for the build status
[22:03:13.657] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:13.657] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:19:0) after 1m0s
[22:04:13.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:19:0)
[22:04:13.708] Timer tick, asking Buildbucket for the build status
[22:04:13.828] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:13.828] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:20:0) after 1m0s
[22:05:13.902] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:20:0)
[22:05:13.902] Timer tick, asking Buildbucket for the build status
[22:05:14.002] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:14.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:21:0) after 1m0s
[22:06:14.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:21:0)
[22:06:14.114] Timer tick, asking Buildbucket for the build status
[22:06:14.238] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:14.238] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:22:0) after 1m0s
[22:07:14.258] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:22:0)
[22:07:14.258] Timer tick, asking Buildbucket for the build status
[22:07:14.365] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:14.365] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:23:0) after 1m0s
[22:08:14.388] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:23:0)
[22:08:14.388] Timer tick, asking Buildbucket for the build status
[22:08:14.499] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:14.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:24:0) after 1m0s
[22:09:14.594] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:24:0)
[22:09:14.594] Timer tick, asking Buildbucket for the build status
[22:09:14.696] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:14.696] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:25:0) after 1m0s
[22:10:14.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:25:0)
[22:10:14.791] Timer tick, asking Buildbucket for the build status
[22:10:14.888] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:14.888] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:26:0) after 1m0s
[22:11:14.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:26:0)
[22:11:14.982] Timer tick, asking Buildbucket for the build status
[22:11:15.099] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:15.099] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:27:0) after 1m0s
[22:12:15.182] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:27:0)
[22:12:15.182] Timer tick, asking Buildbucket for the build status
[22:12:15.285] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:15.285] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:28:0) after 1m0s
[22:13:15.323] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:28:0)
[22:13:15.324] Timer tick, asking Buildbucket for the build status
[22:13:15.451] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:15.451] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:29:0) after 1m0s
[22:14:15.490] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:29:0)
[22:14:15.490] Timer tick, asking Buildbucket for the build status
[22:14:15.620] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:15.620] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:30:0) after 1m0s
[22:15:15.652] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:30:0)
[22:15:15.652] Timer tick, asking Buildbucket for the build status
[22:15:15.850] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:15.850] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:31:0) after 1m0s
[22:16:15.887] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:31:0)
[22:16:15.887] Timer tick, asking Buildbucket for the build status
[22:16:15.986] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:15.986] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:32:0) after 1m0s
[22:17:16.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:32:0)
[22:17:16.109] Timer tick, asking Buildbucket for the build status
[22:17:16.202] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:16.202] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:33:0) after 1m0s
[22:18:16.299] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:33:0)
[22:18:16.299] Timer tick, asking Buildbucket for the build status
[22:18:16.410] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:16.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:34:0) after 1m0s
[22:19:16.433] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:34:0)
[22:19:16.433] Timer tick, asking Buildbucket for the build status
[22:19:16.518] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:16.519] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:35:0) after 1m0s
[22:20:16.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:35:0)
[22:20:16.588] Timer tick, asking Buildbucket for the build status
[22:20:16.703] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:16.703] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:36:0) after 1m0s
[22:21:16.792] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:36:0)
[22:21:16.792] Timer tick, asking Buildbucket for the build status
[22:21:16.895] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:16.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:37:0) after 1m0s
[22:22:16.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:37:0)
[22:22:16.982] Timer tick, asking Buildbucket for the build status
[22:22:17.134] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:17.134] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:38:0) after 1m0s
[22:23:17.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:38:0)
[22:23:17.198] Timer tick, asking Buildbucket for the build status
[22:23:17.346] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:17.346] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:39:0) after 1m0s
[22:24:17.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:39:0)
[22:24:17.394] Timer tick, asking Buildbucket for the build status
[22:24:17.497] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:17.497] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:40:0) after 1m0s
[22:25:17.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:40:0)
[22:25:17.593] Timer tick, asking Buildbucket for the build status
[22:25:17.786] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:17.786] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:41:0) after 1m0s
[22:26:17.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:41:0)
[22:26:17.810] Timer tick, asking Buildbucket for the build status
[22:26:17.896] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:17.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:42:0) after 1m0s
[22:27:17.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:42:0)
[22:27:17.983] Timer tick, asking Buildbucket for the build status
[22:27:18.084] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:18.084] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:43:0) after 1m0s
[22:28:18.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:43:0)
[22:28:18.201] Timer tick, asking Buildbucket for the build status
[22:28:18.303] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:18.303] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:44:0) after 1m0s
[22:29:18.381] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:44:0)
[22:29:18.382] Timer tick, asking Buildbucket for the build status
[22:29:18.481] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:18.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:45:0) after 1m0s
[22:30:18.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:45:0)
[22:30:18.588] Timer tick, asking Buildbucket for the build status
[22:30:18.752] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:18.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:46:0) after 1m0s
[22:31:18.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:46:0)
[22:31:18.776] Timer tick, asking Buildbucket for the build status
[22:31:18.890] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:18.890] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:47:0) after 1m0s
[22:32:18.984] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:47:0)
[22:32:18.984] Timer tick, asking Buildbucket for the build status
[22:32:19.097] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:19.097] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:48:0) after 1m0s
[22:33:19.211] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:48:0)
[22:33:19.211] Timer tick, asking Buildbucket for the build status
[22:33:19.325] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:19.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:49:0) after 1m0s
[22:34:19.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:49:0)
[22:34:19.389] Timer tick, asking Buildbucket for the build status
[22:34:19.541] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:19.541] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:50:0) after 1m0s
[22:35:19.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:50:0)
[22:35:19.597] Timer tick, asking Buildbucket for the build status
[22:35:19.714] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:19.714] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:51:0) after 1m0s
[22:36:19.779] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:51:0)
[22:36:19.779] Timer tick, asking Buildbucket for the build status
[22:36:19.900] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:19.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:52:0) after 1m0s
[22:37:19.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:52:0)
[22:37:19.997] Timer tick, asking Buildbucket for the build status
[22:37:20.144] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:20.144] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:53:0) after 1m0s
[22:38:20.236] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:53:0)
[22:38:20.236] Timer tick, asking Buildbucket for the build status
[22:38:20.338] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:20.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:54:0) after 1m0s
[22:39:20.381] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:54:0)
[22:39:20.381] Timer tick, asking Buildbucket for the build status
[22:39:20.489] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:20.489] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:55:0) after 1m0s
[22:40:20.520] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:55:0)
[22:40:20.520] Timer tick, asking Buildbucket for the build status
[22:40:20.680] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:20.680] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:56:0) after 1m0s
[22:41:20.782] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:56:0)
[22:41:20.782] Timer tick, asking Buildbucket for the build status
[22:41:20.911] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:20.911] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:57:0) after 1m0s
[22:42:20.935] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:57:0)
[22:42:20.935] Timer tick, asking Buildbucket for the build status
[22:42:21.054] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:21.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:58:0) after 1m0s
[22:43:21.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:58:0)
[22:43:21.079] Timer tick, asking Buildbucket for the build status
[22:43:21.209] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:21.209] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:59:0) after 1m0s
[22:44:21.279] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:59:0)
[22:44:21.279] Timer tick, asking Buildbucket for the build status
[22:44:21.369] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:21.369] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:60:0) after 1m0s
[22:45:21.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:60:0)
[22:45:21.557] Timer tick, asking Buildbucket for the build status
[22:45:21.689] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:21.689] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:61:0) after 1m0s
[22:46:21.788] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:61:0)
[22:46:21.788] Timer tick, asking Buildbucket for the build status
[22:46:22.001] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:22.001] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:62:0) after 1m0s
[22:47:22.031] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:62:0)
[22:47:22.031] Timer tick, asking Buildbucket for the build status
[22:47:22.149] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:22.149] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:63:0) after 1m0s
[22:48:22.187] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:63:0)
[22:48:22.187] Timer tick, asking Buildbucket for the build status
[22:48:22.343] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:22.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:64:0) after 1m0s
[22:49:22.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:64:0)
[22:49:22.389] Timer tick, asking Buildbucket for the build status
[22:49:22.520] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:22.520] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:65:0) after 1m0s
[22:50:22.587] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:65:0)
[22:50:22.587] Timer tick, asking Buildbucket for the build status
[22:50:22.713] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:22.713] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:66:0) after 1m0s
[22:51:22.780] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:66:0)
[22:51:22.780] Timer tick, asking Buildbucket for the build status
[22:51:22.900] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:22.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:67:0) after 1m0s
[22:52:23.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:67:0)
[22:52:23.009] Timer tick, asking Buildbucket for the build status
[22:52:23.339] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:23.339] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:68:0) after 1m0s
[22:53:23.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:68:0)
[22:53:23.367] Timer tick, asking Buildbucket for the build status
[22:53:23.472] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:23.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:69:0) after 1m0s
[22:54:23.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:69:0)
[22:54:23.511] Timer tick, asking Buildbucket for the build status
[22:54:23.623] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:23.624] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:70:0) after 1m0s
[22:55:23.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:70:0)
[22:55:23.694] Timer tick, asking Buildbucket for the build status
[22:55:23.859] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:23.859] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:71:0) after 1m0s
[22:56:23.906] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:71:0)
[22:56:23.906] Timer tick, asking Buildbucket for the build status
[22:56:24.023] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:24.023] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:72:0) after 1m0s
[22:57:24.116] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:72:0)
[22:57:24.116] Timer tick, asking Buildbucket for the build status
[22:57:24.214] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:24.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:73:0) after 1m0s
[22:58:24.346] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:73:0)
[22:58:24.346] Timer tick, asking Buildbucket for the build status
[22:58:24.457] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:24.457] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:74:0) after 1m0s
[22:59:24.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:74:0)
[22:59:24.496] Timer tick, asking Buildbucket for the build status
[22:59:24.630] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:24.630] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:75:0) after 1m0s
[23:00:24.675] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:75:0)
[23:00:24.675] Timer tick, asking Buildbucket for the build status
[23:00:25.002] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:25.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:76:0) after 1m0s
[23:01:25.088] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:76:0)
[23:01:25.088] Timer tick, asking Buildbucket for the build status
[23:01:25.237] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:25.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:77:0) after 1m0s
[23:02:25.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:77:0)
[23:02:25.294] Timer tick, asking Buildbucket for the build status
[23:02:25.416] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:25.416] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:78:0) after 1m0s
[23:03:25.483] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:78:0)
[23:03:25.483] Timer tick, asking Buildbucket for the build status
[23:03:25.616] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:25.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:79:0) after 1m0s
[23:04:25.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:79:0)
[23:04:25.682] Timer tick, asking Buildbucket for the build status
[23:04:25.779] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:25.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:80:0) after 1m0s
[23:05:25.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:80:0)
[23:05:25.896] Timer tick, asking Buildbucket for the build status
[23:05:26.015] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:26.015] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:81:0) after 1m0s
[23:06:26.044] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:81:0)
[23:06:26.044] Timer tick, asking Buildbucket for the build status
[23:06:26.187] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:26.187] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:82:0) after 1m0s
[23:07:26.279] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:82:0)
[23:07:26.279] Timer tick, asking Buildbucket for the build status
[23:07:26.408] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:26.408] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:83:0) after 1m0s
[23:08:26.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:83:0)
[23:08:26.479] Timer tick, asking Buildbucket for the build status
[23:08:26.594] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:26.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:84:0) after 1m0s
[23:09:26.712] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:84:0)
[23:09:26.712] Timer tick, asking Buildbucket for the build status
[23:09:26.837] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:26.837] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:85:0) after 1m0s
[23:10:26.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:85:0)
[23:10:26.881] Timer tick, asking Buildbucket for the build status
[23:10:27.016] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:27.016] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:86:0) after 1m0s
[23:11:27.090] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:86:0)
[23:11:27.090] Timer tick, asking Buildbucket for the build status
[23:11:27.224] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:27.224] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:87:0) after 1m0s
[23:12:27.260] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:87:0)
[23:12:27.260] Timer tick, asking Buildbucket for the build status
[23:12:27.367] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:27.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:88:0) after 1m0s
[23:13:27.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:88:0)
[23:13:27.389] Timer tick, asking Buildbucket for the build status
[23:13:27.581] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:27.581] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:89:0) after 1m0s
[23:14:27.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:89:0)
[23:14:27.680] Timer tick, asking Buildbucket for the build status
[23:14:27.783] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:27.783] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:90:0) after 1m0s
[23:15:27.893] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:90:0)
[23:15:27.893] Timer tick, asking Buildbucket for the build status
[23:15:28.066] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:28.066] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:91:0) after 1m0s
[23:16:28.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:91:0)
[23:16:28.095] Timer tick, asking Buildbucket for the build status
[23:16:28.229] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:28.229] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:92:0) after 1m0s
[23:17:28.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:92:0)
[23:17:28.300] Timer tick, asking Buildbucket for the build status
[23:17:28.409] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:28.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:93:0) after 1m0s
[23:18:28.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:93:0)
[23:18:28.488] Timer tick, asking Buildbucket for the build status
[23:18:28.678] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:28.678] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:94:0) after 1m0s
[23:19:28.780] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:94:0)
[23:19:28.780] Timer tick, asking Buildbucket for the build status
[23:19:28.877] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:28.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:95:0) after 1m0s
[23:20:28.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:95:0)
[23:20:28.902] Timer tick, asking Buildbucket for the build status
[23:20:29.033] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:29.033] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:96:0) after 1m0s
[23:21:29.093] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:96:0)
[23:21:29.093] Timer tick, asking Buildbucket for the build status
[23:21:29.203] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:29.203] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:97:0) after 1m0s
[23:22:29.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:97:0)
[23:22:29.294] Timer tick, asking Buildbucket for the build status
[23:22:29.458] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:29.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:98:0) after 1m0s
[23:23:29.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:98:0)
[23:23:29.597] Timer tick, asking Buildbucket for the build status
[23:23:29.691] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:29.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:99:0) after 1m0s
[23:24:29.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:99:0)
[23:24:29.784] Timer tick, asking Buildbucket for the build status
[23:24:29.887] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:29.887] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:100:0) after 1m0s
[23:25:29.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:100:0)
[23:25:29.994] Timer tick, asking Buildbucket for the build status
[23:25:30.138] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:30.138] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:101:0) after 1m0s
[23:26:30.199] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:101:0)
[23:26:30.199] Timer tick, asking Buildbucket for the build status
[23:26:30.346] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:30.346] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:102:0) after 1m0s
[23:27:30.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:102:0)
[23:27:30.385] Timer tick, asking Buildbucket for the build status
[23:27:30.474] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:30.475] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:103:0) after 1m0s
[23:28:30.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:103:0)
[23:28:30.585] Timer tick, asking Buildbucket for the build status
[23:28:30.677] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:30.677] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:104:0) after 1m0s
[23:29:30.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:104:0)
[23:29:30.722] Timer tick, asking Buildbucket for the build status
[23:29:30.831] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:30.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:105:0) after 1m0s
[23:30:30.880] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:105:0)
[23:30:30.880] Timer tick, asking Buildbucket for the build status
[23:30:30.998] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:30.998] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:106:0) after 1m0s
[23:31:31.088] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:106:0)
[23:31:31.089] Timer tick, asking Buildbucket for the build status
[23:31:31.192] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:31.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:107:0) after 1m0s
[23:32:31.283] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:107:0)
[23:32:31.283] Timer tick, asking Buildbucket for the build status
[23:32:31.392] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:31.392] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:108:0) after 1m0s
[23:33:31.417] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:108:0)
[23:33:31.417] Timer tick, asking Buildbucket for the build status
[23:33:31.536] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:31.536] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:109:0) after 1m0s
[23:34:31.601] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:109:0)
[23:34:31.601] Timer tick, asking Buildbucket for the build status
[23:34:31.725] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:31.725] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:110:0) after 1m0s
[23:35:31.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:110:0)
[23:35:31.796] Timer tick, asking Buildbucket for the build status
[23:35:31.915] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:31.915] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:111:0) after 1m0s
[23:36:31.939] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:111:0)
[23:36:31.939] Timer tick, asking Buildbucket for the build status
[23:36:32.035] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:32.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:112:0) after 1m0s
[23:37:32.093] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:112:0)
[23:37:32.093] Timer tick, asking Buildbucket for the build status
[23:37:32.191] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:32.191] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:113:0) after 1m0s
[23:38:32.225] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:113:0)
[23:38:32.225] Timer tick, asking Buildbucket for the build status
[23:38:32.339] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:32.339] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:114:0) after 1m0s
[23:39:32.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:114:0)
[23:39:32.398] Timer tick, asking Buildbucket for the build status
[23:39:32.519] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:32.519] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:115:0) after 1m0s
[23:40:32.542] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:115:0)
[23:40:32.542] Timer tick, asking Buildbucket for the build status
[23:40:32.640] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:32.640] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:116:0) after 1m0s
[23:41:32.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:116:0)
[23:41:32.698] Timer tick, asking Buildbucket for the build status
[23:41:33.022] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:33.022] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:117:0) after 1m0s
[23:42:33.091] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:117:0)
[23:42:33.091] Timer tick, asking Buildbucket for the build status
[23:42:33.223] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:33.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:118:0) after 1m0s
[23:43:33.278] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:118:0)
[23:43:33.279] Timer tick, asking Buildbucket for the build status
[23:43:33.381] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:33.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:119:0) after 1m0s
[23:44:33.408] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:119:0)
[23:44:33.408] Timer tick, asking Buildbucket for the build status
[23:44:33.509] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:33.509] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:120:0) after 1m0s
[23:45:33.582] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:120:0)
[23:45:33.582] Timer tick, asking Buildbucket for the build status
[23:45:33.681] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:33.681] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:121:0) after 1m0s
[23:46:33.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:121:0)
[23:46:33.798] Timer tick, asking Buildbucket for the build status
[23:46:33.896] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:33.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:122:0) after 1m0s
[23:47:33.929] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:122:0)
[23:47:33.929] Timer tick, asking Buildbucket for the build status
[23:47:34.026] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:34.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:123:0) after 1m0s
[23:48:34.101] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:123:0)
[23:48:34.101] Timer tick, asking Buildbucket for the build status
[23:48:34.198] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:34.198] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:124:0) after 1m0s
[23:49:34.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:124:0)
[23:49:34.298] Timer tick, asking Buildbucket for the build status
[23:49:34.447] Build 8856372146518048832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:34.447] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:125:0) after 1m0s
[23:50:00.608] Received PubSub notification, asking Buildbucket for the build status
[23:50:00.745] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:34.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:125:0)
[23:50:34.471] Timer tick, asking Buildbucket for the build status
[23:50:34.564] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:34.564] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:127:0) after 1m0s
[23:51:34.610] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:127:0)
[23:51:34.610] Timer tick, asking Buildbucket for the build status
[23:51:34.743] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:34.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:128:0) after 1m0s
[23:52:34.783] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:128:0)
[23:52:34.783] Timer tick, asking Buildbucket for the build status
[23:52:34.886] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:34.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:129:0) after 1m0s
[23:53:35.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:129:0)
[23:53:35.004] Timer tick, asking Buildbucket for the build status
[23:53:35.119] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:35.119] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:130:0) after 1m0s
[23:54:35.148] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:130:0)
[23:54:35.148] Timer tick, asking Buildbucket for the build status
[23:54:35.249] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:35.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:131:0) after 1m0s
[23:55:35.288] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:131:0)
[23:55:35.288] Timer tick, asking Buildbucket for the build status
[23:55:35.416] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:35.416] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:132:0) after 1m0s
[23:56:35.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:132:0)
[23:56:35.505] Timer tick, asking Buildbucket for the build status
[23:56:35.631] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:35.631] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:133:0) after 1m0s
[23:57:35.687] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:133:0)
[23:57:35.687] Timer tick, asking Buildbucket for the build status
[23:57:35.797] Build 8856372146518048832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:35.797] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021802208637496208:134:0) after 1m0s
[23:58:21.199] Received PubSub notification, asking Buildbucket for the build status
[23:58:21.297] Build 8856372146518048832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:58:21.297] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612310299604221",
"created_by": "project:infra",
"created_ts": "1612302369538334",
"id": "8856372146518048832",
"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\": \"4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\", \"title\": \"4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4a99c70a24c72fc2bc7e19a8ebdc9f09aa64d530\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"78190fe72292979e57a1c812f2e575789f24f1c1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@78190fe72292979e57a1c812f2e575789f24f1c1\", \"title\": \"78190fe72292979e57a1c812f2e575789f24f1c1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/78190fe72292979e57a1c812f2e575789f24f1c1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\", \"title\": \"bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/bd611a0ae6b3d8e5fccce025c3a1d2e15a3a59fc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\", \"title\": \"bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/bbb48dd5681b4e2dbf8cd6a40b2bbfc1757dbc2d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"be9e42e12b519001c81f6088700e4c79b23a187c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@be9e42e12b519001c81f6088700e4c79b23a187c\", \"title\": \"be9e42e12b519001c81f6088700e4c79b23a187c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/be9e42e12b519001c81f6088700e4c79b23a187c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\", \"title\": \"78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/78347cc1e9d57ad0bf96da641d08e9bdf5bd3433\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c61dc08322e854d9ac9424a8e078aa674745227c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c61dc08322e854d9ac9424a8e078aa674745227c\", \"title\": \"c61dc08322e854d9ac9424a8e078aa674745227c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c61dc08322e854d9ac9424a8e078aa674745227c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8e4644a152494778755d8b7d132717830cfc649\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f8e4644a152494778755d8b7d132717830cfc649\", \"title\": \"f8e4644a152494778755d8b7d132717830cfc649\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f8e4644a152494778755d8b7d132717830cfc649\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f8e4644a152494778755d8b7d132717830cfc649\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"f8e4644a152494778755d8b7d132717830cfc649\", \"got_revision_cp\": \"refs/heads/master@{#38164}\"}, \"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-ou51\"], \"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\": [\"5676-c76598c\"], \"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": "1612309797475984",
"status": "COMPLETED",
"status_changed_ts": "1612310299876578",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18500",
"builder:infra-continuous-win10-64",
"buildset:commit/git/f8e4644a152494778755d8b7d132717830cfc649",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f8e4644a152494778755d8b7d132717830cfc649",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021802208637496208",
"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/8856372146518048832/+/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:517d874fa7a6a710",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1612310299923847",
"url": "https://luci-milo-dev.appspot.com/b/8856372146518048832",
"utcnow_ts": "1612310301285939"
}
}
[23:58:21.297] Invocation finished in 2h12m13.163414652s with status SUCCEEDED