buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.infra.cron"
  builder: "Build From Tarball"
>

• publish_tarball/0 (6 years ago by chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com)

{
  "version": "79.0.3945.3"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[07:51:12.737] New invocation is queued and will start shortly
[07:51:12.737] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[07:51:14.016] Starting the invocation (attempt 1)
[07:51:14.094] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:51:14.094] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:51:14.094] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9064525908660212400",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"79.0.3945.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"dc8667cf-6536-4ebd-8ec9-a753cf77c97f\",\"title\":\"publish_tarball/0\"}]},\"version\":\"79.0.3945.3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9064525908660212400",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[07:51:15.008] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1571557874228188",
    "id": "8899095846431329488",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3945.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"dc8667cf-6536-4ebd-8ec9-a753cf77c97f\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3945.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1571557874510872",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9064525908660212400",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8899095846431329488/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_task_id:",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1571557874510983",
    "url": "https://ci.chromium.org/b/8899095846431329488",
    "utcnow_ts": "1571557874990869"
  }
}
[07:51:15.008] Task URL: https://ci.chromium.org/b/8899095846431329488
[07:51:15.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:2:0) after 1m0s
[07:52:15.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:2:0)
[07:52:15.057] Timer tick, asking Buildbucket for the build status
[07:52:15.161] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:52:15.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:3:0) after 1m0s
[07:53:15.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:3:0)
[07:53:15.226] Timer tick, asking Buildbucket for the build status
[07:53:15.598] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:53:15.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:4:0) after 1m0s
[07:54:15.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:4:0)
[07:54:15.624] Timer tick, asking Buildbucket for the build status
[07:54:15.743] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:54:15.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:5:0) after 1m0s
[07:55:15.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:5:0)
[07:55:15.732] Timer tick, asking Buildbucket for the build status
[07:55:15.936] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:15.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:6:0) after 1m0s
[07:56:15.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:6:0)
[07:56:15.992] Timer tick, asking Buildbucket for the build status
[07:56:16.230] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:16.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:7:0) after 1m0s
[07:57:16.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:7:0)
[07:57:16.299] Timer tick, asking Buildbucket for the build status
[07:57:16.524] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:57:16.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:8:0) after 1m0s
[07:58:16.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:8:0)
[07:58:16.548] Timer tick, asking Buildbucket for the build status
[07:58:16.711] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:16.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:9:0) after 1m0s
[07:59:16.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:9:0)
[07:59:16.769] Timer tick, asking Buildbucket for the build status
[07:59:16.940] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:16.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:10:0) after 1m0s
[08:00:16.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:10:0)
[08:00:16.958] Timer tick, asking Buildbucket for the build status
[08:00:17.106] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:17.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:11:0) after 1m0s
[08:01:17.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:11:0)
[08:01:17.154] Timer tick, asking Buildbucket for the build status
[08:01:17.318] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:17.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:12:0) after 1m0s
[08:02:17.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:12:0)
[08:02:17.339] Timer tick, asking Buildbucket for the build status
[08:02:17.523] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:17.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:13:0) after 1m0s
[08:03:17.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:13:0)
[08:03:17.544] Timer tick, asking Buildbucket for the build status
[08:03:19.001] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:19.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:14:0) after 1m0s
[08:04:19.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:14:0)
[08:04:19.104] Timer tick, asking Buildbucket for the build status
[08:04:20.430] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:20.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:15:0) after 1m0s
[08:05:20.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:15:0)
[08:05:20.462] Timer tick, asking Buildbucket for the build status
[08:05:20.697] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:20.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:16:0) after 1m0s
[08:06:20.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:16:0)
[08:06:20.821] Timer tick, asking Buildbucket for the build status
[08:06:21.276] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:21.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:17:0) after 1m0s
[08:07:21.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:17:0)
[08:07:21.399] Timer tick, asking Buildbucket for the build status
[08:07:21.658] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:21.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:18:0) after 1m0s
[08:08:21.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:18:0)
[08:08:21.697] Timer tick, asking Buildbucket for the build status
[08:08:21.912] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:21.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:19:0) after 1m0s
[08:09:21.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:19:0)
[08:09:21.979] Timer tick, asking Buildbucket for the build status
[08:09:22.209] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:22.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:20:0) after 1m0s
[08:10:22.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:20:0)
[08:10:22.255] Timer tick, asking Buildbucket for the build status
[08:10:22.431] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:22.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:21:0) after 1m0s
[08:11:22.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:21:0)
[08:11:22.496] Timer tick, asking Buildbucket for the build status
[08:11:23.923] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:23.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:22:0) after 1m0s
[08:12:23.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:22:0)
[08:12:23.956] Timer tick, asking Buildbucket for the build status
[08:12:24.418] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:24.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:23:0) after 1m0s
[08:13:24.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:23:0)
[08:13:24.490] Timer tick, asking Buildbucket for the build status
[08:13:24.621] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:24.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:24:0) after 1m0s
[08:14:24.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:24:0)
[08:14:24.761] Timer tick, asking Buildbucket for the build status
[08:14:25.381] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:25.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:25:0) after 1m0s
[08:15:25.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:25:0)
[08:15:25.445] Timer tick, asking Buildbucket for the build status
[08:15:25.568] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:25.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:26:0) after 1m0s
[08:16:25.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:26:0)
[08:16:25.680] Timer tick, asking Buildbucket for the build status
[08:16:26.282] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:26.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:27:0) after 1m0s
[08:17:26.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:27:0)
[08:17:26.365] Timer tick, asking Buildbucket for the build status
[08:17:30.256] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:30.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:28:0) after 1m0s
[08:18:30.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:28:0)
[08:18:30.302] Timer tick, asking Buildbucket for the build status
[08:18:30.835] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:30.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:29:0) after 1m0s
[08:19:31.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:29:0)
[08:19:31.006] Timer tick, asking Buildbucket for the build status
[08:19:31.333] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:31.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:30:0) after 1m0s
[08:20:31.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:30:0)
[08:20:31.674] Timer tick, asking Buildbucket for the build status
[08:20:31.943] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:31.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:31:0) after 1m0s
[08:21:32.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:31:0)
[08:21:32.040] Timer tick, asking Buildbucket for the build status
[08:21:32.581] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:32.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:32:0) after 1m0s
[08:22:32.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:32:0)
[08:22:32.707] Timer tick, asking Buildbucket for the build status
[08:22:32.894] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:32.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:33:0) after 1m0s
[08:23:33.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:33:0)
[08:23:33.058] Timer tick, asking Buildbucket for the build status
[08:23:33.426] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:33.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:34:0) after 1m0s
[08:24:33.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:34:0)
[08:24:33.501] Timer tick, asking Buildbucket for the build status
[08:24:33.785] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:33.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:35:0) after 1m0s
[08:25:33.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:35:0)
[08:25:33.783] Timer tick, asking Buildbucket for the build status
[08:25:34.028] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:34.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:36:0) after 1m0s
[08:26:34.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:36:0)
[08:26:34.054] Timer tick, asking Buildbucket for the build status
[08:26:34.555] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:34.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:37:0) after 1m0s
[08:27:34.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:37:0)
[08:27:34.966] Timer tick, asking Buildbucket for the build status
[08:27:36.283] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:36.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:38:0) after 1m0s
[08:28:36.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:38:0)
[08:28:36.319] Timer tick, asking Buildbucket for the build status
[08:28:36.513] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:36.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:39:0) after 1m0s
[08:29:36.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:39:0)
[08:29:36.542] Timer tick, asking Buildbucket for the build status
[08:29:36.674] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:36.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:40:0) after 1m0s
[08:30:36.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:40:0)
[08:30:36.758] Timer tick, asking Buildbucket for the build status
[08:30:36.876] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:36.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:41:0) after 1m0s
[08:31:37.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:41:0)
[08:31:37.026] Timer tick, asking Buildbucket for the build status
[08:31:37.694] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:37.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:42:0) after 1m0s
[08:32:37.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:42:0)
[08:32:37.717] Timer tick, asking Buildbucket for the build status
[08:32:37.837] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:37.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:43:0) after 1m0s
[08:33:37.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:43:0)
[08:33:37.865] Timer tick, asking Buildbucket for the build status
[08:33:38.022] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:38.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:44:0) after 1m0s
[08:34:38.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:44:0)
[08:34:38.143] Timer tick, asking Buildbucket for the build status
[08:34:38.272] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:38.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:45:0) after 1m0s
[08:35:38.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:45:0)
[08:35:38.411] Timer tick, asking Buildbucket for the build status
[08:35:38.668] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:38.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:46:0) after 1m0s
[08:36:38.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:46:0)
[08:36:38.845] Timer tick, asking Buildbucket for the build status
[08:36:39.122] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:39.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:47:0) after 1m0s
[08:37:39.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:47:0)
[08:37:39.257] Timer tick, asking Buildbucket for the build status
[08:37:39.475] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:39.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:48:0) after 1m0s
[08:38:39.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:48:0)
[08:38:39.739] Timer tick, asking Buildbucket for the build status
[08:38:39.897] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:39.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:49:0) after 1m0s
[08:39:39.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:49:0)
[08:39:39.959] Timer tick, asking Buildbucket for the build status
[08:39:40.610] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:40.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:50:0) after 1m0s
[08:40:40.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:50:0)
[08:40:40.689] Timer tick, asking Buildbucket for the build status
[08:40:41.123] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:41.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:51:0) after 1m0s
[08:41:41.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:51:0)
[08:41:41.147] Timer tick, asking Buildbucket for the build status
[08:41:41.734] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:41.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:52:0) after 1m0s
[08:42:41.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:52:0)
[08:42:41.782] Timer tick, asking Buildbucket for the build status
[08:42:42.455] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:42.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:53:0) after 1m0s
[08:43:42.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:53:0)
[08:43:42.488] Timer tick, asking Buildbucket for the build status
[08:43:42.817] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:42.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:54:0) after 1m0s
[08:44:42.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:54:0)
[08:44:42.875] Timer tick, asking Buildbucket for the build status
[08:44:43.239] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:43.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:55:0) after 1m0s
[08:45:43.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:55:0)
[08:45:43.317] Timer tick, asking Buildbucket for the build status
[08:45:43.566] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:43.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:56:0) after 1m0s
[08:46:43.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:56:0)
[08:46:43.574] Timer tick, asking Buildbucket for the build status
[08:46:43.824] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:43.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:57:0) after 1m0s
[08:47:44.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:57:0)
[08:47:44.058] Timer tick, asking Buildbucket for the build status
[08:47:45.576] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:45.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:58:0) after 1m0s
[08:48:45.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:58:0)
[08:48:45.600] Timer tick, asking Buildbucket for the build status
[08:48:45.729] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:45.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:59:0) after 1m0s
[08:49:45.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:59:0)
[08:49:45.746] Timer tick, asking Buildbucket for the build status
[08:49:47.119] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:47.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:60:0) after 1m0s
[08:50:47.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:60:0)
[08:50:47.150] Timer tick, asking Buildbucket for the build status
[08:50:47.286] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:47.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:61:0) after 1m0s
[08:51:47.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:61:0)
[08:51:47.521] Timer tick, asking Buildbucket for the build status
[08:51:48.172] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:48.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:62:0) after 1m0s
[08:52:48.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:62:0)
[08:52:48.315] Timer tick, asking Buildbucket for the build status
[08:52:49.267] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:49.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:63:0) after 1m0s
[08:53:49.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:63:0)
[08:53:49.520] Timer tick, asking Buildbucket for the build status
[08:53:49.668] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:49.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:64:0) after 1m0s
[08:54:49.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:64:0)
[08:54:49.708] Timer tick, asking Buildbucket for the build status
[08:54:49.969] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:49.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:65:0) after 1m0s
[08:55:49.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:65:0)
[08:55:49.994] Timer tick, asking Buildbucket for the build status
[08:55:50.147] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:50.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:66:0) after 1m0s
[08:56:50.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:66:0)
[08:56:50.191] Timer tick, asking Buildbucket for the build status
[08:56:50.461] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:50.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:67:0) after 1m0s
[08:57:50.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:67:0)
[08:57:50.484] Timer tick, asking Buildbucket for the build status
[08:57:50.841] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:50.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:68:0) after 1m0s
[08:58:50.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:68:0)
[08:58:50.848] Timer tick, asking Buildbucket for the build status
[08:58:51.193] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:51.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:69:0) after 1m0s
[08:59:51.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:69:0)
[08:59:51.222] Timer tick, asking Buildbucket for the build status
[08:59:51.570] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:51.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:70:0) after 1m0s
[09:00:51.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:70:0)
[09:00:51.593] Timer tick, asking Buildbucket for the build status
[09:00:52.084] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:52.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:71:0) after 1m0s
[09:01:52.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:71:0)
[09:01:52.122] Timer tick, asking Buildbucket for the build status
[09:01:52.283] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:52.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:72:0) after 1m0s
[09:02:52.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:72:0)
[09:02:52.403] Timer tick, asking Buildbucket for the build status
[09:02:54.280] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:54.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:73:0) after 1m0s
[09:03:54.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:73:0)
[09:03:54.310] Timer tick, asking Buildbucket for the build status
[09:03:54.520] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:54.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:74:0) after 1m0s
[09:04:54.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:74:0)
[09:04:54.570] Timer tick, asking Buildbucket for the build status
[09:04:54.724] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:54.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:75:0) after 1m0s
[09:05:54.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:75:0)
[09:05:54.804] Timer tick, asking Buildbucket for the build status
[09:05:56.191] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:56.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:76:0) after 1m0s
[09:06:56.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:76:0)
[09:06:56.335] Timer tick, asking Buildbucket for the build status
[09:06:58.345] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:58.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:77:0) after 1m0s
[09:07:58.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:77:0)
[09:07:58.396] Timer tick, asking Buildbucket for the build status
[09:08:00.913] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:00.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:78:0) after 1m0s
[09:09:00.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:78:0)
[09:09:00.941] Timer tick, asking Buildbucket for the build status
[09:09:01.247] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:01.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:79:0) after 1m0s
[09:10:01.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:79:0)
[09:10:01.271] Timer tick, asking Buildbucket for the build status
[09:10:01.406] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:01.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:80:0) after 1m0s
[09:11:01.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:80:0)
[09:11:01.420] Timer tick, asking Buildbucket for the build status
[09:11:02.107] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:02.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:81:0) after 1m0s
[09:12:02.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:81:0)
[09:12:02.239] Timer tick, asking Buildbucket for the build status
[09:12:02.403] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:02.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:82:0) after 1m0s
[09:13:02.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:82:0)
[09:13:02.441] Timer tick, asking Buildbucket for the build status
[09:13:02.640] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:02.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:83:0) after 1m0s
[09:14:02.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:83:0)
[09:14:02.730] Timer tick, asking Buildbucket for the build status
[09:14:02.897] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:02.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:84:0) after 1m0s
[09:15:03.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:84:0)
[09:15:03.012] Timer tick, asking Buildbucket for the build status
[09:15:04.395] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:04.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:85:0) after 1m0s
[09:16:04.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:85:0)
[09:16:04.436] Timer tick, asking Buildbucket for the build status
[09:16:04.838] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:04.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:86:0) after 1m0s
[09:17:04.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:86:0)
[09:17:04.931] Timer tick, asking Buildbucket for the build status
[09:17:05.253] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:05.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:87:0) after 1m0s
[09:18:05.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:87:0)
[09:18:05.280] Timer tick, asking Buildbucket for the build status
[09:18:06.104] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:06.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:88:0) after 1m0s
[09:19:06.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:88:0)
[09:19:06.173] Timer tick, asking Buildbucket for the build status
[09:19:06.488] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:06.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:89:0) after 1m0s
[09:20:06.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:89:0)
[09:20:06.517] Timer tick, asking Buildbucket for the build status
[09:20:08.284] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:08.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:90:0) after 1m0s
[09:21:08.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:90:0)
[09:21:08.405] Timer tick, asking Buildbucket for the build status
[09:21:08.555] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:08.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:91:0) after 1m0s
[09:22:09.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:91:0)
[09:22:09.313] Timer tick, asking Buildbucket for the build status
[09:22:09.441] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:09.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:92:0) after 1m0s
[09:23:09.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:92:0)
[09:23:09.465] Timer tick, asking Buildbucket for the build status
[09:23:09.684] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:09.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:93:0) after 1m0s
[09:24:09.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:93:0)
[09:24:09.815] Timer tick, asking Buildbucket for the build status
[09:24:09.946] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:09.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:94:0) after 1m0s
[09:25:09.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:94:0)
[09:25:09.992] Timer tick, asking Buildbucket for the build status
[09:25:10.129] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:10.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:95:0) after 1m0s
[09:26:10.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:95:0)
[09:26:10.162] Timer tick, asking Buildbucket for the build status
[09:26:11.183] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:11.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:96:0) after 1m0s
[09:27:11.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:96:0)
[09:27:11.253] Timer tick, asking Buildbucket for the build status
[09:27:11.786] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:11.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:97:0) after 1m0s
[09:28:11.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:97:0)
[09:28:11.906] Timer tick, asking Buildbucket for the build status
[09:28:12.018] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:12.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:98:0) after 1m0s
[09:29:12.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:98:0)
[09:29:12.172] Timer tick, asking Buildbucket for the build status
[09:29:12.308] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:12.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:99:0) after 1m0s
[09:30:12.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:99:0)
[09:30:12.336] Timer tick, asking Buildbucket for the build status
[09:30:12.568] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:12.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:100:0) after 1m0s
[09:31:12.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:100:0)
[09:31:12.611] Timer tick, asking Buildbucket for the build status
[09:31:12.728] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:12.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:101:0) after 1m0s
[09:32:12.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:101:0)
[09:32:12.837] Timer tick, asking Buildbucket for the build status
[09:32:13.086] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:13.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:102:0) after 1m0s
[09:33:13.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:102:0)
[09:33:13.284] Timer tick, asking Buildbucket for the build status
[09:33:14.271] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:14.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:103:0) after 1m0s
[09:34:14.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:103:0)
[09:34:14.540] Timer tick, asking Buildbucket for the build status
[09:34:14.675] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:14.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:104:0) after 1m0s
[09:35:14.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:104:0)
[09:35:14.961] Timer tick, asking Buildbucket for the build status
[09:35:15.464] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:15.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:105:0) after 1m0s
[09:36:15.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:105:0)
[09:36:15.501] Timer tick, asking Buildbucket for the build status
[09:36:16.192] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:16.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:106:0) after 1m0s
[09:37:16.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:106:0)
[09:37:16.223] Timer tick, asking Buildbucket for the build status
[09:37:16.329] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:16.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:107:0) after 1m0s
[09:38:16.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:107:0)
[09:38:16.486] Timer tick, asking Buildbucket for the build status
[09:38:16.604] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:16.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:108:0) after 1m0s
[09:39:16.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:108:0)
[09:39:16.659] Timer tick, asking Buildbucket for the build status
[09:39:16.916] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:16.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:109:0) after 1m0s
[09:40:16.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:109:0)
[09:40:16.945] Timer tick, asking Buildbucket for the build status
[09:40:17.124] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:17.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:110:0) after 1m0s
[09:41:17.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:110:0)
[09:41:17.149] Timer tick, asking Buildbucket for the build status
[09:41:17.263] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:17.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:111:0) after 1m0s
[09:42:17.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:111:0)
[09:42:17.361] Timer tick, asking Buildbucket for the build status
[09:42:17.992] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:17.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:112:0) after 1m0s
[09:43:17.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:112:0)
[09:43:17.982] Timer tick, asking Buildbucket for the build status
[09:43:18.341] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:18.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:113:0) after 1m0s
[09:44:18.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:113:0)
[09:44:18.384] Timer tick, asking Buildbucket for the build status
[09:44:18.612] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:18.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:114:0) after 1m0s
[09:45:18.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:114:0)
[09:45:18.757] Timer tick, asking Buildbucket for the build status
[09:45:19.761] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:19.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:115:0) after 1m0s
[09:46:19.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:115:0)
[09:46:19.863] Timer tick, asking Buildbucket for the build status
[09:46:20.019] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:20.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:116:0) after 1m0s
[09:47:20.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:116:0)
[09:47:20.264] Timer tick, asking Buildbucket for the build status
[09:47:23.117] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:23.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:117:0) after 1m0s
[09:48:23.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:117:0)
[09:48:23.138] Timer tick, asking Buildbucket for the build status
[09:48:23.571] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:23.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:118:0) after 1m0s
[09:49:23.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:118:0)
[09:49:23.776] Timer tick, asking Buildbucket for the build status
[09:49:24.414] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:24.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:119:0) after 1m0s
[09:50:24.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:119:0)
[09:50:24.455] Timer tick, asking Buildbucket for the build status
[09:50:24.642] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:24.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:120:0) after 1m0s
[09:51:24.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:120:0)
[09:51:24.676] Timer tick, asking Buildbucket for the build status
[09:51:25.117] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:25.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:121:0) after 1m0s
[09:52:25.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:121:0)
[09:52:25.154] Timer tick, asking Buildbucket for the build status
[09:52:25.362] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:25.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:122:0) after 1m0s
[09:53:25.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:122:0)
[09:53:25.913] Timer tick, asking Buildbucket for the build status
[09:53:26.074] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:26.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:123:0) after 1m0s
[09:54:26.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:123:0)
[09:54:26.154] Timer tick, asking Buildbucket for the build status
[09:54:26.968] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:26.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:124:0) after 1m0s
[09:55:27.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:124:0)
[09:55:27.088] Timer tick, asking Buildbucket for the build status
[09:55:27.235] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:27.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:125:0) after 1m0s
[09:56:27.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:125:0)
[09:56:27.419] Timer tick, asking Buildbucket for the build status
[09:56:27.612] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:27.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:126:0) after 1m0s
[09:57:27.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:126:0)
[09:57:27.714] Timer tick, asking Buildbucket for the build status
[09:57:27.853] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:27.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:127:0) after 1m0s
[09:58:27.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:127:0)
[09:58:27.883] Timer tick, asking Buildbucket for the build status
[09:58:28.046] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:28.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:128:0) after 1m0s
[09:59:28.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:128:0)
[09:59:28.074] Timer tick, asking Buildbucket for the build status
[09:59:28.576] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:28.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:129:0) after 1m0s
[10:00:28.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:129:0)
[10:00:28.728] Timer tick, asking Buildbucket for the build status
[10:00:28.868] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:28.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:130:0) after 1m0s
[10:01:28.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:130:0)
[10:01:28.868] Timer tick, asking Buildbucket for the build status
[10:01:29.044] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:29.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:131:0) after 1m0s
[10:02:29.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:131:0)
[10:02:29.084] Timer tick, asking Buildbucket for the build status
[10:02:29.278] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:29.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:132:0) after 1m0s
[10:03:29.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:132:0)
[10:03:29.408] Timer tick, asking Buildbucket for the build status
[10:03:29.522] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:29.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:133:0) after 1m0s
[10:04:29.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:133:0)
[10:04:29.619] Timer tick, asking Buildbucket for the build status
[10:04:29.882] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:29.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:134:0) after 1m0s
[10:05:29.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:134:0)
[10:05:29.961] Timer tick, asking Buildbucket for the build status
[10:05:30.161] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:30.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:135:0) after 1m0s
[10:06:30.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:135:0)
[10:06:30.300] Timer tick, asking Buildbucket for the build status
[10:06:30.561] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:30.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:136:0) after 1m0s
[10:07:30.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:136:0)
[10:07:30.591] Timer tick, asking Buildbucket for the build status
[10:07:33.212] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:33.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:137:0) after 1m0s
[10:08:33.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:137:0)
[10:08:33.319] Timer tick, asking Buildbucket for the build status
[10:08:33.761] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:33.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:138:0) after 1m0s
[10:09:33.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:138:0)
[10:09:33.790] Timer tick, asking Buildbucket for the build status
[10:09:35.095] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:35.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:139:0) after 1m0s
[10:10:35.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:139:0)
[10:10:35.171] Timer tick, asking Buildbucket for the build status
[10:10:36.133] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:36.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:140:0) after 1m0s
[10:11:36.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:140:0)
[10:11:36.264] Timer tick, asking Buildbucket for the build status
[10:11:36.452] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:36.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:141:0) after 1m0s
[10:12:36.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:141:0)
[10:12:36.492] Timer tick, asking Buildbucket for the build status
[10:12:37.281] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:37.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:142:0) after 1m0s
[10:13:37.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:142:0)
[10:13:37.356] Timer tick, asking Buildbucket for the build status
[10:13:37.798] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:37.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:143:0) after 1m0s
[10:14:37.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:143:0)
[10:14:37.824] Timer tick, asking Buildbucket for the build status
[10:14:37.978] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:37.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:144:0) after 1m0s
[10:15:38.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:144:0)
[10:15:38.021] Timer tick, asking Buildbucket for the build status
[10:15:38.479] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:38.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:145:0) after 1m0s
[10:16:38.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:145:0)
[10:16:38.499] Timer tick, asking Buildbucket for the build status
[10:16:39.138] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:39.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:146:0) after 1m0s
[10:17:39.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:146:0)
[10:17:39.235] Timer tick, asking Buildbucket for the build status
[10:17:40.157] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:40.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:147:0) after 1m0s
[10:18:40.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:147:0)
[10:18:40.185] Timer tick, asking Buildbucket for the build status
[10:18:40.426] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:40.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:148:0) after 1m0s
[10:19:40.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:148:0)
[10:19:40.457] Timer tick, asking Buildbucket for the build status
[10:19:41.062] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:41.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:149:0) after 1m0s
[10:20:41.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:149:0)
[10:20:41.136] Timer tick, asking Buildbucket for the build status
[10:20:41.266] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:41.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:150:0) after 1m0s
[10:21:41.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:150:0)
[10:21:41.336] Timer tick, asking Buildbucket for the build status
[10:21:41.552] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:41.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:151:0) after 1m0s
[10:22:41.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:151:0)
[10:22:41.585] Timer tick, asking Buildbucket for the build status
[10:22:41.903] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:41.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:152:0) after 1m0s
[10:23:41.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:152:0)
[10:23:41.927] Timer tick, asking Buildbucket for the build status
[10:23:42.164] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:42.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:153:0) after 1m0s
[10:24:42.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:153:0)
[10:24:42.189] Timer tick, asking Buildbucket for the build status
[10:24:45.412] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:45.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:154:0) after 1m0s
[10:25:45.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:154:0)
[10:25:45.440] Timer tick, asking Buildbucket for the build status
[10:25:46.176] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:46.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:155:0) after 1m0s
[10:26:46.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:155:0)
[10:26:46.213] Timer tick, asking Buildbucket for the build status
[10:26:46.547] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:46.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:156:0) after 1m0s
[10:27:46.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:156:0)
[10:27:46.600] Timer tick, asking Buildbucket for the build status
[10:27:46.905] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:46.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:157:0) after 1m0s
[10:28:46.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:157:0)
[10:28:46.929] Timer tick, asking Buildbucket for the build status
[10:28:47.561] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:47.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:158:0) after 1m0s
[10:29:47.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:158:0)
[10:29:47.704] Timer tick, asking Buildbucket for the build status
[10:29:47.947] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:47.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:159:0) after 1m0s
[10:30:47.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:159:0)
[10:30:47.976] Timer tick, asking Buildbucket for the build status
[10:30:49.470] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:49.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:160:0) after 1m0s
[10:31:49.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:160:0)
[10:31:49.632] Timer tick, asking Buildbucket for the build status
[10:31:50.266] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:50.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:161:0) after 1m0s
[10:32:50.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:161:0)
[10:32:50.591] Timer tick, asking Buildbucket for the build status
[10:32:50.746] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:50.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:162:0) after 1m0s
[10:33:50.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:162:0)
[10:33:50.967] Timer tick, asking Buildbucket for the build status
[10:33:52.188] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:52.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:163:0) after 1m0s
[10:34:52.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:163:0)
[10:34:52.585] Timer tick, asking Buildbucket for the build status
[10:34:52.712] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:52.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:164:0) after 1m0s
[10:35:52.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:164:0)
[10:35:52.742] Timer tick, asking Buildbucket for the build status
[10:35:53.022] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:53.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:165:0) after 1m0s
[10:36:53.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:165:0)
[10:36:53.270] Timer tick, asking Buildbucket for the build status
[10:36:53.467] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:53.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:166:0) after 1m0s
[10:37:53.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:166:0)
[10:37:53.496] Timer tick, asking Buildbucket for the build status
[10:37:53.815] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:53.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:167:0) after 1m0s
[10:38:53.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:167:0)
[10:38:53.854] Timer tick, asking Buildbucket for the build status
[10:38:54.712] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:54.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:168:0) after 1m0s
[10:39:54.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:168:0)
[10:39:54.838] Timer tick, asking Buildbucket for the build status
[10:39:55.131] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:55.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:169:0) after 1m0s
[10:40:55.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:169:0)
[10:40:55.162] Timer tick, asking Buildbucket for the build status
[10:40:55.326] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:55.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:170:0) after 1m0s
[10:41:55.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:170:0)
[10:41:55.356] Timer tick, asking Buildbucket for the build status
[10:41:55.900] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:55.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:171:0) after 1m0s
[10:42:55.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:171:0)
[10:42:55.932] Timer tick, asking Buildbucket for the build status
[10:42:56.108] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:56.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:172:0) after 1m0s
[10:43:56.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:172:0)
[10:43:56.138] Timer tick, asking Buildbucket for the build status
[10:43:56.811] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:56.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:173:0) after 1m0s
[10:44:56.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:173:0)
[10:44:56.998] Timer tick, asking Buildbucket for the build status
[10:44:57.510] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:57.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:174:0) after 1m0s
[10:45:57.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:174:0)
[10:45:57.573] Timer tick, asking Buildbucket for the build status
[10:45:57.799] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:57.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:175:0) after 1m0s
[10:46:57.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:175:0)
[10:46:57.892] Timer tick, asking Buildbucket for the build status
[10:46:58.291] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:58.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:176:0) after 1m0s
[10:47:58.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:176:0)
[10:47:58.504] Timer tick, asking Buildbucket for the build status
[10:47:58.972] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:58.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:177:0) after 1m0s
[10:48:59.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:177:0)
[10:48:59.023] Timer tick, asking Buildbucket for the build status
[10:48:59.151] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:59.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:178:0) after 1m0s
[10:49:59.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:178:0)
[10:49:59.177] Timer tick, asking Buildbucket for the build status
[10:49:59.268] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:59.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:179:0) after 1m0s
[10:50:59.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:179:0)
[10:50:59.335] Timer tick, asking Buildbucket for the build status
[10:50:59.671] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:59.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:180:0) after 1m0s
[10:51:59.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:180:0)
[10:51:59.708] Timer tick, asking Buildbucket for the build status
[10:52:00.043] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:00.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:181:0) after 1m0s
[10:53:00.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:181:0)
[10:53:00.068] Timer tick, asking Buildbucket for the build status
[10:53:00.921] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:00.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:182:0) after 1m0s
[10:54:00.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:182:0)
[10:54:00.948] Timer tick, asking Buildbucket for the build status
[10:54:01.502] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:01.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:183:0) after 1m0s
[10:55:01.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:183:0)
[10:55:01.631] Timer tick, asking Buildbucket for the build status
[10:55:01.902] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:55:01.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:184:0) after 1m0s
[10:56:01.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:184:0)
[10:56:01.931] Timer tick, asking Buildbucket for the build status
[10:56:02.232] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:56:02.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:185:0) after 1m0s
[10:57:02.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:185:0)
[10:57:02.296] Timer tick, asking Buildbucket for the build status
[10:57:02.614] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:57:02.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:186:0) after 1m0s
[10:58:02.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:186:0)
[10:58:02.664] Timer tick, asking Buildbucket for the build status
[10:58:02.774] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:58:02.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:187:0) after 1m0s
[10:59:02.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:187:0)
[10:59:02.818] Timer tick, asking Buildbucket for the build status
[10:59:02.988] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:59:02.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:188:0) after 1m0s
[11:00:03.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:188:0)
[11:00:03.120] Timer tick, asking Buildbucket for the build status
[11:00:03.367] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:00:03.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:189:0) after 1m0s
[11:01:03.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:189:0)
[11:01:03.406] Timer tick, asking Buildbucket for the build status
[11:01:04.236] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:01:04.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:190:0) after 1m0s
[11:02:04.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:190:0)
[11:02:04.266] Timer tick, asking Buildbucket for the build status
[11:02:04.634] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:02:04.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:191:0) after 1m0s
[11:03:04.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:191:0)
[11:03:04.667] Timer tick, asking Buildbucket for the build status
[11:03:04.876] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:03:04.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:192:0) after 1m0s
[11:04:04.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:192:0)
[11:04:04.907] Timer tick, asking Buildbucket for the build status
[11:04:05.093] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:04:05.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:193:0) after 1m0s
[11:05:05.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:193:0)
[11:05:05.215] Timer tick, asking Buildbucket for the build status
[11:05:05.352] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:05:05.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:194:0) after 1m0s
[11:06:05.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:194:0)
[11:06:05.398] Timer tick, asking Buildbucket for the build status
[11:06:05.567] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:06:05.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:195:0) after 1m0s
[11:07:05.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:195:0)
[11:07:05.648] Timer tick, asking Buildbucket for the build status
[11:07:05.897] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:07:05.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:196:0) after 1m0s
[11:08:06.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:196:0)
[11:08:06.065] Timer tick, asking Buildbucket for the build status
[11:08:07.090] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:08:07.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:197:0) after 1m0s
[11:09:07.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:197:0)
[11:09:07.123] Timer tick, asking Buildbucket for the build status
[11:09:07.328] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:09:07.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:198:0) after 1m0s
[11:10:07.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:198:0)
[11:10:07.411] Timer tick, asking Buildbucket for the build status
[11:10:07.586] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:10:07.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:199:0) after 1m0s
[11:11:07.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:199:0)
[11:11:07.657] Timer tick, asking Buildbucket for the build status
[11:11:07.961] Build 8899095846431329488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:11:07.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:200:0) after 1m0s
[11:11:58.186] Received PubSub notification, asking Buildbucket for the build status
[11:11:58.294] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:08.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:200:0)
[11:12:08.097] Timer tick, asking Buildbucket for the build status
[11:12:08.301] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:08.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:202:0) after 1m0s
[11:13:08.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:202:0)
[11:13:08.413] Timer tick, asking Buildbucket for the build status
[11:13:08.707] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:08.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:203:0) after 1m0s
[11:14:09.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:203:0)
[11:14:09.113] Timer tick, asking Buildbucket for the build status
[11:14:09.280] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:09.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:204:0) after 1m0s
[11:15:09.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:204:0)
[11:15:09.428] Timer tick, asking Buildbucket for the build status
[11:15:10.765] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:10.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:205:0) after 1m0s
[11:16:11.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:205:0)
[11:16:11.447] Timer tick, asking Buildbucket for the build status
[11:16:11.897] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:11.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:206:0) after 1m0s
[11:17:12.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:206:0)
[11:17:12.055] Timer tick, asking Buildbucket for the build status
[11:17:12.389] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:12.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:207:0) after 1m0s
[11:18:12.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:207:0)
[11:18:12.524] Timer tick, asking Buildbucket for the build status
[11:18:12.773] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:12.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:208:0) after 1m0s
[11:19:13.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:208:0)
[11:19:13.016] Timer tick, asking Buildbucket for the build status
[11:19:13.295] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:13.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:209:0) after 1m0s
[11:20:13.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:209:0)
[11:20:13.508] Timer tick, asking Buildbucket for the build status
[11:20:15.248] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:15.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:210:0) after 1m0s
[11:21:15.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:210:0)
[11:21:15.596] Timer tick, asking Buildbucket for the build status
[11:21:15.831] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:15.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:211:0) after 1m0s
[11:22:16.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:211:0)
[11:22:16.504] Timer tick, asking Buildbucket for the build status
[11:22:16.730] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:16.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:212:0) after 1m0s
[11:23:17.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:212:0)
[11:23:17.423] Timer tick, asking Buildbucket for the build status
[11:23:17.557] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:17.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:213:0) after 1m0s
[11:24:17.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:213:0)
[11:24:17.690] Timer tick, asking Buildbucket for the build status
[11:24:18.808] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:18.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:214:0) after 1m0s
[11:25:18.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:214:0)
[11:25:18.906] Timer tick, asking Buildbucket for the build status
[11:25:20.076] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:20.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:215:0) after 1m0s
[11:26:20.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:215:0)
[11:26:20.134] Timer tick, asking Buildbucket for the build status
[11:26:20.407] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:20.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:216:0) after 1m0s
[11:27:20.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:216:0)
[11:27:20.791] Timer tick, asking Buildbucket for the build status
[11:27:20.940] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:20.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:217:0) after 1m0s
[11:28:21.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:217:0)
[11:28:21.201] Timer tick, asking Buildbucket for the build status
[11:28:21.821] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:21.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:218:0) after 1m0s
[11:29:21.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:218:0)
[11:29:21.861] Timer tick, asking Buildbucket for the build status
[11:29:21.984] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:21.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:219:0) after 1m0s
[11:30:22.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:219:0)
[11:30:22.287] Timer tick, asking Buildbucket for the build status
[11:30:22.412] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:22.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:220:0) after 1m0s
[11:31:22.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:220:0)
[11:31:22.876] Timer tick, asking Buildbucket for the build status
[11:31:24.186] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:24.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:221:0) after 1m0s
[11:32:24.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:221:0)
[11:32:24.234] Timer tick, asking Buildbucket for the build status
[11:32:24.608] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:24.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:222:0) after 1m0s
[11:33:24.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:222:0)
[11:33:24.648] Timer tick, asking Buildbucket for the build status
[11:33:25.023] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:25.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:223:0) after 1m0s
[11:34:25.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:223:0)
[11:34:25.154] Timer tick, asking Buildbucket for the build status
[11:34:25.342] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:25.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:224:0) after 1m0s
[11:35:25.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:224:0)
[11:35:25.439] Timer tick, asking Buildbucket for the build status
[11:35:25.663] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:25.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:225:0) after 1m0s
[11:36:26.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:225:0)
[11:36:26.004] Timer tick, asking Buildbucket for the build status
[11:36:26.164] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:26.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:226:0) after 1m0s
[11:37:26.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:226:0)
[11:37:26.334] Timer tick, asking Buildbucket for the build status
[11:37:27.192] Build 8899095846431329488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:27.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9064525908660212400:227:0) after 1m0s
[11:37:35.952] Received PubSub notification, asking Buildbucket for the build status
[11:37:36.023] Build 8899095846431329488: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:37:36.023] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1571571455032179",
    "created_by": "project:infra",
    "created_ts": "1571557874228188",
    "failure_reason": "BUILD_FAILURE",
    "id": "8899095846431329488",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3945.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"dc8667cf-6536-4ebd-8ec9-a753cf77c97f\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3945.3\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('Download nodejs.') (retcode: 1)\"}, \"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3945.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"dc8667cf-6536-4ebd-8ec9-a753cf77c97f\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-mrcw\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"79.0.3945.3\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-mrcw\"], \"image\": [\"chrome-xenial-19101700-60651c0b41e\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4542-cd693bf\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Download nodejs.') (retcode: 1)\"}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1571569915484329",
    "status": "COMPLETED",
    "status_changed_ts": "1571569917473801",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9064525908660212400",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8899095846431329488/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_task_id:4800f7a68b145510",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1571571455109307",
    "url": "https://ci.chromium.org/b/8899095846431329488",
    "utcnow_ts": "1571571456014878"
  }
}
[11:37:36.023] Invocation finished in 3h46m23.303864831s with status FAILED