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.4161.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[08:01:43.311] New invocation is queued and will start shortly
[08:01:43.311] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:01:44.753] Starting the invocation (attempt 1)
[08:01:44.793] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:01:44.793] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:01:44.793] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9044231526981053664",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4161.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"6bfa39b1-bde6-464d-b608-74f61e4a5753\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4161.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:9044231526981053664",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:01:45.655] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1590912104949092",
    "id": "8878801464599250800",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4161.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"6bfa39b1-bde6-464d-b608-74f61e4a5753\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4161.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1590912105396593",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9044231526981053664",
      "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/8878801464599250800/+/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": "1590912105396749",
    "url": "https://ci.chromium.org/b/8878801464599250800",
    "utcnow_ts": "1590912105642319"
  }
}
[08:01:45.655] Task URL: https://ci.chromium.org/b/8878801464599250800
[08:01:45.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:2:0) after 1m0s
[08:02:45.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:2:0)
[08:02:45.788] Timer tick, asking Buildbucket for the build status
[08:02:46.064] Build 8878801464599250800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:46.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:3:0) after 1m0s
[08:02:46.593] Received PubSub notification, asking Buildbucket for the build status
[08:02:46.890] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:46.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:3:0)
[08:03:46.120] Timer tick, asking Buildbucket for the build status
[08:03:46.520] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:46.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:5:0) after 1m0s
[08:04:46.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:5:0)
[08:04:46.556] Timer tick, asking Buildbucket for the build status
[08:04:46.927] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:46.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:6:0) after 1m0s
[08:05:46.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:6:0)
[08:05:46.967] Timer tick, asking Buildbucket for the build status
[08:05:47.162] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:47.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:7:0) after 1m0s
[08:06:47.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:7:0)
[08:06:47.644] Timer tick, asking Buildbucket for the build status
[08:06:48.593] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:48.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:8:0) after 1m0s
[08:07:48.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:8:0)
[08:07:48.660] Timer tick, asking Buildbucket for the build status
[08:07:49.002] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:49.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:9:0) after 1m0s
[08:08:49.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:9:0)
[08:08:49.033] Timer tick, asking Buildbucket for the build status
[08:08:49.521] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:49.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:10:0) after 1m0s
[08:09:49.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:10:0)
[08:09:49.543] Timer tick, asking Buildbucket for the build status
[08:09:49.818] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:49.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:11:0) after 1m0s
[08:10:49.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:11:0)
[08:10:49.843] Timer tick, asking Buildbucket for the build status
[08:10:49.938] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:49.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:12:0) after 1m0s
[08:11:49.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:12:0)
[08:11:49.962] Timer tick, asking Buildbucket for the build status
[08:11:50.336] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:50.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:13:0) after 1m0s
[08:12:50.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:13:0)
[08:12:50.374] Timer tick, asking Buildbucket for the build status
[08:12:50.630] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:50.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:14:0) after 1m0s
[08:13:50.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:14:0)
[08:13:50.662] Timer tick, asking Buildbucket for the build status
[08:13:50.814] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:50.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:15:0) after 1m0s
[08:14:50.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:15:0)
[08:14:50.834] Timer tick, asking Buildbucket for the build status
[08:14:51.157] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:51.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:16:0) after 1m0s
[08:15:51.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:16:0)
[08:15:51.255] Timer tick, asking Buildbucket for the build status
[08:15:51.728] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:51.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:17:0) after 1m0s
[08:16:51.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:17:0)
[08:16:51.750] Timer tick, asking Buildbucket for the build status
[08:16:51.883] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:51.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:18:0) after 1m0s
[08:17:51.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:18:0)
[08:17:51.948] Timer tick, asking Buildbucket for the build status
[08:17:52.133] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:52.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:19:0) after 1m0s
[08:18:52.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:19:0)
[08:18:52.182] Timer tick, asking Buildbucket for the build status
[08:18:52.350] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:52.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:20:0) after 1m0s
[08:19:52.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:20:0)
[08:19:52.368] Timer tick, asking Buildbucket for the build status
[08:19:52.946] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:52.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:21:0) after 1m0s
[08:20:53.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:21:0)
[08:20:53.018] Timer tick, asking Buildbucket for the build status
[08:20:53.168] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:53.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:22:0) after 1m0s
[08:21:53.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:22:0)
[08:21:53.191] Timer tick, asking Buildbucket for the build status
[08:21:53.336] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:53.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:23:0) after 1m0s
[08:22:53.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:23:0)
[08:22:53.376] Timer tick, asking Buildbucket for the build status
[08:22:53.857] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:53.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:24:0) after 1m0s
[08:23:53.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:24:0)
[08:23:53.878] Timer tick, asking Buildbucket for the build status
[08:23:55.307] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:55.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:25:0) after 1m0s
[08:24:55.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:25:0)
[08:24:55.370] Timer tick, asking Buildbucket for the build status
[08:24:55.804] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:55.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:26:0) after 1m0s
[08:25:55.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:26:0)
[08:25:55.843] Timer tick, asking Buildbucket for the build status
[08:25:56.083] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:56.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:27:0) after 1m0s
[08:26:56.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:27:0)
[08:26:56.150] Timer tick, asking Buildbucket for the build status
[08:26:56.522] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:56.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:28:0) after 1m0s
[08:27:56.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:28:0)
[08:27:56.564] Timer tick, asking Buildbucket for the build status
[08:27:56.678] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:56.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:29:0) after 1m0s
[08:28:56.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:29:0)
[08:28:56.717] Timer tick, asking Buildbucket for the build status
[08:28:56.840] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:56.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:30:0) after 1m0s
[08:29:56.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:30:0)
[08:29:56.866] Timer tick, asking Buildbucket for the build status
[08:29:57.033] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:57.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:31:0) after 1m0s
[08:30:57.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:31:0)
[08:30:57.119] Timer tick, asking Buildbucket for the build status
[08:30:57.462] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:57.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:32:0) after 1m0s
[08:31:57.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:32:0)
[08:31:57.546] Timer tick, asking Buildbucket for the build status
[08:31:57.703] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:57.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:33:0) after 1m0s
[08:32:57.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:33:0)
[08:32:57.763] Timer tick, asking Buildbucket for the build status
[08:32:57.971] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:57.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:34:0) after 1m0s
[08:33:58.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:34:0)
[08:33:58.051] Timer tick, asking Buildbucket for the build status
[08:33:58.628] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:58.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:35:0) after 1m0s
[08:34:58.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:35:0)
[08:34:58.761] Timer tick, asking Buildbucket for the build status
[08:34:58.976] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:58.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:36:0) after 1m0s
[08:35:59.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:36:0)
[08:35:59.047] Timer tick, asking Buildbucket for the build status
[08:35:59.281] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:59.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:37:0) after 1m0s
[08:36:59.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:37:0)
[08:36:59.357] Timer tick, asking Buildbucket for the build status
[08:36:59.887] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:59.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:38:0) after 1m0s
[08:37:59.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:38:0)
[08:37:59.967] Timer tick, asking Buildbucket for the build status
[08:38:00.361] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:00.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:39:0) after 1m0s
[08:39:01.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:39:0)
[08:39:01.266] Timer tick, asking Buildbucket for the build status
[08:39:01.703] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:01.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:40:0) after 1m0s
[08:40:02.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:40:0)
[08:40:02.230] Timer tick, asking Buildbucket for the build status
[08:40:02.889] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:02.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:41:0) after 1m0s
[08:41:03.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:41:0)
[08:41:03.001] Timer tick, asking Buildbucket for the build status
[08:41:03.487] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:03.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:42:0) after 1m0s
[08:42:03.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:42:0)
[08:42:03.574] Timer tick, asking Buildbucket for the build status
[08:42:03.817] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:03.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:43:0) after 1m0s
[08:43:03.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:43:0)
[08:43:03.968] Timer tick, asking Buildbucket for the build status
[08:43:04.126] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:04.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:44:0) after 1m0s
[08:44:04.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:44:0)
[08:44:04.223] Timer tick, asking Buildbucket for the build status
[08:44:05.056] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:05.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:45:0) after 1m0s
[08:45:05.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:45:0)
[08:45:05.089] Timer tick, asking Buildbucket for the build status
[08:45:05.387] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:05.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:46:0) after 1m0s
[08:46:05.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:46:0)
[08:46:05.474] Timer tick, asking Buildbucket for the build status
[08:46:05.839] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:05.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:47:0) after 1m0s
[08:47:05.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:47:0)
[08:47:05.870] Timer tick, asking Buildbucket for the build status
[08:47:06.346] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:06.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:48:0) after 1m0s
[08:48:06.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:48:0)
[08:48:06.477] Timer tick, asking Buildbucket for the build status
[08:48:07.442] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:07.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:49:0) after 1m0s
[08:49:07.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:49:0)
[08:49:07.448] Timer tick, asking Buildbucket for the build status
[08:49:08.237] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:08.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:50:0) after 1m0s
[08:50:08.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:50:0)
[08:50:08.305] Timer tick, asking Buildbucket for the build status
[08:50:08.573] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:08.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:51:0) after 1m0s
[08:51:08.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:51:0)
[08:51:08.648] Timer tick, asking Buildbucket for the build status
[08:51:09.113] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:09.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:52:0) after 1m0s
[08:52:09.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:52:0)
[08:52:09.213] Timer tick, asking Buildbucket for the build status
[08:52:09.434] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:09.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:53:0) after 1m0s
[08:53:09.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:53:0)
[08:53:09.529] Timer tick, asking Buildbucket for the build status
[08:53:09.791] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:09.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:54:0) after 1m0s
[08:54:09.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:54:0)
[08:54:09.866] Timer tick, asking Buildbucket for the build status
[08:54:10.005] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:10.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:55:0) after 1m0s
[08:55:10.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:55:0)
[08:55:10.053] Timer tick, asking Buildbucket for the build status
[08:55:10.336] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:10.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:56:0) after 1m0s
[08:56:10.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:56:0)
[08:56:10.427] Timer tick, asking Buildbucket for the build status
[08:56:10.606] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:10.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:57:0) after 1m0s
[08:57:10.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:57:0)
[08:57:10.757] Timer tick, asking Buildbucket for the build status
[08:57:10.910] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:10.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:58:0) after 1m0s
[08:58:11.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:58:0)
[08:58:11.174] Timer tick, asking Buildbucket for the build status
[08:58:11.460] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:11.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:59:0) after 1m0s
[08:59:11.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:59:0)
[08:59:11.679] Timer tick, asking Buildbucket for the build status
[08:59:11.883] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:11.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:60:0) after 1m0s
[09:00:12.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:60:0)
[09:00:12.153] Timer tick, asking Buildbucket for the build status
[09:00:13.952] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:13.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:61:0) after 1m0s
[09:01:14.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:61:0)
[09:01:14.137] Timer tick, asking Buildbucket for the build status
[09:01:15.129] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:15.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:62:0) after 1m0s
[09:02:15.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:62:0)
[09:02:15.459] Timer tick, asking Buildbucket for the build status
[09:02:16.781] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:16.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:63:0) after 1m0s
[09:03:17.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:63:0)
[09:03:17.075] Timer tick, asking Buildbucket for the build status
[09:03:24.728] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:24.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:64:0) after 1m0s
[09:04:24.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:64:0)
[09:04:24.767] Timer tick, asking Buildbucket for the build status
[09:04:25.207] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:25.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:65:0) after 1m0s
[09:05:25.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:65:0)
[09:05:25.306] Timer tick, asking Buildbucket for the build status
[09:05:25.527] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:25.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:66:0) after 1m0s
[09:06:25.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:66:0)
[09:06:25.623] Timer tick, asking Buildbucket for the build status
[09:06:25.841] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:25.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:67:0) after 1m0s
[09:07:25.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:67:0)
[09:07:25.865] Timer tick, asking Buildbucket for the build status
[09:07:26.032] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:26.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:68:0) after 1m0s
[09:08:26.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:68:0)
[09:08:26.065] Timer tick, asking Buildbucket for the build status
[09:08:27.122] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:27.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:69:0) after 1m0s
[09:09:27.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:69:0)
[09:09:27.161] Timer tick, asking Buildbucket for the build status
[09:09:27.689] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:27.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:70:0) after 1m0s
[09:10:27.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:70:0)
[09:10:27.853] Timer tick, asking Buildbucket for the build status
[09:10:28.030] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:28.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:71:0) after 1m0s
[09:11:28.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:71:0)
[09:11:28.119] Timer tick, asking Buildbucket for the build status
[09:11:28.282] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:28.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:72:0) after 1m0s
[09:12:28.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:72:0)
[09:12:28.275] Timer tick, asking Buildbucket for the build status
[09:12:28.397] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:28.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:73:0) after 1m0s
[09:13:28.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:73:0)
[09:13:28.410] Timer tick, asking Buildbucket for the build status
[09:13:28.652] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:28.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:74:0) after 1m0s
[09:14:28.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:74:0)
[09:14:28.704] Timer tick, asking Buildbucket for the build status
[09:14:28.938] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:28.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:75:0) after 1m0s
[09:15:28.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:75:0)
[09:15:28.979] Timer tick, asking Buildbucket for the build status
[09:15:29.339] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:29.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:76:0) after 1m0s
[09:16:29.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:76:0)
[09:16:29.416] Timer tick, asking Buildbucket for the build status
[09:16:29.664] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:29.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:77:0) after 1m0s
[09:17:31.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:77:0)
[09:17:31.086] Timer tick, asking Buildbucket for the build status
[09:17:31.293] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:31.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:78:0) after 1m0s
[09:18:31.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:78:0)
[09:18:31.346] Timer tick, asking Buildbucket for the build status
[09:18:31.843] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:31.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:79:0) after 1m0s
[09:19:31.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:79:0)
[09:19:31.909] Timer tick, asking Buildbucket for the build status
[09:19:32.136] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:32.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:80:0) after 1m0s
[09:20:32.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:80:0)
[09:20:32.179] Timer tick, asking Buildbucket for the build status
[09:20:32.616] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:32.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:81:0) after 1m0s
[09:21:32.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:81:0)
[09:21:32.709] Timer tick, asking Buildbucket for the build status
[09:21:32.855] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:32.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:82:0) after 1m0s
[09:22:32.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:82:0)
[09:22:32.882] Timer tick, asking Buildbucket for the build status
[09:22:33.293] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:33.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:83:0) after 1m0s
[09:23:33.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:83:0)
[09:23:33.416] Timer tick, asking Buildbucket for the build status
[09:23:33.829] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:33.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:84:0) after 1m0s
[09:24:33.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:84:0)
[09:24:33.903] Timer tick, asking Buildbucket for the build status
[09:24:34.347] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:34.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:85:0) after 1m0s
[09:25:34.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:85:0)
[09:25:34.384] Timer tick, asking Buildbucket for the build status
[09:25:35.175] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:35.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:86:0) after 1m0s
[09:26:41.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:86:0)
[09:26:41.093] Timer tick, asking Buildbucket for the build status
[09:26:41.432] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:41.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:87:0) after 1m0s
[09:27:41.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:87:0)
[09:27:41.719] Timer tick, asking Buildbucket for the build status
[09:27:42.076] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:42.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:88:0) after 1m0s
[09:28:42.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:88:0)
[09:28:42.148] Timer tick, asking Buildbucket for the build status
[09:28:42.281] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:42.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:89:0) after 1m0s
[09:29:42.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:89:0)
[09:29:42.312] Timer tick, asking Buildbucket for the build status
[09:29:42.641] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:42.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:90:0) after 1m0s
[09:30:42.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:90:0)
[09:30:42.672] Timer tick, asking Buildbucket for the build status
[09:30:42.941] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:42.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:91:0) after 1m0s
[09:31:43.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:91:0)
[09:31:43.075] Timer tick, asking Buildbucket for the build status
[09:31:43.274] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:43.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:92:0) after 1m0s
[09:32:43.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:92:0)
[09:32:43.307] Timer tick, asking Buildbucket for the build status
[09:32:43.453] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:43.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:93:0) after 1m0s
[09:33:43.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:93:0)
[09:33:43.562] Timer tick, asking Buildbucket for the build status
[09:33:43.979] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:43.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:94:0) after 1m0s
[09:34:44.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:94:0)
[09:34:44.016] Timer tick, asking Buildbucket for the build status
[09:34:44.250] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:44.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:95:0) after 1m0s
[09:35:44.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:95:0)
[09:35:44.297] Timer tick, asking Buildbucket for the build status
[09:35:44.751] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:44.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:96:0) after 1m0s
[09:36:44.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:96:0)
[09:36:44.795] Timer tick, asking Buildbucket for the build status
[09:36:44.939] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:44.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:97:0) after 1m0s
[09:37:45.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:97:0)
[09:37:45.149] Timer tick, asking Buildbucket for the build status
[09:37:45.447] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:45.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:98:0) after 1m0s
[09:38:45.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:98:0)
[09:38:45.499] Timer tick, asking Buildbucket for the build status
[09:38:45.712] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:45.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:99:0) after 1m0s
[09:39:45.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:99:0)
[09:39:45.752] Timer tick, asking Buildbucket for the build status
[09:39:48.509] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:48.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:100:0) after 1m0s
[09:40:48.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:100:0)
[09:40:48.619] Timer tick, asking Buildbucket for the build status
[09:40:48.824] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:48.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:101:0) after 1m0s
[09:41:48.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:101:0)
[09:41:48.886] Timer tick, asking Buildbucket for the build status
[09:41:49.074] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:49.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:102:0) after 1m0s
[09:42:49.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:102:0)
[09:42:49.134] Timer tick, asking Buildbucket for the build status
[09:42:49.263] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:49.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:103:0) after 1m0s
[09:43:49.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:103:0)
[09:43:49.304] Timer tick, asking Buildbucket for the build status
[09:43:49.441] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:49.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:104:0) after 1m0s
[09:44:49.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:104:0)
[09:44:49.516] Timer tick, asking Buildbucket for the build status
[09:44:49.901] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:49.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:105:0) after 1m0s
[09:45:49.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:105:0)
[09:45:49.965] Timer tick, asking Buildbucket for the build status
[09:45:52.600] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:52.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:106:0) after 1m0s
[09:46:52.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:106:0)
[09:46:52.684] Timer tick, asking Buildbucket for the build status
[09:46:52.841] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:52.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:107:0) after 1m0s
[09:47:52.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:107:0)
[09:47:52.956] Timer tick, asking Buildbucket for the build status
[09:47:53.944] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:53.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:108:0) after 1m0s
[09:48:54.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:108:0)
[09:48:54.077] Timer tick, asking Buildbucket for the build status
[09:48:54.385] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:54.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:109:0) after 1m0s
[09:49:54.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:109:0)
[09:49:54.424] Timer tick, asking Buildbucket for the build status
[09:49:55.042] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:55.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:110:0) after 1m0s
[09:50:55.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:110:0)
[09:50:55.312] Timer tick, asking Buildbucket for the build status
[09:50:55.787] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:55.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:111:0) after 1m0s
[09:51:56.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:111:0)
[09:51:56.030] Timer tick, asking Buildbucket for the build status
[09:51:56.433] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:56.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:112:0) after 1m0s
[09:52:56.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:112:0)
[09:52:56.611] Timer tick, asking Buildbucket for the build status
[09:52:56.950] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:56.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:113:0) after 1m0s
[09:53:57.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:113:0)
[09:53:57.013] Timer tick, asking Buildbucket for the build status
[09:53:57.229] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:57.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:114:0) after 1m0s
[09:54:57.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:114:0)
[09:54:57.256] Timer tick, asking Buildbucket for the build status
[09:54:59.186] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:59.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:115:0) after 1m0s
[09:55:59.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:115:0)
[09:55:59.438] Timer tick, asking Buildbucket for the build status
[09:56:03.128] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:03.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:116:0) after 1m0s
[09:57:03.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:116:0)
[09:57:03.321] Timer tick, asking Buildbucket for the build status
[09:57:03.458] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:03.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:117:0) after 1m0s
[09:58:03.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:117:0)
[09:58:03.612] Timer tick, asking Buildbucket for the build status
[09:58:03.918] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:03.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:118:0) after 1m0s
[09:59:04.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:118:0)
[09:59:04.160] Timer tick, asking Buildbucket for the build status
[09:59:04.685] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:04.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:119:0) after 1m0s
[10:00:04.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:119:0)
[10:00:04.679] Timer tick, asking Buildbucket for the build status
[10:00:05.636] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:05.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:120:0) after 1m0s
[10:01:05.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:120:0)
[10:01:05.840] Timer tick, asking Buildbucket for the build status
[10:01:06.300] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:06.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:121:0) after 1m0s
[10:02:06.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:121:0)
[10:02:06.496] Timer tick, asking Buildbucket for the build status
[10:02:07.076] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:07.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:122:0) after 1m0s
[10:03:07.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:122:0)
[10:03:07.182] Timer tick, asking Buildbucket for the build status
[10:03:07.684] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:07.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:123:0) after 1m0s
[10:04:07.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:123:0)
[10:04:07.743] Timer tick, asking Buildbucket for the build status
[10:04:08.036] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:08.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:124:0) after 1m0s
[10:05:08.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:124:0)
[10:05:08.119] Timer tick, asking Buildbucket for the build status
[10:05:08.277] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:08.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:125:0) after 1m0s
[10:06:08.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:125:0)
[10:06:08.270] Timer tick, asking Buildbucket for the build status
[10:06:09.622] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:09.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:126:0) after 1m0s
[10:07:09.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:126:0)
[10:07:09.689] Timer tick, asking Buildbucket for the build status
[10:07:09.900] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:09.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:127:0) after 1m0s
[10:08:09.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:127:0)
[10:08:09.932] Timer tick, asking Buildbucket for the build status
[10:08:10.317] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:10.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:128:0) after 1m0s
[10:09:10.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:128:0)
[10:09:10.407] Timer tick, asking Buildbucket for the build status
[10:09:10.696] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:10.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:129:0) after 1m0s
[10:10:10.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:129:0)
[10:10:10.884] Timer tick, asking Buildbucket for the build status
[10:10:11.180] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:11.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:130:0) after 1m0s
[10:11:11.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:130:0)
[10:11:11.274] Timer tick, asking Buildbucket for the build status
[10:11:11.669] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:11.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:131:0) after 1m0s
[10:12:11.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:131:0)
[10:12:11.855] Timer tick, asking Buildbucket for the build status
[10:12:12.233] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:12.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:132:0) after 1m0s
[10:13:12.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:132:0)
[10:13:12.340] Timer tick, asking Buildbucket for the build status
[10:13:12.527] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:12.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:133:0) after 1m0s
[10:14:12.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:133:0)
[10:14:12.686] Timer tick, asking Buildbucket for the build status
[10:14:12.906] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:12.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:134:0) after 1m0s
[10:15:13.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:134:0)
[10:15:13.341] Timer tick, asking Buildbucket for the build status
[10:15:13.539] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:13.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:135:0) after 1m0s
[10:16:13.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:135:0)
[10:16:13.962] Timer tick, asking Buildbucket for the build status
[10:16:14.176] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:14.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:136:0) after 1m0s
[10:17:14.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:136:0)
[10:17:14.392] Timer tick, asking Buildbucket for the build status
[10:17:14.623] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:14.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:137:0) after 1m0s
[10:18:14.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:137:0)
[10:18:14.965] Timer tick, asking Buildbucket for the build status
[10:18:15.394] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:15.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:138:0) after 1m0s
[10:19:15.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:138:0)
[10:19:15.474] Timer tick, asking Buildbucket for the build status
[10:19:15.793] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:15.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:139:0) after 1m0s
[10:20:15.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:139:0)
[10:20:15.872] Timer tick, asking Buildbucket for the build status
[10:20:17.453] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:17.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:140:0) after 1m0s
[10:21:17.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:140:0)
[10:21:17.727] Timer tick, asking Buildbucket for the build status
[10:21:17.884] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:17.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:141:0) after 1m0s
[10:22:17.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:141:0)
[10:22:17.880] Timer tick, asking Buildbucket for the build status
[10:22:18.039] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:18.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:142:0) after 1m0s
[10:23:18.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:142:0)
[10:23:18.143] Timer tick, asking Buildbucket for the build status
[10:23:19.613] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:19.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:143:0) after 1m0s
[10:24:19.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:143:0)
[10:24:19.651] Timer tick, asking Buildbucket for the build status
[10:24:19.897] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:19.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:144:0) after 1m0s
[10:25:19.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:144:0)
[10:25:19.938] Timer tick, asking Buildbucket for the build status
[10:25:20.445] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:20.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:145:0) after 1m0s
[10:26:20.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:145:0)
[10:26:20.507] Timer tick, asking Buildbucket for the build status
[10:26:20.946] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:20.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:146:0) after 1m0s
[10:27:20.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:146:0)
[10:27:20.989] Timer tick, asking Buildbucket for the build status
[10:27:21.274] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:21.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:147:0) after 1m0s
[10:28:21.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:147:0)
[10:28:21.414] Timer tick, asking Buildbucket for the build status
[10:28:22.483] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:22.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:148:0) after 1m0s
[10:29:22.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:148:0)
[10:29:22.519] Timer tick, asking Buildbucket for the build status
[10:29:22.872] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:22.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:149:0) after 1m0s
[10:30:23.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:149:0)
[10:30:23.044] Timer tick, asking Buildbucket for the build status
[10:30:23.312] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:23.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:150:0) after 1m0s
[10:31:23.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:150:0)
[10:31:23.448] Timer tick, asking Buildbucket for the build status
[10:31:23.706] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:23.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:151:0) after 1m0s
[10:32:23.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:151:0)
[10:32:23.922] Timer tick, asking Buildbucket for the build status
[10:32:24.125] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:24.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:152:0) after 1m0s
[10:33:24.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:152:0)
[10:33:24.241] Timer tick, asking Buildbucket for the build status
[10:33:24.544] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:24.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:153:0) after 1m0s
[10:34:24.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:153:0)
[10:34:24.714] Timer tick, asking Buildbucket for the build status
[10:34:24.903] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:24.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:154:0) after 1m0s
[10:35:24.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:154:0)
[10:35:24.953] Timer tick, asking Buildbucket for the build status
[10:35:25.153] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:25.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:155:0) after 1m0s
[10:36:25.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:155:0)
[10:36:25.276] Timer tick, asking Buildbucket for the build status
[10:36:26.017] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:26.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:156:0) after 1m0s
[10:37:26.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:156:0)
[10:37:26.149] Timer tick, asking Buildbucket for the build status
[10:37:26.347] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:26.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:157:0) after 1m0s
[10:38:26.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:157:0)
[10:38:26.387] Timer tick, asking Buildbucket for the build status
[10:38:28.162] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:28.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:158:0) after 1m0s
[10:39:28.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:158:0)
[10:39:28.324] Timer tick, asking Buildbucket for the build status
[10:39:28.885] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:28.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:159:0) after 1m0s
[10:40:28.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:159:0)
[10:40:28.945] Timer tick, asking Buildbucket for the build status
[10:40:29.112] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:29.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:160:0) after 1m0s
[10:41:29.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:160:0)
[10:41:29.165] Timer tick, asking Buildbucket for the build status
[10:41:29.557] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:29.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:161:0) after 1m0s
[10:42:29.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:161:0)
[10:42:29.690] Timer tick, asking Buildbucket for the build status
[10:42:31.182] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:31.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:162:0) after 1m0s
[10:43:31.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:162:0)
[10:43:31.267] Timer tick, asking Buildbucket for the build status
[10:43:31.512] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:31.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:163:0) after 1m0s
[10:44:31.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:163:0)
[10:44:31.573] Timer tick, asking Buildbucket for the build status
[10:44:32.128] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:32.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:164:0) after 1m0s
[10:45:32.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:164:0)
[10:45:32.223] Timer tick, asking Buildbucket for the build status
[10:45:32.789] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:32.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:165:0) after 1m0s
[10:46:32.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:165:0)
[10:46:32.823] Timer tick, asking Buildbucket for the build status
[10:46:36.864] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:36.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:166:0) after 1m0s
[10:47:36.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:166:0)
[10:47:36.899] Timer tick, asking Buildbucket for the build status
[10:47:37.066] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:37.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:167:0) after 1m0s
[10:48:37.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:167:0)
[10:48:37.208] Timer tick, asking Buildbucket for the build status
[10:48:37.841] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:37.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:168:0) after 1m0s
[10:49:38.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:168:0)
[10:49:38.061] Timer tick, asking Buildbucket for the build status
[10:49:38.266] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:38.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:169:0) after 1m0s
[10:50:38.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:169:0)
[10:50:38.327] Timer tick, asking Buildbucket for the build status
[10:50:38.454] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:38.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:170:0) after 1m0s
[10:51:38.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:170:0)
[10:51:38.611] Timer tick, asking Buildbucket for the build status
[10:51:39.527] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:39.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:171:0) after 1m0s
[10:52:39.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:171:0)
[10:52:39.678] Timer tick, asking Buildbucket for the build status
[10:52:39.815] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:39.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:172:0) after 1m0s
[10:53:39.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:172:0)
[10:53:39.957] Timer tick, asking Buildbucket for the build status
[10:53:40.251] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:40.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:173:0) after 1m0s
[10:54:40.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:173:0)
[10:54:40.721] Timer tick, asking Buildbucket for the build status
[10:54:40.844] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:40.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:174:0) after 1m0s
[10:55:41.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:174:0)
[10:55:41.239] Timer tick, asking Buildbucket for the build status
[10:55:41.593] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:41.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:175:0) after 1m0s
[10:56:41.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:175:0)
[10:56:41.656] Timer tick, asking Buildbucket for the build status
[10:56:43.095] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:43.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:176:0) after 1m0s
[10:57:43.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:176:0)
[10:57:43.191] Timer tick, asking Buildbucket for the build status
[10:57:43.353] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:43.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:177:0) after 1m0s
[10:58:43.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:177:0)
[10:58:43.384] Timer tick, asking Buildbucket for the build status
[10:58:43.658] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:43.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:178:0) after 1m0s
[10:59:43.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:178:0)
[10:59:43.687] Timer tick, asking Buildbucket for the build status
[10:59:43.850] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:43.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:179:0) after 1m0s
[11:00:43.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:179:0)
[11:00:43.927] Timer tick, asking Buildbucket for the build status
[11:00:44.097] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:44.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:180:0) after 1m0s
[11:01:44.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:180:0)
[11:01:44.234] Timer tick, asking Buildbucket for the build status
[11:01:44.392] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:44.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:181:0) after 1m0s
[11:02:44.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:181:0)
[11:02:44.456] Timer tick, asking Buildbucket for the build status
[11:02:44.592] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:44.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:182:0) after 1m0s
[11:03:44.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:182:0)
[11:03:44.638] Timer tick, asking Buildbucket for the build status
[11:03:45.065] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:45.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:183:0) after 1m0s
[11:04:45.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:183:0)
[11:04:45.135] Timer tick, asking Buildbucket for the build status
[11:04:45.293] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:45.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:184:0) after 1m0s
[11:05:45.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:184:0)
[11:05:45.333] Timer tick, asking Buildbucket for the build status
[11:05:45.606] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:45.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:185:0) after 1m0s
[11:06:45.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:185:0)
[11:06:45.641] Timer tick, asking Buildbucket for the build status
[11:06:45.820] Build 8878801464599250800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:45.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044231526981053664:186:0) after 1m0s
[11:07:15.229] Received PubSub notification, asking Buildbucket for the build status
[11:07:15.325] Build 8878801464599250800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:07:15.325] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1590923233830679",
    "created_by": "project:infra",
    "created_ts": "1590912104949092",
    "id": "8878801464599250800",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4161.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"6bfa39b1-bde6-464d-b608-74f61e4a5753\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4161.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"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-bqlo\"], \"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\": [\"5142-e16b32c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1590912165318944",
    "status": "COMPLETED",
    "status_changed_ts": "1590923234213295",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9044231526981053664",
      "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/8878801464599250800/+/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:4c8291c5f19a6410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1590923234228591",
    "url": "https://ci.chromium.org/b/8878801464599250800",
    "utcnow_ts": "1590923235312833"
  }
}
[11:07:15.325] Invocation finished in 3h5m32.031876899s with status SUCCEEDED