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

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

{
  "version": "85.0.4179.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[00:42:08.594] New invocation is queued and will start shortly
[00:42:08.594] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[00:42:09.698] Starting the invocation (attempt 1)
[00:42:09.740] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:42:09.740] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:42:09.740] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9042175452654291472",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4179.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"f9bd0cd2-a6e9-456b-a091-16c0147dddef\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4179.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9042175452654291472",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[00:42:10.955] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1592872929843106",
    "id": "8876745390674964384",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4179.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f9bd0cd2-a6e9-456b-a091-16c0147dddef\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4179.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1592872930154455",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042175452654291472",
      "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/8876745390674964384/+/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": "1592872930155252",
    "url": "https://ci.chromium.org/b/8876745390674964384",
    "utcnow_ts": "1592872930886270"
  }
}
[00:42:10.955] Task URL: https://ci.chromium.org/b/8876745390674964384
[00:42:10.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:2:0) after 1m0s
[00:42:28.756] Received PubSub notification, asking Buildbucket for the build status
[00:42:28.841] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:10.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:2:0)
[00:43:10.978] Timer tick, asking Buildbucket for the build status
[00:43:11.155] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:11.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:4:0) after 1m0s
[00:44:11.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:4:0)
[00:44:11.225] Timer tick, asking Buildbucket for the build status
[00:44:11.353] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:11.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:5:0) after 1m0s
[00:45:11.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:5:0)
[00:45:11.455] Timer tick, asking Buildbucket for the build status
[00:45:11.948] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:11.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:6:0) after 1m0s
[00:46:12.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:6:0)
[00:46:12.001] Timer tick, asking Buildbucket for the build status
[00:46:12.519] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:12.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:7:0) after 1m0s
[00:47:12.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:7:0)
[00:47:12.598] Timer tick, asking Buildbucket for the build status
[00:47:12.956] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:12.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:8:0) after 1m0s
[00:48:12.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:8:0)
[00:48:12.983] Timer tick, asking Buildbucket for the build status
[00:48:13.145] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:13.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:9:0) after 1m0s
[00:49:13.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:9:0)
[00:49:13.210] Timer tick, asking Buildbucket for the build status
[00:49:14.122] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:14.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:10:0) after 1m0s
[00:50:14.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:10:0)
[00:50:14.167] Timer tick, asking Buildbucket for the build status
[00:50:14.656] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:14.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:11:0) after 1m0s
[00:51:14.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:11:0)
[00:51:14.696] Timer tick, asking Buildbucket for the build status
[00:51:14.991] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:14.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:12:0) after 1m0s
[00:52:15.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:12:0)
[00:52:15.031] Timer tick, asking Buildbucket for the build status
[00:52:15.291] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:15.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:13:0) after 1m0s
[00:53:15.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:13:0)
[00:53:15.425] Timer tick, asking Buildbucket for the build status
[00:53:15.614] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:15.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:14:0) after 1m0s
[00:54:15.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:14:0)
[00:54:15.616] Timer tick, asking Buildbucket for the build status
[00:54:16.002] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:16.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:15:0) after 1m0s
[00:55:16.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:15:0)
[00:55:16.073] Timer tick, asking Buildbucket for the build status
[00:55:16.216] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:16.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:16:0) after 1m0s
[00:56:16.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:16:0)
[00:56:16.276] Timer tick, asking Buildbucket for the build status
[00:56:16.510] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:16.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:17:0) after 1m0s
[00:57:16.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:17:0)
[00:57:16.549] Timer tick, asking Buildbucket for the build status
[00:57:16.653] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:16.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:18:0) after 1m0s
[00:58:16.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:18:0)
[00:58:16.677] Timer tick, asking Buildbucket for the build status
[00:58:16.987] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:16.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:19:0) after 1m0s
[00:59:17.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:19:0)
[00:59:17.008] Timer tick, asking Buildbucket for the build status
[00:59:17.283] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:17.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:20:0) after 1m0s
[01:00:17.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:20:0)
[01:00:17.301] Timer tick, asking Buildbucket for the build status
[01:00:17.524] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:17.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:21:0) after 1m0s
[01:01:17.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:21:0)
[01:01:17.540] Timer tick, asking Buildbucket for the build status
[01:01:17.723] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:17.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:22:0) after 1m0s
[01:02:17.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:22:0)
[01:02:17.700] Timer tick, asking Buildbucket for the build status
[01:02:17.823] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:17.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:23:0) after 1m0s
[01:03:17.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:23:0)
[01:03:17.848] Timer tick, asking Buildbucket for the build status
[01:03:18.445] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:18.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:24:0) after 1m0s
[01:04:18.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:24:0)
[01:04:18.516] Timer tick, asking Buildbucket for the build status
[01:04:18.868] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:18.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:25:0) after 1m0s
[01:05:18.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:25:0)
[01:05:18.912] Timer tick, asking Buildbucket for the build status
[01:05:19.067] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:19.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:26:0) after 1m0s
[01:06:19.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:26:0)
[01:06:19.115] Timer tick, asking Buildbucket for the build status
[01:06:19.195] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:19.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:27:0) after 1m0s
[01:07:19.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:27:0)
[01:07:19.369] Timer tick, asking Buildbucket for the build status
[01:07:19.498] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:19.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:28:0) after 1m0s
[01:08:19.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:28:0)
[01:08:19.646] Timer tick, asking Buildbucket for the build status
[01:08:19.945] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:19.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:29:0) after 1m0s
[01:09:20.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:29:0)
[01:09:20.079] Timer tick, asking Buildbucket for the build status
[01:09:20.262] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:20.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:30:0) after 1m0s
[01:10:20.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:30:0)
[01:10:20.780] Timer tick, asking Buildbucket for the build status
[01:10:20.954] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:20.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:31:0) after 1m0s
[01:11:21.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:31:0)
[01:11:21.108] Timer tick, asking Buildbucket for the build status
[01:11:21.270] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:21.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:32:0) after 1m0s
[01:12:21.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:32:0)
[01:12:21.337] Timer tick, asking Buildbucket for the build status
[01:12:21.789] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:21.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:33:0) after 1m0s
[01:13:21.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:33:0)
[01:13:21.986] Timer tick, asking Buildbucket for the build status
[01:13:22.378] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:22.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:34:0) after 1m0s
[01:14:22.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:34:0)
[01:14:22.455] Timer tick, asking Buildbucket for the build status
[01:14:22.844] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:22.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:35:0) after 1m0s
[01:15:22.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:35:0)
[01:15:22.920] Timer tick, asking Buildbucket for the build status
[01:15:23.129] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:23.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:36:0) after 1m0s
[01:16:23.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:36:0)
[01:16:23.420] Timer tick, asking Buildbucket for the build status
[01:16:23.727] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:23.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:37:0) after 1m0s
[01:17:23.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:37:0)
[01:17:23.836] Timer tick, asking Buildbucket for the build status
[01:17:24.474] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:24.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:38:0) after 1m0s
[01:18:24.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:38:0)
[01:18:24.518] Timer tick, asking Buildbucket for the build status
[01:18:27.194] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:27.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:39:0) after 1m0s
[01:19:27.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:39:0)
[01:19:27.278] Timer tick, asking Buildbucket for the build status
[01:19:27.395] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:27.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:40:0) after 1m0s
[01:20:27.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:40:0)
[01:20:27.481] Timer tick, asking Buildbucket for the build status
[01:20:27.686] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:27.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:41:0) after 1m0s
[01:21:27.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:41:0)
[01:21:27.668] Timer tick, asking Buildbucket for the build status
[01:21:27.888] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:27.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:42:0) after 1m0s
[01:22:27.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:42:0)
[01:22:27.893] Timer tick, asking Buildbucket for the build status
[01:22:28.056] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:28.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:43:0) after 1m0s
[01:23:28.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:43:0)
[01:23:28.157] Timer tick, asking Buildbucket for the build status
[01:23:28.302] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:28.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:44:0) after 1m0s
[01:24:28.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:44:0)
[01:24:28.363] Timer tick, asking Buildbucket for the build status
[01:24:28.473] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:28.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:45:0) after 1m0s
[01:25:28.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:45:0)
[01:25:28.528] Timer tick, asking Buildbucket for the build status
[01:25:28.729] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:28.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:46:0) after 1m0s
[01:26:28.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:46:0)
[01:26:28.788] Timer tick, asking Buildbucket for the build status
[01:26:29.307] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:29.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:47:0) after 1m0s
[01:27:29.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:47:0)
[01:27:29.385] Timer tick, asking Buildbucket for the build status
[01:27:29.570] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:29.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:48:0) after 1m0s
[01:28:29.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:48:0)
[01:28:29.842] Timer tick, asking Buildbucket for the build status
[01:28:30.429] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:30.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:49:0) after 1m0s
[01:29:30.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:49:0)
[01:29:30.542] Timer tick, asking Buildbucket for the build status
[01:29:30.894] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:30.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:50:0) after 1m0s
[01:30:31.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:50:0)
[01:30:31.025] Timer tick, asking Buildbucket for the build status
[01:30:31.178] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:31.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:51:0) after 1m0s
[01:31:31.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:51:0)
[01:31:31.231] Timer tick, asking Buildbucket for the build status
[01:31:31.329] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:31.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:52:0) after 1m0s
[01:32:31.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:52:0)
[01:32:31.425] Timer tick, asking Buildbucket for the build status
[01:32:31.565] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:31.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:53:0) after 1m0s
[01:33:31.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:53:0)
[01:33:31.669] Timer tick, asking Buildbucket for the build status
[01:33:32.323] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:32.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:54:0) after 1m0s
[01:34:32.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:54:0)
[01:34:32.386] Timer tick, asking Buildbucket for the build status
[01:34:32.598] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:32.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:55:0) after 1m0s
[01:35:32.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:55:0)
[01:35:32.675] Timer tick, asking Buildbucket for the build status
[01:35:32.780] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:32.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:56:0) after 1m0s
[01:36:32.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:56:0)
[01:36:32.848] Timer tick, asking Buildbucket for the build status
[01:36:32.944] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:32.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:57:0) after 1m0s
[01:37:32.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:57:0)
[01:37:32.985] Timer tick, asking Buildbucket for the build status
[01:37:33.163] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:33.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:58:0) after 1m0s
[01:38:33.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:58:0)
[01:38:33.244] Timer tick, asking Buildbucket for the build status
[01:38:33.380] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:33.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:59:0) after 1m0s
[01:39:33.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:59:0)
[01:39:33.459] Timer tick, asking Buildbucket for the build status
[01:39:33.615] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:33.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:60:0) after 1m0s
[01:40:33.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:60:0)
[01:40:33.723] Timer tick, asking Buildbucket for the build status
[01:40:34.215] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:34.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:61:0) after 1m0s
[01:41:34.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:61:0)
[01:41:34.269] Timer tick, asking Buildbucket for the build status
[01:41:34.463] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:34.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:62:0) after 1m0s
[01:42:34.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:62:0)
[01:42:34.658] Timer tick, asking Buildbucket for the build status
[01:42:35.036] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:35.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:63:0) after 1m0s
[01:43:35.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:63:0)
[01:43:35.325] Timer tick, asking Buildbucket for the build status
[01:43:37.291] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:37.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:64:0) after 1m0s
[01:44:37.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:64:0)
[01:44:37.519] Timer tick, asking Buildbucket for the build status
[01:44:37.874] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:37.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:65:0) after 1m0s
[01:45:38.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:65:0)
[01:45:38.022] Timer tick, asking Buildbucket for the build status
[01:45:38.189] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:38.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:66:0) after 1m0s
[01:46:38.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:66:0)
[01:46:38.319] Timer tick, asking Buildbucket for the build status
[01:46:38.623] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:38.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:67:0) after 1m0s
[01:47:38.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:67:0)
[01:47:38.790] Timer tick, asking Buildbucket for the build status
[01:47:38.925] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:38.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:68:0) after 1m0s
[01:48:39.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:68:0)
[01:48:39.039] Timer tick, asking Buildbucket for the build status
[01:48:39.801] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:39.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:69:0) after 1m0s
[01:49:39.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:69:0)
[01:49:39.890] Timer tick, asking Buildbucket for the build status
[01:49:40.025] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:40.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:70:0) after 1m0s
[01:50:40.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:70:0)
[01:50:40.055] Timer tick, asking Buildbucket for the build status
[01:50:40.364] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:40.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:71:0) after 1m0s
[01:51:40.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:71:0)
[01:51:40.390] Timer tick, asking Buildbucket for the build status
[01:51:40.744] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:40.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:72:0) after 1m0s
[01:52:40.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:72:0)
[01:52:40.792] Timer tick, asking Buildbucket for the build status
[01:52:40.959] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:40.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:73:0) after 1m0s
[01:53:41.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:73:0)
[01:53:41.011] Timer tick, asking Buildbucket for the build status
[01:53:41.737] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:41.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:74:0) after 1m0s
[01:54:41.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:74:0)
[01:54:41.765] Timer tick, asking Buildbucket for the build status
[01:54:41.914] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:41.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:75:0) after 1m0s
[01:55:41.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:75:0)
[01:55:41.969] Timer tick, asking Buildbucket for the build status
[01:55:42.228] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:42.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:76:0) after 1m0s
[01:56:42.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:76:0)
[01:56:42.247] Timer tick, asking Buildbucket for the build status
[01:56:42.458] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:42.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:77:0) after 1m0s
[01:57:42.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:77:0)
[01:57:42.508] Timer tick, asking Buildbucket for the build status
[01:57:42.743] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:42.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:78:0) after 1m0s
[01:58:42.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:78:0)
[01:58:42.805] Timer tick, asking Buildbucket for the build status
[01:58:44.185] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:44.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:79:0) after 1m0s
[01:59:44.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:79:0)
[01:59:44.225] Timer tick, asking Buildbucket for the build status
[01:59:44.349] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:44.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:80:0) after 1m0s
[02:00:44.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:80:0)
[02:00:44.376] Timer tick, asking Buildbucket for the build status
[02:00:44.616] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:44.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:81:0) after 1m0s
[02:01:44.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:81:0)
[02:01:44.679] Timer tick, asking Buildbucket for the build status
[02:01:45.192] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:45.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:82:0) after 1m0s
[02:02:45.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:82:0)
[02:02:45.204] Timer tick, asking Buildbucket for the build status
[02:02:45.328] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:45.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:83:0) after 1m0s
[02:03:45.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:83:0)
[02:03:45.352] Timer tick, asking Buildbucket for the build status
[02:03:46.058] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:46.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:84:0) after 1m0s
[02:04:46.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:84:0)
[02:04:46.108] Timer tick, asking Buildbucket for the build status
[02:04:46.503] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:46.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:85:0) after 1m0s
[02:05:46.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:85:0)
[02:05:46.525] Timer tick, asking Buildbucket for the build status
[02:05:46.726] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:46.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:86:0) after 1m0s
[02:06:46.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:86:0)
[02:06:46.763] Timer tick, asking Buildbucket for the build status
[02:06:47.082] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:47.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:87:0) after 1m0s
[02:07:47.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:87:0)
[02:07:47.118] Timer tick, asking Buildbucket for the build status
[02:07:47.410] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:47.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:88:0) after 1m0s
[02:08:47.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:88:0)
[02:08:47.434] Timer tick, asking Buildbucket for the build status
[02:08:48.031] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:48.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:89:0) after 1m0s
[02:09:48.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:89:0)
[02:09:48.046] Timer tick, asking Buildbucket for the build status
[02:09:48.167] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:48.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:90:0) after 1m0s
[02:10:48.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:90:0)
[02:10:48.195] Timer tick, asking Buildbucket for the build status
[02:10:48.398] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:48.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:91:0) after 1m0s
[02:11:48.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:91:0)
[02:11:48.384] Timer tick, asking Buildbucket for the build status
[02:11:48.545] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:48.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:92:0) after 1m0s
[02:12:48.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:92:0)
[02:12:48.607] Timer tick, asking Buildbucket for the build status
[02:12:49.450] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:49.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:93:0) after 1m0s
[02:13:49.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:93:0)
[02:13:49.480] Timer tick, asking Buildbucket for the build status
[02:13:50.449] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:50.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:94:0) after 1m0s
[02:14:50.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:94:0)
[02:14:50.491] Timer tick, asking Buildbucket for the build status
[02:14:50.600] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:50.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:95:0) after 1m0s
[02:15:50.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:95:0)
[02:15:50.702] Timer tick, asking Buildbucket for the build status
[02:15:50.882] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:50.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:96:0) after 1m0s
[02:16:51.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:96:0)
[02:16:51.046] Timer tick, asking Buildbucket for the build status
[02:16:51.314] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:51.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:97:0) after 1m0s
[02:17:51.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:97:0)
[02:17:51.403] Timer tick, asking Buildbucket for the build status
[02:17:51.771] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:51.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:98:0) after 1m0s
[02:18:51.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:98:0)
[02:18:51.833] Timer tick, asking Buildbucket for the build status
[02:18:52.057] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:52.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:99:0) after 1m0s
[02:19:52.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:99:0)
[02:19:52.096] Timer tick, asking Buildbucket for the build status
[02:19:52.475] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:52.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:100:0) after 1m0s
[02:20:52.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:100:0)
[02:20:52.477] Timer tick, asking Buildbucket for the build status
[02:20:52.589] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:52.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:101:0) after 1m0s
[02:21:52.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:101:0)
[02:21:52.612] Timer tick, asking Buildbucket for the build status
[02:21:52.743] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:52.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:102:0) after 1m0s
[02:22:52.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:102:0)
[02:22:52.861] Timer tick, asking Buildbucket for the build status
[02:22:52.986] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:52.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:103:0) after 1m0s
[02:23:53.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:103:0)
[02:23:53.016] Timer tick, asking Buildbucket for the build status
[02:23:54.950] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:54.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:104:0) after 1m0s
[02:24:55.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:104:0)
[02:24:55.106] Timer tick, asking Buildbucket for the build status
[02:24:55.460] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:55.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:105:0) after 1m0s
[02:25:55.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:105:0)
[02:25:55.452] Timer tick, asking Buildbucket for the build status
[02:25:55.589] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:55.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:106:0) after 1m0s
[02:26:55.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:106:0)
[02:26:55.688] Timer tick, asking Buildbucket for the build status
[02:26:56.322] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:56.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:107:0) after 1m0s
[02:27:56.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:107:0)
[02:27:56.363] Timer tick, asking Buildbucket for the build status
[02:27:56.473] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:56.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:108:0) after 1m0s
[02:28:56.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:108:0)
[02:28:56.584] Timer tick, asking Buildbucket for the build status
[02:28:57.008] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:57.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:109:0) after 1m0s
[02:29:57.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:109:0)
[02:29:57.102] Timer tick, asking Buildbucket for the build status
[02:29:57.257] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:57.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:110:0) after 1m0s
[02:30:57.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:110:0)
[02:30:57.354] Timer tick, asking Buildbucket for the build status
[02:30:57.450] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:57.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:111:0) after 1m0s
[02:31:57.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:111:0)
[02:31:57.536] Timer tick, asking Buildbucket for the build status
[02:31:57.646] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:57.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:112:0) after 1m0s
[02:32:57.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:112:0)
[02:32:57.842] Timer tick, asking Buildbucket for the build status
[02:32:57.948] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:57.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:113:0) after 1m0s
[02:33:58.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:113:0)
[02:33:58.069] Timer tick, asking Buildbucket for the build status
[02:33:58.562] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:58.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:114:0) after 1m0s
[02:34:58.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:114:0)
[02:34:58.825] Timer tick, asking Buildbucket for the build status
[02:34:58.973] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:58.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:115:0) after 1m0s
[02:35:59.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:115:0)
[02:35:59.089] Timer tick, asking Buildbucket for the build status
[02:35:59.698] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:59.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:116:0) after 1m0s
[02:36:59.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:116:0)
[02:36:59.911] Timer tick, asking Buildbucket for the build status
[02:37:00.227] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:00.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:117:0) after 1m0s
[02:38:00.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:117:0)
[02:38:00.296] Timer tick, asking Buildbucket for the build status
[02:38:00.567] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:00.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:118:0) after 1m0s
[02:39:01.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:118:0)
[02:39:01.692] Timer tick, asking Buildbucket for the build status
[02:39:01.879] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:01.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:119:0) after 1m0s
[02:40:02.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:119:0)
[02:40:02.212] Timer tick, asking Buildbucket for the build status
[02:40:02.383] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:02.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:120:0) after 1m0s
[02:41:03.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:120:0)
[02:41:03.061] Timer tick, asking Buildbucket for the build status
[02:41:03.324] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:03.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:121:0) after 1m0s
[02:42:03.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:121:0)
[02:42:03.358] Timer tick, asking Buildbucket for the build status
[02:42:03.898] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:03.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:122:0) after 1m0s
[02:43:04.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:122:0)
[02:43:04.097] Timer tick, asking Buildbucket for the build status
[02:43:04.252] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:04.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:123:0) after 1m0s
[02:44:04.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:123:0)
[02:44:04.426] Timer tick, asking Buildbucket for the build status
[02:44:05.390] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:05.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:124:0) after 1m0s
[02:45:05.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:124:0)
[02:45:05.523] Timer tick, asking Buildbucket for the build status
[02:45:05.794] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:05.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:125:0) after 1m0s
[02:46:05.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:125:0)
[02:46:05.859] Timer tick, asking Buildbucket for the build status
[02:46:06.166] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:06.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:126:0) after 1m0s
[02:47:06.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:126:0)
[02:47:06.249] Timer tick, asking Buildbucket for the build status
[02:47:06.571] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:06.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:127:0) after 1m0s
[02:48:06.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:127:0)
[02:48:06.680] Timer tick, asking Buildbucket for the build status
[02:48:08.906] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:08.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:128:0) after 1m0s
[02:49:09.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:128:0)
[02:49:09.083] Timer tick, asking Buildbucket for the build status
[02:49:09.818] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:09.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:129:0) after 1m0s
[02:50:09.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:129:0)
[02:50:09.867] Timer tick, asking Buildbucket for the build status
[02:50:10.007] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:10.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:130:0) after 1m0s
[02:51:10.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:130:0)
[02:51:10.028] Timer tick, asking Buildbucket for the build status
[02:51:10.342] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:10.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:131:0) after 1m0s
[02:52:10.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:131:0)
[02:52:10.416] Timer tick, asking Buildbucket for the build status
[02:52:10.581] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:10.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:132:0) after 1m0s
[02:53:10.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:132:0)
[02:53:10.722] Timer tick, asking Buildbucket for the build status
[02:53:11.208] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:11.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:133:0) after 1m0s
[02:54:11.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:133:0)
[02:54:11.406] Timer tick, asking Buildbucket for the build status
[02:54:11.682] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:11.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:134:0) after 1m0s
[02:55:11.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:134:0)
[02:55:11.684] Timer tick, asking Buildbucket for the build status
[02:55:11.950] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:11.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:135:0) after 1m0s
[02:56:12.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:135:0)
[02:56:12.008] Timer tick, asking Buildbucket for the build status
[02:56:12.155] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:12.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:136:0) after 1m0s
[02:57:12.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:136:0)
[02:57:12.226] Timer tick, asking Buildbucket for the build status
[02:57:12.462] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:12.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:137:0) after 1m0s
[02:58:12.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:137:0)
[02:58:12.586] Timer tick, asking Buildbucket for the build status
[02:58:12.722] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:12.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:138:0) after 1m0s
[02:59:12.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:138:0)
[02:59:12.745] Timer tick, asking Buildbucket for the build status
[02:59:12.972] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:12.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:139:0) after 1m0s
[03:00:13.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:139:0)
[03:00:13.052] Timer tick, asking Buildbucket for the build status
[03:00:13.196] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:13.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:140:0) after 1m0s
[03:01:13.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:140:0)
[03:01:13.542] Timer tick, asking Buildbucket for the build status
[03:01:13.719] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:13.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:141:0) after 1m0s
[03:02:13.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:141:0)
[03:02:13.775] Timer tick, asking Buildbucket for the build status
[03:02:14.049] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:14.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:142:0) after 1m0s
[03:03:14.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:142:0)
[03:03:14.201] Timer tick, asking Buildbucket for the build status
[03:03:14.397] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:14.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:143:0) after 1m0s
[03:04:14.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:143:0)
[03:04:14.413] Timer tick, asking Buildbucket for the build status
[03:04:14.575] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:14.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:144:0) after 1m0s
[03:05:14.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:144:0)
[03:05:14.688] Timer tick, asking Buildbucket for the build status
[03:05:14.839] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:14.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:145:0) after 1m0s
[03:06:14.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:145:0)
[03:06:14.938] Timer tick, asking Buildbucket for the build status
[03:06:15.702] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:15.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:146:0) after 1m0s
[03:07:15.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:146:0)
[03:07:15.739] Timer tick, asking Buildbucket for the build status
[03:07:16.280] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:16.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:147:0) after 1m0s
[03:08:16.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:147:0)
[03:08:16.365] Timer tick, asking Buildbucket for the build status
[03:08:16.862] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:16.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:148:0) after 1m0s
[03:09:16.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:148:0)
[03:09:16.982] Timer tick, asking Buildbucket for the build status
[03:09:17.163] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:17.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:149:0) after 1m0s
[03:10:17.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:149:0)
[03:10:17.226] Timer tick, asking Buildbucket for the build status
[03:10:18.078] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:18.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:150:0) after 1m0s
[03:11:18.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:150:0)
[03:11:18.585] Timer tick, asking Buildbucket for the build status
[03:11:18.796] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:18.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:151:0) after 1m0s
[03:12:18.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:151:0)
[03:12:18.918] Timer tick, asking Buildbucket for the build status
[03:12:19.288] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:19.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:152:0) after 1m0s
[03:13:19.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:152:0)
[03:13:19.684] Timer tick, asking Buildbucket for the build status
[03:13:19.956] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:19.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:153:0) after 1m0s
[03:14:20.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:153:0)
[03:14:20.197] Timer tick, asking Buildbucket for the build status
[03:14:20.426] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:20.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:154:0) after 1m0s
[03:15:20.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:154:0)
[03:15:20.542] Timer tick, asking Buildbucket for the build status
[03:15:20.670] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:20.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:155:0) after 1m0s
[03:16:20.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:155:0)
[03:16:20.871] Timer tick, asking Buildbucket for the build status
[03:16:21.335] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:21.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:156:0) after 1m0s
[03:17:21.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:156:0)
[03:17:21.380] Timer tick, asking Buildbucket for the build status
[03:17:21.607] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:21.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:157:0) after 1m0s
[03:18:21.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:157:0)
[03:18:21.682] Timer tick, asking Buildbucket for the build status
[03:18:21.972] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:21.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:158:0) after 1m0s
[03:19:22.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:158:0)
[03:19:22.021] Timer tick, asking Buildbucket for the build status
[03:19:22.265] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:22.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:159:0) after 1m0s
[03:20:22.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:159:0)
[03:20:22.270] Timer tick, asking Buildbucket for the build status
[03:20:22.462] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:22.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:160:0) after 1m0s
[03:21:22.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:160:0)
[03:21:22.508] Timer tick, asking Buildbucket for the build status
[03:21:22.812] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:22.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:161:0) after 1m0s
[03:22:22.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:161:0)
[03:22:22.873] Timer tick, asking Buildbucket for the build status
[03:22:23.262] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:23.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:162:0) after 1m0s
[03:23:23.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:162:0)
[03:23:23.319] Timer tick, asking Buildbucket for the build status
[03:23:23.623] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:23.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:163:0) after 1m0s
[03:24:23.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:163:0)
[03:24:23.675] Timer tick, asking Buildbucket for the build status
[03:24:23.829] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:23.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:164:0) after 1m0s
[03:25:23.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:164:0)
[03:25:23.974] Timer tick, asking Buildbucket for the build status
[03:25:24.224] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:24.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:165:0) after 1m0s
[03:26:24.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:165:0)
[03:26:24.337] Timer tick, asking Buildbucket for the build status
[03:26:24.492] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:24.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:166:0) after 1m0s
[03:27:24.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:166:0)
[03:27:24.589] Timer tick, asking Buildbucket for the build status
[03:27:24.714] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:24.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:167:0) after 1m0s
[03:28:24.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:167:0)
[03:28:24.849] Timer tick, asking Buildbucket for the build status
[03:28:25.270] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:25.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:168:0) after 1m0s
[03:29:25.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:168:0)
[03:29:25.398] Timer tick, asking Buildbucket for the build status
[03:29:25.682] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:25.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:169:0) after 1m0s
[03:30:25.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:169:0)
[03:30:25.756] Timer tick, asking Buildbucket for the build status
[03:30:26.264] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:26.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:170:0) after 1m0s
[03:31:26.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:170:0)
[03:31:26.412] Timer tick, asking Buildbucket for the build status
[03:31:26.956] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:26.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:171:0) after 1m0s
[03:32:27.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:171:0)
[03:32:27.082] Timer tick, asking Buildbucket for the build status
[03:32:27.194] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:27.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:172:0) after 1m0s
[03:33:27.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:172:0)
[03:33:27.293] Timer tick, asking Buildbucket for the build status
[03:33:29.039] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:29.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:173:0) after 1m0s
[03:34:29.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:173:0)
[03:34:29.079] Timer tick, asking Buildbucket for the build status
[03:34:29.219] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:29.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:174:0) after 1m0s
[03:35:29.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:174:0)
[03:35:29.243] Timer tick, asking Buildbucket for the build status
[03:35:29.358] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:29.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:175:0) after 1m0s
[03:36:29.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:175:0)
[03:36:29.405] Timer tick, asking Buildbucket for the build status
[03:36:29.661] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:29.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:176:0) after 1m0s
[03:37:29.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:176:0)
[03:37:29.738] Timer tick, asking Buildbucket for the build status
[03:37:29.836] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:29.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:177:0) after 1m0s
[03:38:29.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:177:0)
[03:38:29.959] Timer tick, asking Buildbucket for the build status
[03:38:30.140] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:30.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:178:0) after 1m0s
[03:39:30.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:178:0)
[03:39:30.764] Timer tick, asking Buildbucket for the build status
[03:39:30.967] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:30.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:179:0) after 1m0s
[03:40:31.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:179:0)
[03:40:31.066] Timer tick, asking Buildbucket for the build status
[03:40:31.189] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:31.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:180:0) after 1m0s
[03:41:32.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:180:0)
[03:41:32.945] Timer tick, asking Buildbucket for the build status
[03:41:33.127] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:33.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:181:0) after 1m0s
[03:42:33.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:181:0)
[03:42:33.374] Timer tick, asking Buildbucket for the build status
[03:42:33.858] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:33.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:182:0) after 1m0s
[03:43:33.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:182:0)
[03:43:33.925] Timer tick, asking Buildbucket for the build status
[03:43:36.345] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:36.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:183:0) after 1m0s
[03:44:36.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:183:0)
[03:44:36.486] Timer tick, asking Buildbucket for the build status
[03:44:36.716] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:36.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:184:0) after 1m0s
[03:45:36.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:184:0)
[03:45:36.929] Timer tick, asking Buildbucket for the build status
[03:45:37.427] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:37.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:185:0) after 1m0s
[03:46:37.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:185:0)
[03:46:37.578] Timer tick, asking Buildbucket for the build status
[03:46:38.043] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:38.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:186:0) after 1m0s
[03:47:38.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:186:0)
[03:47:38.224] Timer tick, asking Buildbucket for the build status
[03:47:38.542] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:38.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:187:0) after 1m0s
[03:48:38.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:187:0)
[03:48:38.613] Timer tick, asking Buildbucket for the build status
[03:48:39.376] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:39.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:188:0) after 1m0s
[03:49:39.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:188:0)
[03:49:39.519] Timer tick, asking Buildbucket for the build status
[03:49:39.634] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:39.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:189:0) after 1m0s
[03:50:39.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:189:0)
[03:50:39.732] Timer tick, asking Buildbucket for the build status
[03:50:39.849] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:39.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:190:0) after 1m0s
[03:51:39.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:190:0)
[03:51:39.957] Timer tick, asking Buildbucket for the build status
[03:51:40.093] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:40.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:191:0) after 1m0s
[03:52:40.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:191:0)
[03:52:40.167] Timer tick, asking Buildbucket for the build status
[03:52:40.294] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:40.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:192:0) after 1m0s
[03:53:40.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:192:0)
[03:53:40.357] Timer tick, asking Buildbucket for the build status
[03:53:41.017] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:41.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:193:0) after 1m0s
[03:54:41.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:193:0)
[03:54:41.306] Timer tick, asking Buildbucket for the build status
[03:54:41.441] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:41.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:194:0) after 1m0s
[03:55:41.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:194:0)
[03:55:41.535] Timer tick, asking Buildbucket for the build status
[03:55:41.647] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:41.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:195:0) after 1m0s
[03:56:41.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:195:0)
[03:56:41.897] Timer tick, asking Buildbucket for the build status
[03:56:42.046] Build 8876745390674964384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:42.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042175452654291472:196:0) after 1m0s
[03:57:08.875] Received PubSub notification, asking Buildbucket for the build status
[03:57:08.943] Build 8876745390674964384: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:57:08.943] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1592884627736044",
    "created_by": "project:infra",
    "created_ts": "1592872929843106",
    "id": "8876745390674964384",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4179.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f9bd0cd2-a6e9-456b-a091-16c0147dddef\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4179.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-6vz3\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"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\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1592872947534847",
    "status": "COMPLETED",
    "status_changed_ts": "1592884628370185",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042175452654291472",
      "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/8876745390674964384/+/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:4cf7719e4eb5da10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1592884628469406",
    "url": "https://ci.chromium.org/b/8876745390674964384",
    "utcnow_ts": "1592884628929443"
  }
}
[03:57:08.943] Invocation finished in 3h15m0.371756676s with status SUCCEEDED