LUCI Scheduler :: infra :: Build From Tarball

Status: FAILED

Triggered by: chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com

Duration: 3 hours

Actions:

Definition (rev ef1333c7bf2cf95d15a0ce92acf83d24bcd6c5ac)

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

Incoming triggers

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

Incoming properties

{
  "version": "78.0.3896.2"
}

Incoming tags

parent_buildername:publish_tarball
user_agent:recipe

Debug log (UTC)

[23:02:16.566] New invocation is queued and will start shortly
[23:02:16.566] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[23:02:19.849] Starting the invocation (attempt 1)
[23:02:19.900] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:02:19.900] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:02:19.900] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9069179631746366464",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"78.0.3896.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"8d48f0e0-7b15-4655-b117-bcf4b9a5ee58\",\"title\":\"publish_tarball/0\"}]},\"version\":\"78.0.3896.2\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9069179631746366464",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[23:02:20.722] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:infra",
    "created_ts": "1567119740060513",
    "id": "8903749567405000528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"78.0.3896.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"8d48f0e0-7b15-4655-b117-bcf4b9a5ee58\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"78.0.3896.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1567119740274098",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9069179631746366464",
      "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/8903749567405000528/+/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": "1567119740274194",
    "url": "https://ci.chromium.org/b/8903749567405000528",
    "utcnow_ts": "1567119740711781"
  }
}
[23:02:20.722] Task URL: https://ci.chromium.org/b/8903749567405000528
[23:02:20.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:2:0) after 1m0s
[23:03:20.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:2:0)
[23:03:20.737] Timer tick, asking Buildbucket for the build status
[23:03:21.141] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:21.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:3:0) after 1m0s
[23:04:21.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:3:0)
[23:04:21.249] Timer tick, asking Buildbucket for the build status
[23:04:22.478] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:22.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:4:0) after 1m0s
[23:05:22.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:4:0)
[23:05:22.557] Timer tick, asking Buildbucket for the build status
[23:05:22.661] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:22.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:5:0) after 1m0s
[23:06:22.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:5:0)
[23:06:22.653] Timer tick, asking Buildbucket for the build status
[23:06:22.757] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:22.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:6:0) after 1m0s
[23:07:22.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:6:0)
[23:07:22.739] Timer tick, asking Buildbucket for the build status
[23:07:23.395] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:23.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:7:0) after 1m0s
[23:08:23.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:7:0)
[23:08:23.472] Timer tick, asking Buildbucket for the build status
[23:08:23.631] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:23.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:8:0) after 1m0s
[23:09:23.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:8:0)
[23:09:23.617] Timer tick, asking Buildbucket for the build status
[23:09:23.738] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:23.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:9:0) after 1m0s
[23:10:23.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:9:0)
[23:10:23.723] Timer tick, asking Buildbucket for the build status
[23:10:23.845] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:23.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:10:0) after 1m0s
[23:11:23.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:10:0)
[23:11:23.926] Timer tick, asking Buildbucket for the build status
[23:11:24.040] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:24.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:11:0) after 1m0s
[23:12:24.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:11:0)
[23:12:24.148] Timer tick, asking Buildbucket for the build status
[23:12:24.237] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:24.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:12:0) after 1m0s
[23:13:24.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:12:0)
[23:13:24.307] Timer tick, asking Buildbucket for the build status
[23:13:24.820] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:24.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:13:0) after 1m0s
[23:14:24.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:13:0)
[23:14:24.920] Timer tick, asking Buildbucket for the build status
[23:14:25.036] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:25.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:14:0) after 1m0s
[23:15:25.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:14:0)
[23:15:25.336] Timer tick, asking Buildbucket for the build status
[23:15:25.495] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:25.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:15:0) after 1m0s
[23:16:25.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:15:0)
[23:16:25.794] Timer tick, asking Buildbucket for the build status
[23:16:26.147] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:26.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:16:0) after 1m0s
[23:17:26.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:16:0)
[23:17:26.193] Timer tick, asking Buildbucket for the build status
[23:17:26.300] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:26.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:17:0) after 1m0s
[23:18:26.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:17:0)
[23:18:26.401] Timer tick, asking Buildbucket for the build status
[23:18:26.728] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:26.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:18:0) after 1m0s
[23:19:26.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:18:0)
[23:19:26.767] Timer tick, asking Buildbucket for the build status
[23:19:26.884] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:26.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:19:0) after 1m0s
[23:20:26.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:19:0)
[23:20:26.936] Timer tick, asking Buildbucket for the build status
[23:20:27.032] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:27.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:20:0) after 1m0s
[23:21:27.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:20:0)
[23:21:27.089] Timer tick, asking Buildbucket for the build status
[23:21:27.406] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:27.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:21:0) after 1m0s
[23:22:27.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:21:0)
[23:22:27.504] Timer tick, asking Buildbucket for the build status
[23:22:27.795] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:27.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:22:0) after 1m0s
[23:23:27.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:22:0)
[23:23:27.827] Timer tick, asking Buildbucket for the build status
[23:23:27.940] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:27.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:23:0) after 1m0s
[23:24:27.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:23:0)
[23:24:27.988] Timer tick, asking Buildbucket for the build status
[23:24:28.072] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:28.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:24:0) after 1m0s
[23:25:28.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:24:0)
[23:25:28.178] Timer tick, asking Buildbucket for the build status
[23:25:28.325] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:28.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:25:0) after 1m0s
[23:26:28.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:25:0)
[23:26:28.374] Timer tick, asking Buildbucket for the build status
[23:26:28.541] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:28.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:26:0) after 1m0s
[23:27:28.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:26:0)
[23:27:28.599] Timer tick, asking Buildbucket for the build status
[23:27:28.824] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:28.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:27:0) after 1m0s
[23:28:28.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:27:0)
[23:28:28.848] Timer tick, asking Buildbucket for the build status
[23:28:28.962] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:28.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:28:0) after 1m0s
[23:29:28.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:28:0)
[23:29:28.987] Timer tick, asking Buildbucket for the build status
[23:29:29.088] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:29.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:29:0) after 1m0s
[23:30:29.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:29:0)
[23:30:29.521] Timer tick, asking Buildbucket for the build status
[23:30:29.736] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:29.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:30:0) after 1m0s
[23:31:29.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:30:0)
[23:31:29.980] Timer tick, asking Buildbucket for the build status
[23:31:30.327] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:30.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:31:0) after 1m0s
[23:32:30.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:31:0)
[23:32:30.557] Timer tick, asking Buildbucket for the build status
[23:32:30.679] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:30.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:32:0) after 1m0s
[23:33:30.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:32:0)
[23:33:30.776] Timer tick, asking Buildbucket for the build status
[23:33:30.906] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:30.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:33:0) after 1m0s
[23:34:31.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:33:0)
[23:34:31.036] Timer tick, asking Buildbucket for the build status
[23:34:31.460] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:31.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:34:0) after 1m0s
[23:35:31.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:34:0)
[23:35:31.541] Timer tick, asking Buildbucket for the build status
[23:35:31.737] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:31.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:35:0) after 1m0s
[23:36:31.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:35:0)
[23:36:31.801] Timer tick, asking Buildbucket for the build status
[23:36:31.991] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:31.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:36:0) after 1m0s
[23:37:32.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:36:0)
[23:37:32.085] Timer tick, asking Buildbucket for the build status
[23:37:32.869] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:32.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:37:0) after 1m0s
[23:38:33.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:37:0)
[23:38:33.123] Timer tick, asking Buildbucket for the build status
[23:38:33.291] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:33.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:38:0) after 1m0s
[23:39:33.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:38:0)
[23:39:33.287] Timer tick, asking Buildbucket for the build status
[23:39:33.489] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:33.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:39:0) after 1m0s
[23:40:33.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:39:0)
[23:40:33.858] Timer tick, asking Buildbucket for the build status
[23:40:34.205] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:34.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:40:0) after 1m0s
[23:41:34.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:40:0)
[23:41:34.664] Timer tick, asking Buildbucket for the build status
[23:41:34.778] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:34.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:41:0) after 1m0s
[23:42:34.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:41:0)
[23:42:34.984] Timer tick, asking Buildbucket for the build status
[23:42:35.158] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:35.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:42:0) after 1m0s
[23:43:35.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:42:0)
[23:43:35.354] Timer tick, asking Buildbucket for the build status
[23:43:35.560] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:35.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:43:0) after 1m0s
[23:44:35.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:43:0)
[23:44:35.653] Timer tick, asking Buildbucket for the build status
[23:44:35.828] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:35.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:44:0) after 1m0s
[23:45:36.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:44:0)
[23:45:36.036] Timer tick, asking Buildbucket for the build status
[23:45:36.199] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:36.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:45:0) after 1m0s
[23:46:36.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:45:0)
[23:46:36.435] Timer tick, asking Buildbucket for the build status
[23:46:36.729] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:36.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:46:0) after 1m0s
[23:47:36.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:46:0)
[23:47:36.960] Timer tick, asking Buildbucket for the build status
[23:47:39.135] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:39.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:47:0) after 1m0s
[23:48:39.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:47:0)
[23:48:39.199] Timer tick, asking Buildbucket for the build status
[23:48:39.341] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:39.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:48:0) after 1m0s
[23:49:39.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:48:0)
[23:49:39.403] Timer tick, asking Buildbucket for the build status
[23:49:39.579] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:39.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:49:0) after 1m0s
[23:50:39.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:49:0)
[23:50:39.742] Timer tick, asking Buildbucket for the build status
[23:50:39.981] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:39.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:50:0) after 1m0s
[23:51:40.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:50:0)
[23:51:40.128] Timer tick, asking Buildbucket for the build status
[23:51:40.660] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:40.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:51:0) after 1m0s
[23:52:40.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:51:0)
[23:52:40.781] Timer tick, asking Buildbucket for the build status
[23:52:40.926] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:40.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:52:0) after 1m0s
[23:53:41.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:52:0)
[23:53:41.007] Timer tick, asking Buildbucket for the build status
[23:53:41.129] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:41.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:53:0) after 1m0s
[23:54:41.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:53:0)
[23:54:41.169] Timer tick, asking Buildbucket for the build status
[23:54:41.379] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:41.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:54:0) after 1m0s
[23:55:41.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:54:0)
[23:55:41.487] Timer tick, asking Buildbucket for the build status
[23:55:41.584] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:41.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:55:0) after 1m0s
[23:56:41.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:55:0)
[23:56:41.674] Timer tick, asking Buildbucket for the build status
[23:56:41.804] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:41.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:56:0) after 1m0s
[23:57:41.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:56:0)
[23:57:41.827] Timer tick, asking Buildbucket for the build status
[23:57:42.089] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:42.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:57:0) after 1m0s
[23:58:42.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:57:0)
[23:58:42.134] Timer tick, asking Buildbucket for the build status
[23:58:42.231] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:42.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:58:0) after 1m0s
[23:59:42.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:58:0)
[23:59:42.353] Timer tick, asking Buildbucket for the build status
[23:59:42.593] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:42.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:59:0) after 1m0s
[00:00:42.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:59:0)
[00:00:42.658] Timer tick, asking Buildbucket for the build status
[00:00:42.909] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:42.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:60:0) after 1m0s
[00:01:42.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:60:0)
[00:01:42.999] Timer tick, asking Buildbucket for the build status
[00:01:43.161] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:43.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:61:0) after 1m0s
[00:02:43.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:61:0)
[00:02:43.472] Timer tick, asking Buildbucket for the build status
[00:02:43.643] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:43.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:62:0) after 1m0s
[00:03:43.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:62:0)
[00:03:43.859] Timer tick, asking Buildbucket for the build status
[00:03:43.966] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:43.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:63:0) after 1m0s
[00:04:44.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:63:0)
[00:04:44.148] Timer tick, asking Buildbucket for the build status
[00:04:44.254] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:44.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:64:0) after 1m0s
[00:05:44.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:64:0)
[00:05:44.326] Timer tick, asking Buildbucket for the build status
[00:05:44.434] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:44.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:65:0) after 1m0s
[00:06:44.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:65:0)
[00:06:44.506] Timer tick, asking Buildbucket for the build status
[00:06:44.603] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:44.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:66:0) after 1m0s
[00:07:44.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:66:0)
[00:07:44.704] Timer tick, asking Buildbucket for the build status
[00:07:44.827] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:44.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:67:0) after 1m0s
[00:08:44.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:67:0)
[00:08:44.906] Timer tick, asking Buildbucket for the build status
[00:08:45.092] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:45.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:68:0) after 1m0s
[00:09:45.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:68:0)
[00:09:45.153] Timer tick, asking Buildbucket for the build status
[00:09:48.356] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:48.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:69:0) after 1m0s
[00:10:48.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:69:0)
[00:10:48.546] Timer tick, asking Buildbucket for the build status
[00:10:48.755] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:48.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:70:0) after 1m0s
[00:11:48.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:70:0)
[00:11:48.897] Timer tick, asking Buildbucket for the build status
[00:11:49.108] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:49.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:71:0) after 1m0s
[00:12:49.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:71:0)
[00:12:49.214] Timer tick, asking Buildbucket for the build status
[00:12:49.372] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:49.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:72:0) after 1m0s
[00:13:49.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:72:0)
[00:13:49.466] Timer tick, asking Buildbucket for the build status
[00:13:49.604] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:49.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:73:0) after 1m0s
[00:14:49.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:73:0)
[00:14:49.633] Timer tick, asking Buildbucket for the build status
[00:14:49.822] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:49.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:74:0) after 1m0s
[00:15:49.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:74:0)
[00:15:49.886] Timer tick, asking Buildbucket for the build status
[00:15:50.066] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:50.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:75:0) after 1m0s
[00:16:50.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:75:0)
[00:16:50.140] Timer tick, asking Buildbucket for the build status
[00:16:50.282] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:50.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:76:0) after 1m0s
[00:17:50.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:76:0)
[00:17:50.486] Timer tick, asking Buildbucket for the build status
[00:17:50.656] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:50.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:77:0) after 1m0s
[00:18:50.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:77:0)
[00:18:50.860] Timer tick, asking Buildbucket for the build status
[00:18:51.146] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:51.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:78:0) after 1m0s
[00:19:51.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:78:0)
[00:19:51.170] Timer tick, asking Buildbucket for the build status
[00:19:51.630] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:51.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:79:0) after 1m0s
[00:20:51.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:79:0)
[00:20:51.697] Timer tick, asking Buildbucket for the build status
[00:20:51.940] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:51.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:80:0) after 1m0s
[00:21:52.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:80:0)
[00:21:52.037] Timer tick, asking Buildbucket for the build status
[00:21:52.146] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:52.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:81:0) after 1m0s
[00:22:52.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:81:0)
[00:22:52.213] Timer tick, asking Buildbucket for the build status
[00:22:52.351] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:52.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:82:0) after 1m0s
[00:23:52.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:82:0)
[00:23:52.374] Timer tick, asking Buildbucket for the build status
[00:23:52.526] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:52.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:83:0) after 1m0s
[00:24:52.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:83:0)
[00:24:52.587] Timer tick, asking Buildbucket for the build status
[00:24:52.867] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:52.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:84:0) after 1m0s
[00:25:52.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:84:0)
[00:25:52.954] Timer tick, asking Buildbucket for the build status
[00:25:53.070] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:53.070] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:85:0) after 1m0s
[00:26:53.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:85:0)
[00:26:53.164] Timer tick, asking Buildbucket for the build status
[00:26:53.274] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:53.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:86:0) after 1m0s
[00:27:53.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:86:0)
[00:27:53.388] Timer tick, asking Buildbucket for the build status
[00:27:53.539] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:53.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:87:0) after 1m0s
[00:28:53.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:87:0)
[00:28:53.604] Timer tick, asking Buildbucket for the build status
[00:28:53.731] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:53.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:88:0) after 1m0s
[00:29:53.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:88:0)
[00:29:53.798] Timer tick, asking Buildbucket for the build status
[00:29:53.918] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:53.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:89:0) after 1m0s
[00:30:53.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:89:0)
[00:30:53.995] Timer tick, asking Buildbucket for the build status
[00:30:54.322] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:54.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:90:0) after 1m0s
[00:31:54.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:90:0)
[00:31:54.379] Timer tick, asking Buildbucket for the build status
[00:31:54.512] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:54.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:91:0) after 1m0s
[00:32:54.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:91:0)
[00:32:54.548] Timer tick, asking Buildbucket for the build status
[00:32:54.783] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:54.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:92:0) after 1m0s
[00:33:54.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:92:0)
[00:33:54.892] Timer tick, asking Buildbucket for the build status
[00:33:55.088] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:55.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:93:0) after 1m0s
[00:34:55.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:93:0)
[00:34:55.112] Timer tick, asking Buildbucket for the build status
[00:34:55.857] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:55.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:94:0) after 1m0s
[00:35:55.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:94:0)
[00:35:55.883] Timer tick, asking Buildbucket for the build status
[00:35:56.281] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:56.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:95:0) after 1m0s
[00:36:56.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:95:0)
[00:36:56.306] Timer tick, asking Buildbucket for the build status
[00:36:56.568] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:56.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:96:0) after 1m0s
[00:37:56.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:96:0)
[00:37:56.595] Timer tick, asking Buildbucket for the build status
[00:37:56.699] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:56.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:97:0) after 1m0s
[00:38:56.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:97:0)
[00:38:56.875] Timer tick, asking Buildbucket for the build status
[00:38:57.049] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:57.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:98:0) after 1m0s
[00:39:57.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:98:0)
[00:39:57.182] Timer tick, asking Buildbucket for the build status
[00:39:57.288] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:57.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:99:0) after 1m0s
[00:40:57.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:99:0)
[00:40:57.376] Timer tick, asking Buildbucket for the build status
[00:40:57.513] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:57.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:100:0) after 1m0s
[00:41:57.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:100:0)
[00:41:57.596] Timer tick, asking Buildbucket for the build status
[00:41:57.712] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:57.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:101:0) after 1m0s
[00:42:57.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:101:0)
[00:42:57.781] Timer tick, asking Buildbucket for the build status
[00:42:58.129] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:58.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:102:0) after 1m0s
[00:43:58.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:102:0)
[00:43:58.153] Timer tick, asking Buildbucket for the build status
[00:43:58.308] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:58.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:103:0) after 1m0s
[00:44:58.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:103:0)
[00:44:58.365] Timer tick, asking Buildbucket for the build status
[00:44:58.626] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:58.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:104:0) after 1m0s
[00:45:58.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:104:0)
[00:45:58.667] Timer tick, asking Buildbucket for the build status
[00:45:58.787] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:58.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:105:0) after 1m0s
[00:46:58.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:105:0)
[00:46:58.802] Timer tick, asking Buildbucket for the build status
[00:46:59.012] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:59.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:106:0) after 1m0s
[00:47:59.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:106:0)
[00:47:59.196] Timer tick, asking Buildbucket for the build status
[00:47:59.381] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:59.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:107:0) after 1m0s
[00:48:59.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:107:0)
[00:48:59.654] Timer tick, asking Buildbucket for the build status
[00:48:59.921] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:59.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:108:0) after 1m0s
[00:50:00.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:108:0)
[00:50:00.225] Timer tick, asking Buildbucket for the build status
[00:50:00.930] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:00.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:109:0) after 1m0s
[00:51:01.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:109:0)
[00:51:01.059] Timer tick, asking Buildbucket for the build status
[00:51:01.260] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:01.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:110:0) after 1m0s
[00:52:01.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:110:0)
[00:52:01.522] Timer tick, asking Buildbucket for the build status
[00:52:01.828] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:01.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:111:0) after 1m0s
[00:53:01.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:111:0)
[00:53:01.905] Timer tick, asking Buildbucket for the build status
[00:53:02.009] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:02.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:112:0) after 1m0s
[00:54:02.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:112:0)
[00:54:02.003] Timer tick, asking Buildbucket for the build status
[00:54:02.160] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:02.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:113:0) after 1m0s
[00:55:02.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:113:0)
[00:55:02.211] Timer tick, asking Buildbucket for the build status
[00:55:02.533] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:02.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:114:0) after 1m0s
[00:56:02.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:114:0)
[00:56:02.573] Timer tick, asking Buildbucket for the build status
[00:56:02.673] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:02.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:115:0) after 1m0s
[00:57:02.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:115:0)
[00:57:02.748] Timer tick, asking Buildbucket for the build status
[00:57:02.881] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:02.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:116:0) after 1m0s
[00:58:02.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:116:0)
[00:58:02.937] Timer tick, asking Buildbucket for the build status
[00:58:03.218] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:03.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:117:0) after 1m0s
[00:59:03.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:117:0)
[00:59:03.240] Timer tick, asking Buildbucket for the build status
[00:59:03.498] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:03.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:118:0) after 1m0s
[01:00:03.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:118:0)
[01:00:03.565] Timer tick, asking Buildbucket for the build status
[01:00:03.803] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:03.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:119:0) after 1m0s
[01:01:04.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:119:0)
[01:01:04.068] Timer tick, asking Buildbucket for the build status
[01:01:04.527] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:04.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:120:0) after 1m0s
[01:02:04.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:120:0)
[01:02:04.786] Timer tick, asking Buildbucket for the build status
[01:02:04.928] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:04.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:121:0) after 1m0s
[01:03:05.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:121:0)
[01:03:05.145] Timer tick, asking Buildbucket for the build status
[01:03:05.273] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:05.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:122:0) after 1m0s
[01:04:05.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:122:0)
[01:04:05.463] Timer tick, asking Buildbucket for the build status
[01:04:05.584] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:05.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:123:0) after 1m0s
[01:05:05.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:123:0)
[01:05:05.728] Timer tick, asking Buildbucket for the build status
[01:05:06.084] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:06.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:124:0) after 1m0s
[01:06:06.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:124:0)
[01:06:06.250] Timer tick, asking Buildbucket for the build status
[01:06:06.425] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:06.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:125:0) after 1m0s
[01:07:06.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:125:0)
[01:07:06.489] Timer tick, asking Buildbucket for the build status
[01:07:08.504] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:08.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:126:0) after 1m0s
[01:08:08.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:126:0)
[01:08:08.655] Timer tick, asking Buildbucket for the build status
[01:08:09.098] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:09.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:127:0) after 1m0s
[01:09:09.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:127:0)
[01:09:09.141] Timer tick, asking Buildbucket for the build status
[01:09:09.508] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:09.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:128:0) after 1m0s
[01:10:09.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:128:0)
[01:10:09.562] Timer tick, asking Buildbucket for the build status
[01:10:09.686] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:09.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:129:0) after 1m0s
[01:11:09.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:129:0)
[01:11:09.758] Timer tick, asking Buildbucket for the build status
[01:11:09.907] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:09.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:130:0) after 1m0s
[01:12:10.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:130:0)
[01:12:10.088] Timer tick, asking Buildbucket for the build status
[01:12:10.232] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:10.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:131:0) after 1m0s
[01:13:10.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:131:0)
[01:13:10.248] Timer tick, asking Buildbucket for the build status
[01:13:11.840] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:11.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:132:0) after 1m0s
[01:14:11.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:132:0)
[01:14:11.940] Timer tick, asking Buildbucket for the build status
[01:14:12.291] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:12.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:133:0) after 1m0s
[01:15:12.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:133:0)
[01:15:12.288] Timer tick, asking Buildbucket for the build status
[01:15:12.472] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:12.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:134:0) after 1m0s
[01:16:12.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:134:0)
[01:16:12.459] Timer tick, asking Buildbucket for the build status
[01:16:12.637] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:12.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:135:0) after 1m0s
[01:17:12.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:135:0)
[01:17:12.686] Timer tick, asking Buildbucket for the build status
[01:17:13.839] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:13.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:136:0) after 1m0s
[01:18:13.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:136:0)
[01:18:13.888] Timer tick, asking Buildbucket for the build status
[01:18:13.997] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:13.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:137:0) after 1m0s
[01:19:14.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:137:0)
[01:19:14.194] Timer tick, asking Buildbucket for the build status
[01:19:14.457] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:14.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:138:0) after 1m0s
[01:20:14.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:138:0)
[01:20:14.770] Timer tick, asking Buildbucket for the build status
[01:20:14.943] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:14.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:139:0) after 1m0s
[01:21:15.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:139:0)
[01:21:15.087] Timer tick, asking Buildbucket for the build status
[01:21:15.485] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:15.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:140:0) after 1m0s
[01:22:15.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:140:0)
[01:22:15.511] Timer tick, asking Buildbucket for the build status
[01:22:15.856] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:15.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:141:0) after 1m0s
[01:23:15.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:141:0)
[01:23:15.955] Timer tick, asking Buildbucket for the build status
[01:23:16.234] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:16.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:142:0) after 1m0s
[01:24:16.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:142:0)
[01:24:16.312] Timer tick, asking Buildbucket for the build status
[01:24:16.471] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:16.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:143:0) after 1m0s
[01:25:16.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:143:0)
[01:25:16.567] Timer tick, asking Buildbucket for the build status
[01:25:16.752] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:16.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:144:0) after 1m0s
[01:26:16.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:144:0)
[01:26:16.810] Timer tick, asking Buildbucket for the build status
[01:26:18.728] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:18.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:145:0) after 1m0s
[01:27:18.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:145:0)
[01:27:18.757] Timer tick, asking Buildbucket for the build status
[01:27:19.015] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:19.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:146:0) after 1m0s
[01:28:19.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:146:0)
[01:28:19.059] Timer tick, asking Buildbucket for the build status
[01:28:19.429] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:19.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:147:0) after 1m0s
[01:29:19.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:147:0)
[01:29:19.449] Timer tick, asking Buildbucket for the build status
[01:29:19.606] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:19.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:148:0) after 1m0s
[01:30:19.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:148:0)
[01:30:19.696] Timer tick, asking Buildbucket for the build status
[01:30:20.044] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:20.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:149:0) after 1m0s
[01:31:20.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:149:0)
[01:31:20.079] Timer tick, asking Buildbucket for the build status
[01:31:20.377] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:20.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:150:0) after 1m0s
[01:32:20.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:150:0)
[01:32:20.426] Timer tick, asking Buildbucket for the build status
[01:32:21.682] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:21.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:151:0) after 1m0s
[01:33:21.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:151:0)
[01:33:21.747] Timer tick, asking Buildbucket for the build status
[01:33:22.054] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:22.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:152:0) after 1m0s
[01:34:22.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:152:0)
[01:34:22.110] Timer tick, asking Buildbucket for the build status
[01:34:22.262] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:22.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:153:0) after 1m0s
[01:35:22.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:153:0)
[01:35:22.449] Timer tick, asking Buildbucket for the build status
[01:35:22.608] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:22.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:154:0) after 1m0s
[01:36:22.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:154:0)
[01:36:22.723] Timer tick, asking Buildbucket for the build status
[01:36:22.982] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:22.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:155:0) after 1m0s
[01:37:23.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:155:0)
[01:37:23.078] Timer tick, asking Buildbucket for the build status
[01:37:23.278] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:23.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:156:0) after 1m0s
[01:38:23.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:156:0)
[01:38:23.304] Timer tick, asking Buildbucket for the build status
[01:38:23.623] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:23.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:157:0) after 1m0s
[01:39:23.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:157:0)
[01:39:23.694] Timer tick, asking Buildbucket for the build status
[01:39:23.890] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:23.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:158:0) after 1m0s
[01:40:24.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:158:0)
[01:40:24.055] Timer tick, asking Buildbucket for the build status
[01:40:24.177] Build 8903749567405000528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:24.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:159:0) after 1m0s
[01:40:44.137] Received PubSub notification, asking Buildbucket for the build status
[01:40:44.431] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:24.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:159:0)
[01:41:24.452] Timer tick, asking Buildbucket for the build status
[01:41:24.641] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:24.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:161:0) after 1m0s
[01:42:24.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:161:0)
[01:42:24.749] Timer tick, asking Buildbucket for the build status
[01:42:24.945] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:24.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:162:0) after 1m0s
[01:43:25.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:162:0)
[01:43:25.131] Timer tick, asking Buildbucket for the build status
[01:43:25.224] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:25.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:163:0) after 1m0s
[01:44:25.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:163:0)
[01:44:25.281] Timer tick, asking Buildbucket for the build status
[01:44:25.681] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:25.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:164:0) after 1m0s
[01:45:25.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:164:0)
[01:45:25.779] Timer tick, asking Buildbucket for the build status
[01:45:26.202] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:26.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:165:0) after 1m0s
[01:46:26.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:165:0)
[01:46:26.233] Timer tick, asking Buildbucket for the build status
[01:46:26.380] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:26.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:166:0) after 1m0s
[01:47:26.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:166:0)
[01:47:26.495] Timer tick, asking Buildbucket for the build status
[01:47:26.822] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:26.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:167:0) after 1m0s
[01:48:26.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:167:0)
[01:48:26.843] Timer tick, asking Buildbucket for the build status
[01:48:27.679] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:27.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:168:0) after 1m0s
[01:49:27.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:168:0)
[01:49:27.715] Timer tick, asking Buildbucket for the build status
[01:49:27.850] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:27.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:169:0) after 1m0s
[01:50:27.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:169:0)
[01:50:27.976] Timer tick, asking Buildbucket for the build status
[01:50:28.368] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:28.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:170:0) after 1m0s
[01:51:28.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:170:0)
[01:51:28.402] Timer tick, asking Buildbucket for the build status
[01:51:28.613] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:28.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:171:0) after 1m0s
[01:52:28.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:171:0)
[01:52:28.725] Timer tick, asking Buildbucket for the build status
[01:52:29.412] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:29.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:172:0) after 1m0s
[01:53:29.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:172:0)
[01:53:29.486] Timer tick, asking Buildbucket for the build status
[01:53:29.986] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:29.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:173:0) after 1m0s
[01:54:30.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:173:0)
[01:54:30.048] Timer tick, asking Buildbucket for the build status
[01:54:30.148] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:30.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:174:0) after 1m0s
[01:55:30.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:174:0)
[01:55:30.208] Timer tick, asking Buildbucket for the build status
[01:55:30.332] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:30.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:175:0) after 1m0s
[01:56:30.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:175:0)
[01:56:30.360] Timer tick, asking Buildbucket for the build status
[01:56:30.463] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:30.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:176:0) after 1m0s
[01:57:30.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:176:0)
[01:57:30.460] Timer tick, asking Buildbucket for the build status
[01:57:30.560] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:30.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:177:0) after 1m0s
[01:58:30.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:177:0)
[01:58:30.625] Timer tick, asking Buildbucket for the build status
[01:58:30.737] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:30.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:178:0) after 1m0s
[01:59:30.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:178:0)
[01:59:30.838] Timer tick, asking Buildbucket for the build status
[01:59:31.898] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:31.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:179:0) after 1m0s
[02:00:31.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:179:0)
[02:00:31.949] Timer tick, asking Buildbucket for the build status
[02:00:32.065] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:32.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:180:0) after 1m0s
[02:01:32.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:180:0)
[02:01:32.074] Timer tick, asking Buildbucket for the build status
[02:01:32.424] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:32.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:181:0) after 1m0s
[02:02:32.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:181:0)
[02:02:32.504] Timer tick, asking Buildbucket for the build status
[02:02:32.615] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:32.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:182:0) after 1m0s
[02:03:32.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:182:0)
[02:03:32.688] Timer tick, asking Buildbucket for the build status
[02:03:33.168] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:33.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:183:0) after 1m0s
[02:04:33.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:183:0)
[02:04:33.302] Timer tick, asking Buildbucket for the build status
[02:04:33.414] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:33.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:184:0) after 1m0s
[02:05:33.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:184:0)
[02:05:33.456] Timer tick, asking Buildbucket for the build status
[02:05:33.578] Build 8903749567405000528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:33.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:185:0) after 1m0s
[02:06:33.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069179631746366464:185:0)
[02:06:33.600] Timer tick, asking Buildbucket for the build status
[02:06:33.691] Build 8903749567405000528: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:06:33.691] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1567130793093171",
    "created_by": "project:infra",
    "created_ts": "1567119740060513",
    "failure_reason": "BUILD_FAILURE",
    "id": "8903749567405000528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"78.0.3896.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"8d48f0e0-7b15-4655-b117-bcf4b9a5ee58\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"78.0.3896.2\"}}",
    "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\": \"78.0.3896.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"8d48f0e0-7b15-4655-b117-bcf4b9a5ee58\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-cxbs\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"78.0.3896.2\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-cxbs\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"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\": [\"4397-8786906\"], \"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": "1567129243247079",
    "status": "COMPLETED",
    "status_changed_ts": "1567129243687335",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9069179631746366464",
      "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/8903749567405000528/+/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:46f86f188388d610",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1567130793141725",
    "url": "https://ci.chromium.org/b/8903749567405000528",
    "utcnow_ts": "1567130793681413"
  }
}
[02:06:33.691] Invocation finished in 3h4m17.15473316s with status FAILED