Status: SUCCEEDED
Triggered by: -
Duration: 1 hour
Actions:

Definition (rev db79390766c74b5c78e905f2f711f95842d49a9d)

buildbucket: <
  server: "cr-buildbucket-dev.appspot.com"
  bucket: "luci.infra.ci"
  builder: "infra-continuous-win10-64"
>

Incoming triggers

Incoming properties

{
  "branch": "refs/heads/master",
  "repository": "https://chromium.googlesource.com/infra/infra",
  "revision": "e755228cf2f84cfeffa605943be42edf7a0bddb2"
}

Incoming tags

  • buildset:commit/git/e755228cf2f84cfeffa605943be42edf7a0bddb2
  • buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e755228cf2f84cfeffa605943be42edf7a0bddb2
  • gitiles_ref:refs/heads/master

Debug log (UTC)

[18:25:46.173] New invocation is queued and will start shortly
[18:25:47.253] Starting the invocation (attempt 1)
[18:25:47.343] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[18:25:47.343] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[18:25:47.344] Buildbucket request:
{
  "bucket": "luci.infra.ci",
  "client_operation_id": "9021814814595568896",
  "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\":\"0aa9fd87528729101f1293a08be73c9c9897e161\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0aa9fd87528729101f1293a08be73c9c9897e161\",\"title\":\"0aa9fd87528729101f1293a08be73c9c9897e161\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0aa9fd87528729101f1293a08be73c9c9897e161\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"e755228cf2f84cfeffa605943be42edf7a0bddb2\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e755228cf2f84cfeffa605943be42edf7a0bddb2\",\"title\":\"e755228cf2f84cfeffa605943be42edf7a0bddb2\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/e755228cf2f84cfeffa605943be42edf7a0bddb2\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"e755228cf2f84cfeffa605943be42edf7a0bddb2\"}}",
  "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:9021814814595568896",
    "scheduler_job_id:infra/infra-continuous-win10-64",
    "user_agent:luci-scheduler-dev",
    "buildset:commit/git/e755228cf2f84cfeffa605943be42edf7a0bddb2",
    "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e755228cf2f84cfeffa605943be42edf7a0bddb2",
    "gitiles_ref:refs/heads/master"
  ]
}
[18:25:48.183] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.ci",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1612290347547092",
    "id": "8856384752489318096",
    "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\": \"0aa9fd87528729101f1293a08be73c9c9897e161\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0aa9fd87528729101f1293a08be73c9c9897e161\", \"title\": \"0aa9fd87528729101f1293a08be73c9c9897e161\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0aa9fd87528729101f1293a08be73c9c9897e161\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e755228cf2f84cfeffa605943be42edf7a0bddb2\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e755228cf2f84cfeffa605943be42edf7a0bddb2\", \"title\": \"e755228cf2f84cfeffa605943be42edf7a0bddb2\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e755228cf2f84cfeffa605943be42edf7a0bddb2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e755228cf2f84cfeffa605943be42edf7a0bddb2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1612290348005667",
    "tags": [
      "build_address:luci.infra.ci/infra-continuous-win10-64/18498",
      "builder:infra-continuous-win10-64",
      "buildset:commit/git/e755228cf2f84cfeffa605943be42edf7a0bddb2",
      "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e755228cf2f84cfeffa605943be42edf7a0bddb2",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021814814595568896",
      "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/8856384752489318096/+/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": "1612290348005895",
    "url": "https://luci-milo-dev.appspot.com/b/8856384752489318096",
    "utcnow_ts": "1612290348172246"
  }
}
[18:25:48.183] Task URL: https://luci-milo-dev.appspot.com/b/8856384752489318096
[18:25:48.183] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:2:0) after 1m0s
[18:26:48.205] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:2:0)
[18:26:48.205] Timer tick, asking Buildbucket for the build status
[18:26:48.310] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:26:48.310] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:3:0) after 1m0s
[18:27:48.353] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:3:0)
[18:27:48.353] Timer tick, asking Buildbucket for the build status
[18:27:48.450] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:27:48.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:4:0) after 1m0s
[18:28:48.476] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:4:0)
[18:28:48.477] Timer tick, asking Buildbucket for the build status
[18:28:48.618] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:28:48.618] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:5:0) after 1m0s
[18:29:48.674] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:5:0)
[18:29:48.674] Timer tick, asking Buildbucket for the build status
[18:29:48.774] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:29:48.774] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:6:0) after 1m0s
[18:30:48.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:6:0)
[18:30:48.809] Timer tick, asking Buildbucket for the build status
[18:30:48.916] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:30:48.916] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:7:0) after 1m0s
[18:31:48.938] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:7:0)
[18:31:48.938] Timer tick, asking Buildbucket for the build status
[18:31:49.042] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:31:49.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:8:0) after 1m0s
[18:32:49.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:8:0)
[18:32:49.072] Timer tick, asking Buildbucket for the build status
[18:32:49.176] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:32:49.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:9:0) after 1m0s
[18:33:49.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:9:0)
[18:33:49.202] Timer tick, asking Buildbucket for the build status
[18:33:49.375] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:33:49.375] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:10:0) after 1m0s
[18:34:49.397] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:10:0)
[18:34:49.397] Timer tick, asking Buildbucket for the build status
[18:34:49.534] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:49.534] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:11:0) after 1m0s
[18:35:49.637] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:11:0)
[18:35:49.637] Timer tick, asking Buildbucket for the build status
[18:35:49.806] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:49.806] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:12:0) after 1m0s
[18:36:49.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:12:0)
[18:36:49.900] Timer tick, asking Buildbucket for the build status
[18:36:49.990] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:36:49.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:13:0) after 1m0s
[18:37:50.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:13:0)
[18:37:50.114] Timer tick, asking Buildbucket for the build status
[18:37:50.276] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:50.276] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:14:0) after 1m0s
[18:38:50.324] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:14:0)
[18:38:50.324] Timer tick, asking Buildbucket for the build status
[18:38:50.443] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:50.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:15:0) after 1m0s
[18:39:50.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:15:0)
[18:39:50.507] Timer tick, asking Buildbucket for the build status
[18:39:50.598] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:50.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:16:0) after 1m0s
[18:40:50.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:16:0)
[18:40:50.698] Timer tick, asking Buildbucket for the build status
[18:40:50.788] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:50.788] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:17:0) after 1m0s
[18:41:50.863] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:17:0)
[18:41:50.863] Timer tick, asking Buildbucket for the build status
[18:41:50.949] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:50.949] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:18:0) after 1m0s
[18:42:50.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:18:0)
[18:42:50.998] Timer tick, asking Buildbucket for the build status
[18:42:51.103] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:51.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:19:0) after 1m0s
[18:43:51.139] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:19:0)
[18:43:51.139] Timer tick, asking Buildbucket for the build status
[18:43:51.254] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:51.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:20:0) after 1m0s
[18:44:51.315] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:20:0)
[18:44:51.315] Timer tick, asking Buildbucket for the build status
[18:44:51.409] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:51.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:21:0) after 1m0s
[18:45:51.431] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:21:0)
[18:45:51.432] Timer tick, asking Buildbucket for the build status
[18:45:51.524] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:51.524] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:22:0) after 1m0s
[18:46:51.549] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:22:0)
[18:46:51.549] Timer tick, asking Buildbucket for the build status
[18:46:51.661] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:51.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:23:0) after 1m0s
[18:47:51.711] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:23:0)
[18:47:51.711] Timer tick, asking Buildbucket for the build status
[18:47:51.825] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:51.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:24:0) after 1m0s
[18:48:51.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:24:0)
[18:48:51.910] Timer tick, asking Buildbucket for the build status
[18:48:52.028] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:52.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:25:0) after 1m0s
[18:49:52.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:25:0)
[18:49:52.117] Timer tick, asking Buildbucket for the build status
[18:49:52.203] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:52.203] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:26:0) after 1m0s
[18:50:52.317] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:26:0)
[18:50:52.317] Timer tick, asking Buildbucket for the build status
[18:50:52.413] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:52.413] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:27:0) after 1m0s
[18:51:52.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:27:0)
[18:51:52.511] Timer tick, asking Buildbucket for the build status
[18:51:52.616] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:52.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:28:0) after 1m0s
[18:52:52.712] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:28:0)
[18:52:52.712] Timer tick, asking Buildbucket for the build status
[18:52:52.819] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:52.819] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:29:0) after 1m0s
[18:53:52.959] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:29:0)
[18:53:52.959] Timer tick, asking Buildbucket for the build status
[18:53:53.050] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:53.050] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:30:0) after 1m0s
[18:54:53.097] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:30:0)
[18:54:53.097] Timer tick, asking Buildbucket for the build status
[18:54:53.193] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:53.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:31:0) after 1m0s
[18:55:53.316] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:31:0)
[18:55:53.316] Timer tick, asking Buildbucket for the build status
[18:55:53.416] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:53.416] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:32:0) after 1m0s
[18:56:53.444] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:32:0)
[18:56:53.444] Timer tick, asking Buildbucket for the build status
[18:56:53.533] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:53.533] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:33:0) after 1m0s
[18:57:53.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:33:0)
[18:57:53.607] Timer tick, asking Buildbucket for the build status
[18:57:53.723] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:53.723] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:34:0) after 1m0s
[18:58:53.812] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:34:0)
[18:58:53.812] Timer tick, asking Buildbucket for the build status
[18:58:53.936] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:53.936] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:35:0) after 1m0s
[18:59:53.999] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:35:0)
[18:59:53.999] Timer tick, asking Buildbucket for the build status
[18:59:54.089] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:54.089] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:36:0) after 1m0s
[19:00:54.242] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:36:0)
[19:00:54.242] Timer tick, asking Buildbucket for the build status
[19:00:54.443] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:54.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:37:0) after 1m0s
[19:01:54.469] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:37:0)
[19:01:54.469] Timer tick, asking Buildbucket for the build status
[19:01:54.595] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:54.595] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:38:0) after 1m0s
[19:02:54.639] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:38:0)
[19:02:54.639] Timer tick, asking Buildbucket for the build status
[19:02:54.812] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:54.812] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:39:0) after 1m0s
[19:03:54.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:39:0)
[19:03:54.910] Timer tick, asking Buildbucket for the build status
[19:03:55.005] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:55.005] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:40:0) after 1m0s
[19:04:55.096] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:40:0)
[19:04:55.096] Timer tick, asking Buildbucket for the build status
[19:04:55.195] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:55.195] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:41:0) after 1m0s
[19:05:55.244] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:41:0)
[19:05:55.244] Timer tick, asking Buildbucket for the build status
[19:05:55.483] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:55.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:42:0) after 1m0s
[19:06:55.509] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:42:0)
[19:06:55.509] Timer tick, asking Buildbucket for the build status
[19:06:55.601] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:55.601] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:43:0) after 1m0s
[19:07:55.634] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:43:0)
[19:07:55.634] Timer tick, asking Buildbucket for the build status
[19:07:55.740] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:55.740] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:44:0) after 1m0s
[19:08:55.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:44:0)
[19:08:55.810] Timer tick, asking Buildbucket for the build status
[19:08:55.912] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:55.912] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:45:0) after 1m0s
[19:09:56.027] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:45:0)
[19:09:56.027] Timer tick, asking Buildbucket for the build status
[19:09:56.116] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:56.116] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:46:0) after 1m0s
[19:10:56.140] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:46:0)
[19:10:56.140] Timer tick, asking Buildbucket for the build status
[19:10:56.243] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:56.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:47:0) after 1m0s
[19:11:56.312] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:47:0)
[19:11:56.312] Timer tick, asking Buildbucket for the build status
[19:11:56.414] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:56.414] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:48:0) after 1m0s
[19:12:56.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:48:0)
[19:12:56.515] Timer tick, asking Buildbucket for the build status
[19:12:56.667] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:56.667] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:49:0) after 1m0s
[19:13:56.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:49:0)
[19:13:56.722] Timer tick, asking Buildbucket for the build status
[19:13:56.812] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:56.812] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:50:0) after 1m0s
[19:14:56.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:50:0)
[19:14:56.898] Timer tick, asking Buildbucket for the build status
[19:14:57.016] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:57.016] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:51:0) after 1m0s
[19:15:57.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:51:0)
[19:15:57.100] Timer tick, asking Buildbucket for the build status
[19:15:57.193] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:57.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:52:0) after 1m0s
[19:16:57.317] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:52:0)
[19:16:57.317] Timer tick, asking Buildbucket for the build status
[19:16:57.443] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:57.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:53:0) after 1m0s
[19:17:57.485] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:53:0)
[19:17:57.485] Timer tick, asking Buildbucket for the build status
[19:17:57.585] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:57.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:54:0) after 1m0s
[19:18:57.608] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:54:0)
[19:18:57.608] Timer tick, asking Buildbucket for the build status
[19:18:57.717] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:57.717] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:55:0) after 1m0s
[19:19:57.815] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:55:0)
[19:19:57.815] Timer tick, asking Buildbucket for the build status
[19:19:57.971] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:57.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:56:0) after 1m0s
[19:20:58.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:56:0)
[19:20:58.014] Timer tick, asking Buildbucket for the build status
[19:20:58.103] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:58.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:57:0) after 1m0s
[19:21:58.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:57:0)
[19:21:58.133] Timer tick, asking Buildbucket for the build status
[19:21:58.233] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:58.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:58:0) after 1m0s
[19:22:58.269] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:58:0)
[19:22:58.269] Timer tick, asking Buildbucket for the build status
[19:22:58.359] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:58.359] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:59:0) after 1m0s
[19:23:58.402] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:59:0)
[19:23:58.402] Timer tick, asking Buildbucket for the build status
[19:23:58.510] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:58.510] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:60:0) after 1m0s
[19:24:58.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:60:0)
[19:24:58.599] Timer tick, asking Buildbucket for the build status
[19:24:58.719] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:58.719] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:61:0) after 1m0s
[19:25:58.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:61:0)
[19:25:58.798] Timer tick, asking Buildbucket for the build status
[19:25:58.897] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:58.897] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:62:0) after 1m0s
[19:26:58.944] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:62:0)
[19:26:58.944] Timer tick, asking Buildbucket for the build status
[19:26:59.052] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:59.052] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:63:0) after 1m0s
[19:27:59.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:63:0)
[19:27:59.118] Timer tick, asking Buildbucket for the build status
[19:27:59.262] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:59.262] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:64:0) after 1m0s
[19:28:59.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:64:0)
[19:28:59.306] Timer tick, asking Buildbucket for the build status
[19:28:59.392] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:59.392] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:65:0) after 1m0s
[19:29:59.424] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:65:0)
[19:29:59.424] Timer tick, asking Buildbucket for the build status
[19:29:59.513] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:59.513] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:66:0) after 1m0s
[19:30:59.546] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:66:0)
[19:30:59.546] Timer tick, asking Buildbucket for the build status
[19:30:59.695] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:59.695] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:67:0) after 1m0s
[19:31:59.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:67:0)
[19:31:59.799] Timer tick, asking Buildbucket for the build status
[19:31:59.915] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:59.915] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:68:0) after 1m0s
[19:33:00.019] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:68:0)
[19:33:00.019] Timer tick, asking Buildbucket for the build status
[19:33:00.586] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:00.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:69:0) after 1m0s
[19:34:00.615] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:69:0)
[19:34:00.615] Timer tick, asking Buildbucket for the build status
[19:34:00.715] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:00.715] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:70:0) after 1m0s
[19:35:00.746] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:70:0)
[19:35:00.746] Timer tick, asking Buildbucket for the build status
[19:35:00.919] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:00.919] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:71:0) after 1m0s
[19:36:01.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:71:0)
[19:36:01.002] Timer tick, asking Buildbucket for the build status
[19:36:01.142] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:01.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:72:0) after 1m0s
[19:37:01.169] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:72:0)
[19:37:01.169] Timer tick, asking Buildbucket for the build status
[19:37:01.271] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:01.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:73:0) after 1m0s
[19:38:01.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:73:0)
[19:38:01.301] Timer tick, asking Buildbucket for the build status
[19:38:01.418] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:01.418] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:74:0) after 1m0s
[19:39:01.524] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:74:0)
[19:39:01.524] Timer tick, asking Buildbucket for the build status
[19:39:01.616] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:01.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:75:0) after 1m0s
[19:40:01.720] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:75:0)
[19:40:01.720] Timer tick, asking Buildbucket for the build status
[19:40:01.839] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:01.839] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:76:0) after 1m0s
[19:41:01.866] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:76:0)
[19:41:01.866] Timer tick, asking Buildbucket for the build status
[19:41:01.963] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:01.963] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:77:0) after 1m0s
[19:42:02.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:77:0)
[19:42:02.004] Timer tick, asking Buildbucket for the build status
[19:42:02.155] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:02.155] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:78:0) after 1m0s
[19:43:02.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:78:0)
[19:43:02.200] Timer tick, asking Buildbucket for the build status
[19:43:02.289] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:02.289] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:79:0) after 1m0s
[19:44:02.402] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:79:0)
[19:44:02.402] Timer tick, asking Buildbucket for the build status
[19:44:03.661] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:03.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:80:0) after 1m0s
[19:45:03.715] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:80:0)
[19:45:03.715] Timer tick, asking Buildbucket for the build status
[19:45:03.829] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:03.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:81:0) after 1m0s
[19:46:03.914] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:81:0)
[19:46:03.914] Timer tick, asking Buildbucket for the build status
[19:46:04.029] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:04.029] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:82:0) after 1m0s
[19:47:04.119] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:82:0)
[19:47:04.119] Timer tick, asking Buildbucket for the build status
[19:47:04.208] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:04.208] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:83:0) after 1m0s
[19:48:04.324] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:83:0)
[19:48:04.324] Timer tick, asking Buildbucket for the build status
[19:48:04.437] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:04.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:84:0) after 1m0s
[19:49:04.465] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:84:0)
[19:49:04.465] Timer tick, asking Buildbucket for the build status
[19:49:04.550] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:04.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:85:0) after 1m0s
[19:50:04.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:85:0)
[19:50:04.607] Timer tick, asking Buildbucket for the build status
[19:50:04.709] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:04.710] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:86:0) after 1m0s
[19:51:04.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:86:0)
[19:51:04.810] Timer tick, asking Buildbucket for the build status
[19:51:04.929] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:04.929] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:87:0) after 1m0s
[19:52:04.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:87:0)
[19:52:04.965] Timer tick, asking Buildbucket for the build status
[19:52:05.052] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:05.052] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:88:0) after 1m0s
[19:53:05.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:88:0)
[19:53:05.105] Timer tick, asking Buildbucket for the build status
[19:53:05.200] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:05.200] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:89:0) after 1m0s
[19:54:05.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:89:0)
[19:54:05.300] Timer tick, asking Buildbucket for the build status
[19:54:05.427] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:05.427] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:90:0) after 1m0s
[19:55:05.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:90:0)
[19:55:05.512] Timer tick, asking Buildbucket for the build status
[19:55:05.602] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:05.602] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:91:0) after 1m0s
[19:56:05.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:91:0)
[19:56:05.638] Timer tick, asking Buildbucket for the build status
[19:56:05.743] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:05.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:92:0) after 1m0s
[19:57:05.811] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:92:0)
[19:57:05.811] Timer tick, asking Buildbucket for the build status
[19:57:05.896] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:05.896] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:93:0) after 1m0s
[19:58:05.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:93:0)
[19:58:05.943] Timer tick, asking Buildbucket for the build status
[19:58:06.075] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:06.075] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:94:0) after 1m0s
[19:59:06.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:94:0)
[19:59:06.113] Timer tick, asking Buildbucket for the build status
[19:59:06.213] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:06.213] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:95:0) after 1m0s
[20:00:06.323] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:95:0)
[20:00:06.323] Timer tick, asking Buildbucket for the build status
[20:00:06.425] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:06.425] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:96:0) after 1m0s
[20:01:06.523] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:96:0)
[20:01:06.523] Timer tick, asking Buildbucket for the build status
[20:01:06.617] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:06.617] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:97:0) after 1m0s
[20:02:06.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:97:0)
[20:02:06.648] Timer tick, asking Buildbucket for the build status
[20:02:06.746] Build 8856384752489318096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:06.746] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:98:0) after 1m0s
[20:02:45.205] Received PubSub notification, asking Buildbucket for the build status
[20:02:45.301] Build 8856384752489318096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:06.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:98:0)
[20:03:06.809] Timer tick, asking Buildbucket for the build status
[20:03:06.918] Build 8856384752489318096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:06.918] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:100:0) after 1m0s
[20:04:07.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:100:0)
[20:04:07.005] Timer tick, asking Buildbucket for the build status
[20:04:07.124] Build 8856384752489318096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:07.124] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:101:0) after 1m0s
[20:05:07.152] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:101:0)
[20:05:07.152] Timer tick, asking Buildbucket for the build status
[20:05:07.242] Build 8856384752489318096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:07.242] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:102:0) after 1m0s
[20:06:07.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:102:0)
[20:06:07.301] Timer tick, asking Buildbucket for the build status
[20:06:07.397] Build 8856384752489318096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:07.397] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:103:0) after 1m0s
[20:07:07.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:103:0)
[20:07:07.512] Timer tick, asking Buildbucket for the build status
[20:07:07.595] Build 8856384752489318096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:07.596] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:104:0) after 1m0s
[20:08:07.623] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:104:0)
[20:08:07.623] Timer tick, asking Buildbucket for the build status
[20:08:07.721] Build 8856384752489318096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:07.721] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:105:0) after 1m0s
[20:09:07.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021814814595568896:105:0)
[20:09:07.810] Timer tick, asking Buildbucket for the build status
[20:09:07.907] Build 8856384752489318096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:09:07.907] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.ci",
    "canary_preference": "PROD",
    "completed_ts": "1612296547177066",
    "created_by": "project:infra",
    "created_ts": "1612290347547092",
    "id": "8856384752489318096",
    "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\": \"0aa9fd87528729101f1293a08be73c9c9897e161\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0aa9fd87528729101f1293a08be73c9c9897e161\", \"title\": \"0aa9fd87528729101f1293a08be73c9c9897e161\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0aa9fd87528729101f1293a08be73c9c9897e161\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e755228cf2f84cfeffa605943be42edf7a0bddb2\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e755228cf2f84cfeffa605943be42edf7a0bddb2\", \"title\": \"e755228cf2f84cfeffa605943be42edf7a0bddb2\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e755228cf2f84cfeffa605943be42edf7a0bddb2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e755228cf2f84cfeffa605943be42edf7a0bddb2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"got_revision\": \"e755228cf2f84cfeffa605943be42edf7a0bddb2\", \"got_revision_cp\": \"refs/heads/master@{#38149}\"}, \"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-9mx0\"], \"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": "1612296161947297",
    "status": "COMPLETED",
    "status_changed_ts": "1612296547541611",
    "tags": [
      "build_address:luci.infra.ci/infra-continuous-win10-64/18498",
      "builder:infra-continuous-win10-64",
      "buildset:commit/git/e755228cf2f84cfeffa605943be42edf7a0bddb2",
      "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/e755228cf2f84cfeffa605943be42edf7a0bddb2",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021814814595568896",
      "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/8856384752489318096/+/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:517ccfdcffe24410",
      "user_agent:luci-scheduler-dev"
    ],
    "updated_ts": "1612296547559204",
    "url": "https://luci-milo-dev.appspot.com/b/8856384752489318096",
    "utcnow_ts": "1612296547898226"
  }
}
[20:09:07.907] Invocation finished in 1h43m21.752665782s with status SUCCEEDED