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": "89.0.4349.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[17:34:09.417] New invocation is queued and will start shortly
[17:34:09.417] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[17:34:10.687] Starting the invocation (attempt 1)
[17:34:10.722] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:34:10.722] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:34:10.722] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9026891491894628560",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4349.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"108c766c-283b-4ef8-af6b-5f2e3b4ad1ef\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4349.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:9026891491894628560",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[17:34:11.381] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1607448850823894",
    "id": "8861461429757457216",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4349.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"108c766c-283b-4ef8-af6b-5f2e3b4ad1ef\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4349.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1607448851221808",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026891491894628560",
      "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/8861461429757457216/+/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": "1607448851222017",
    "url": "https://ci.chromium.org/b/8861461429757457216",
    "utcnow_ts": "1607448851371886"
  }
}
[17:34:11.381] Task URL: https://ci.chromium.org/b/8861461429757457216
[17:34:11.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:2:0) after 1m0s
[17:34:47.773] Received PubSub notification, asking Buildbucket for the build status
[17:34:47.855] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:11.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:2:0)
[17:35:11.401] Timer tick, asking Buildbucket for the build status
[17:35:11.482] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:11.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:4:0) after 1m0s
[17:36:11.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:4:0)
[17:36:11.584] Timer tick, asking Buildbucket for the build status
[17:36:11.718] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:11.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:5:0) after 1m0s
[17:37:11.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:5:0)
[17:37:11.782] Timer tick, asking Buildbucket for the build status
[17:37:11.888] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:11.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:6:0) after 1m0s
[17:38:11.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:6:0)
[17:38:11.938] Timer tick, asking Buildbucket for the build status
[17:38:12.164] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:12.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:7:0) after 1m0s
[17:39:12.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:7:0)
[17:39:12.191] Timer tick, asking Buildbucket for the build status
[17:39:12.322] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:12.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:8:0) after 1m0s
[17:40:12.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:8:0)
[17:40:12.320] Timer tick, asking Buildbucket for the build status
[17:40:12.436] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:12.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:9:0) after 1m0s
[17:41:12.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:9:0)
[17:41:12.493] Timer tick, asking Buildbucket for the build status
[17:41:12.559] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:12.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:10:0) after 1m0s
[17:42:12.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:10:0)
[17:42:12.551] Timer tick, asking Buildbucket for the build status
[17:42:12.667] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:12.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:11:0) after 1m0s
[17:43:12.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:11:0)
[17:43:12.996] Timer tick, asking Buildbucket for the build status
[17:43:13.068] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:13.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:12:0) after 1m0s
[17:44:13.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:12:0)
[17:44:13.180] Timer tick, asking Buildbucket for the build status
[17:44:13.331] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:13.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:13:0) after 1m0s
[17:45:13.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:13:0)
[17:45:13.495] Timer tick, asking Buildbucket for the build status
[17:45:13.556] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:13.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:14:0) after 1m0s
[17:46:13.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:14:0)
[17:46:13.779] Timer tick, asking Buildbucket for the build status
[17:46:13.867] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:13.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:15:0) after 1m0s
[17:47:13.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:15:0)
[17:47:13.945] Timer tick, asking Buildbucket for the build status
[17:47:14.012] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:14.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:16:0) after 1m0s
[17:48:14.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:16:0)
[17:48:14.046] Timer tick, asking Buildbucket for the build status
[17:48:14.114] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:14.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:17:0) after 1m0s
[17:49:14.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:17:0)
[17:49:14.274] Timer tick, asking Buildbucket for the build status
[17:49:14.455] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:14.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:18:0) after 1m0s
[17:50:14.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:18:0)
[17:50:14.510] Timer tick, asking Buildbucket for the build status
[17:50:14.594] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:14.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:19:0) after 1m0s
[17:51:14.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:19:0)
[17:51:14.738] Timer tick, asking Buildbucket for the build status
[17:51:14.806] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:14.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:20:0) after 1m0s
[17:52:14.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:20:0)
[17:52:14.815] Timer tick, asking Buildbucket for the build status
[17:52:14.885] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:14.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:21:0) after 1m0s
[17:53:15.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:21:0)
[17:53:15.053] Timer tick, asking Buildbucket for the build status
[17:53:15.122] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:15.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:22:0) after 1m0s
[17:54:15.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:22:0)
[17:54:15.225] Timer tick, asking Buildbucket for the build status
[17:54:15.355] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:15.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:23:0) after 1m0s
[17:55:15.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:23:0)
[17:55:15.387] Timer tick, asking Buildbucket for the build status
[17:55:15.559] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:15.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:24:0) after 1m0s
[17:56:15.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:24:0)
[17:56:15.595] Timer tick, asking Buildbucket for the build status
[17:56:15.648] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:15.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:25:0) after 1m0s
[17:57:15.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:25:0)
[17:57:15.723] Timer tick, asking Buildbucket for the build status
[17:57:15.776] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:15.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:26:0) after 1m0s
[17:58:15.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:26:0)
[17:58:15.789] Timer tick, asking Buildbucket for the build status
[17:58:15.848] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:15.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:27:0) after 1m0s
[17:59:15.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:27:0)
[17:59:15.934] Timer tick, asking Buildbucket for the build status
[17:59:16.047] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:16.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:28:0) after 1m0s
[18:00:16.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:28:0)
[18:00:16.098] Timer tick, asking Buildbucket for the build status
[18:00:16.381] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:16.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:29:0) after 1m0s
[18:01:16.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:29:0)
[18:01:16.375] Timer tick, asking Buildbucket for the build status
[18:01:16.442] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:16.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:30:0) after 1m0s
[18:02:16.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:30:0)
[18:02:16.511] Timer tick, asking Buildbucket for the build status
[18:02:16.647] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:16.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:31:0) after 1m0s
[18:03:16.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:31:0)
[18:03:16.680] Timer tick, asking Buildbucket for the build status
[18:03:16.797] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:16.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:32:0) after 1m0s
[18:04:17.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:32:0)
[18:04:17.759] Timer tick, asking Buildbucket for the build status
[18:04:18.230] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:18.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:33:0) after 1m0s
[18:05:18.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:33:0)
[18:05:18.552] Timer tick, asking Buildbucket for the build status
[18:05:18.712] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:18.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:34:0) after 1m0s
[18:06:18.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:34:0)
[18:06:18.872] Timer tick, asking Buildbucket for the build status
[18:06:19.030] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:19.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:35:0) after 1m0s
[18:07:19.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:35:0)
[18:07:19.107] Timer tick, asking Buildbucket for the build status
[18:07:19.207] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:19.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:36:0) after 1m0s
[18:08:19.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:36:0)
[18:08:19.299] Timer tick, asking Buildbucket for the build status
[18:08:19.369] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:19.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:37:0) after 1m0s
[18:09:19.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:37:0)
[18:09:19.431] Timer tick, asking Buildbucket for the build status
[18:09:19.548] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:19.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:38:0) after 1m0s
[18:10:19.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:38:0)
[18:10:19.687] Timer tick, asking Buildbucket for the build status
[18:10:19.749] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:19.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:39:0) after 1m0s
[18:11:19.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:39:0)
[18:11:19.784] Timer tick, asking Buildbucket for the build status
[18:11:19.922] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:19.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:40:0) after 1m0s
[18:12:19.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:40:0)
[18:12:19.931] Timer tick, asking Buildbucket for the build status
[18:12:19.994] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:19.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:41:0) after 1m0s
[18:13:20.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:41:0)
[18:13:20.051] Timer tick, asking Buildbucket for the build status
[18:13:20.263] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:20.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:42:0) after 1m0s
[18:14:20.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:42:0)
[18:14:20.358] Timer tick, asking Buildbucket for the build status
[18:14:20.816] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:20.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:43:0) after 1m0s
[18:15:20.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:43:0)
[18:15:20.913] Timer tick, asking Buildbucket for the build status
[18:15:20.968] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:20.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:44:0) after 1m0s
[18:16:20.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:44:0)
[18:16:20.985] Timer tick, asking Buildbucket for the build status
[18:16:21.133] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:21.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:45:0) after 1m0s
[18:17:21.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:45:0)
[18:17:21.179] Timer tick, asking Buildbucket for the build status
[18:17:21.268] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:21.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:46:0) after 1m0s
[18:18:21.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:46:0)
[18:18:21.311] Timer tick, asking Buildbucket for the build status
[18:18:21.373] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:21.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:47:0) after 1m0s
[18:19:21.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:47:0)
[18:19:21.439] Timer tick, asking Buildbucket for the build status
[18:19:21.585] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:21.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:48:0) after 1m0s
[18:20:21.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:48:0)
[18:20:21.571] Timer tick, asking Buildbucket for the build status
[18:20:21.646] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:21.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:49:0) after 1m0s
[18:21:21.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:49:0)
[18:21:21.796] Timer tick, asking Buildbucket for the build status
[18:21:21.864] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:21.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:50:0) after 1m0s
[18:22:22.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:50:0)
[18:22:22.005] Timer tick, asking Buildbucket for the build status
[18:22:22.086] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:22.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:51:0) after 1m0s
[18:23:22.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:51:0)
[18:23:22.180] Timer tick, asking Buildbucket for the build status
[18:23:22.524] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:22.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:52:0) after 1m0s
[18:24:22.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:52:0)
[18:24:22.543] Timer tick, asking Buildbucket for the build status
[18:24:22.669] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:22.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:53:0) after 1m0s
[18:25:22.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:53:0)
[18:25:22.697] Timer tick, asking Buildbucket for the build status
[18:25:23.489] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:23.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:54:0) after 1m0s
[18:26:23.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:54:0)
[18:26:23.536] Timer tick, asking Buildbucket for the build status
[18:26:23.606] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:23.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:55:0) after 1m0s
[18:27:23.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:55:0)
[18:27:23.659] Timer tick, asking Buildbucket for the build status
[18:27:23.752] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:23.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:56:0) after 1m0s
[18:28:23.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:56:0)
[18:28:23.887] Timer tick, asking Buildbucket for the build status
[18:28:23.949] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:23.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:57:0) after 1m0s
[18:29:24.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:57:0)
[18:29:24.055] Timer tick, asking Buildbucket for the build status
[18:29:24.186] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:24.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:58:0) after 1m0s
[18:30:24.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:58:0)
[18:30:24.251] Timer tick, asking Buildbucket for the build status
[18:30:24.309] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:24.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:59:0) after 1m0s
[18:31:24.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:59:0)
[18:31:24.325] Timer tick, asking Buildbucket for the build status
[18:31:24.509] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:24.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:60:0) after 1m0s
[18:32:24.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:60:0)
[18:32:24.614] Timer tick, asking Buildbucket for the build status
[18:32:24.706] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:24.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:61:0) after 1m0s
[18:33:24.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:61:0)
[18:33:24.725] Timer tick, asking Buildbucket for the build status
[18:33:24.795] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:24.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:62:0) after 1m0s
[18:34:24.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:62:0)
[18:34:24.815] Timer tick, asking Buildbucket for the build status
[18:34:24.929] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:24.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:63:0) after 1m0s
[18:35:24.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:63:0)
[18:35:24.951] Timer tick, asking Buildbucket for the build status
[18:35:25.231] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:25.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:64:0) after 1m0s
[18:36:25.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:64:0)
[18:36:25.251] Timer tick, asking Buildbucket for the build status
[18:36:25.312] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:25.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:65:0) after 1m0s
[18:37:25.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:65:0)
[18:37:25.331] Timer tick, asking Buildbucket for the build status
[18:37:25.393] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:25.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:66:0) after 1m0s
[18:38:25.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:66:0)
[18:38:25.415] Timer tick, asking Buildbucket for the build status
[18:38:25.481] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:25.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:67:0) after 1m0s
[18:39:25.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:67:0)
[18:39:25.589] Timer tick, asking Buildbucket for the build status
[18:39:25.690] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:25.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:68:0) after 1m0s
[18:40:25.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:68:0)
[18:40:25.735] Timer tick, asking Buildbucket for the build status
[18:40:25.790] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:25.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:69:0) after 1m0s
[18:41:25.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:69:0)
[18:41:25.829] Timer tick, asking Buildbucket for the build status
[18:41:25.892] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:25.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:70:0) after 1m0s
[18:42:25.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:70:0)
[18:42:25.944] Timer tick, asking Buildbucket for the build status
[18:42:26.013] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:26.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:71:0) after 1m0s
[18:43:26.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:71:0)
[18:43:26.041] Timer tick, asking Buildbucket for the build status
[18:43:26.763] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:26.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:72:0) after 1m0s
[18:44:26.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:72:0)
[18:44:26.763] Timer tick, asking Buildbucket for the build status
[18:44:27.155] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:27.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:73:0) after 1m0s
[18:45:27.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:73:0)
[18:45:27.192] Timer tick, asking Buildbucket for the build status
[18:45:27.670] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:27.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:74:0) after 1m0s
[18:46:27.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:74:0)
[18:46:27.742] Timer tick, asking Buildbucket for the build status
[18:46:27.797] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:27.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:75:0) after 1m0s
[18:47:27.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:75:0)
[18:47:27.842] Timer tick, asking Buildbucket for the build status
[18:47:27.910] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:27.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:76:0) after 1m0s
[18:48:27.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:76:0)
[18:48:27.957] Timer tick, asking Buildbucket for the build status
[18:48:28.021] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:28.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:77:0) after 1m0s
[18:49:28.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:77:0)
[18:49:28.024] Timer tick, asking Buildbucket for the build status
[18:49:28.226] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:28.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:78:0) after 1m0s
[18:50:28.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:78:0)
[18:50:28.233] Timer tick, asking Buildbucket for the build status
[18:50:28.301] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:28.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:79:0) after 1m0s
[18:51:28.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:79:0)
[18:51:28.326] Timer tick, asking Buildbucket for the build status
[18:51:29.234] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:29.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:80:0) after 1m0s
[18:52:29.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:80:0)
[18:52:29.256] Timer tick, asking Buildbucket for the build status
[18:52:29.344] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:29.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:81:0) after 1m0s
[18:53:29.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:81:0)
[18:53:29.366] Timer tick, asking Buildbucket for the build status
[18:53:29.506] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:29.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:82:0) after 1m0s
[18:54:29.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:82:0)
[18:54:29.520] Timer tick, asking Buildbucket for the build status
[18:54:29.990] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:29.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:83:0) after 1m0s
[18:55:29.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:83:0)
[18:55:29.984] Timer tick, asking Buildbucket for the build status
[18:55:30.048] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:30.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:84:0) after 1m0s
[18:56:30.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:84:0)
[18:56:30.096] Timer tick, asking Buildbucket for the build status
[18:56:30.330] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:30.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:85:0) after 1m0s
[18:57:30.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:85:0)
[18:57:30.356] Timer tick, asking Buildbucket for the build status
[18:57:30.435] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:30.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:86:0) after 1m0s
[18:58:30.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:86:0)
[18:58:30.468] Timer tick, asking Buildbucket for the build status
[18:58:30.781] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:30.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:87:0) after 1m0s
[18:59:30.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:87:0)
[18:59:30.818] Timer tick, asking Buildbucket for the build status
[18:59:30.919] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:30.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:88:0) after 1m0s
[19:00:30.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:88:0)
[19:00:30.956] Timer tick, asking Buildbucket for the build status
[19:00:31.027] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:31.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:89:0) after 1m0s
[19:01:31.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:89:0)
[19:01:31.109] Timer tick, asking Buildbucket for the build status
[19:01:31.313] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:31.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:90:0) after 1m0s
[19:02:31.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:90:0)
[19:02:31.347] Timer tick, asking Buildbucket for the build status
[19:02:31.429] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:31.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:91:0) after 1m0s
[19:03:31.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:91:0)
[19:03:31.481] Timer tick, asking Buildbucket for the build status
[19:03:31.564] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:31.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:92:0) after 1m0s
[19:04:31.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:92:0)
[19:04:31.599] Timer tick, asking Buildbucket for the build status
[19:04:31.722] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:31.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:93:0) after 1m0s
[19:05:31.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:93:0)
[19:05:31.795] Timer tick, asking Buildbucket for the build status
[19:05:31.874] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:31.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:94:0) after 1m0s
[19:06:31.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:94:0)
[19:06:31.998] Timer tick, asking Buildbucket for the build status
[19:06:32.057] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:32.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:95:0) after 1m0s
[19:07:32.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:95:0)
[19:07:32.141] Timer tick, asking Buildbucket for the build status
[19:07:32.211] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:32.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:96:0) after 1m0s
[19:08:32.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:96:0)
[19:08:32.235] Timer tick, asking Buildbucket for the build status
[19:08:32.301] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:32.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:97:0) after 1m0s
[19:09:32.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:97:0)
[19:09:32.370] Timer tick, asking Buildbucket for the build status
[19:09:32.489] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:32.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:98:0) after 1m0s
[19:10:32.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:98:0)
[19:10:32.580] Timer tick, asking Buildbucket for the build status
[19:10:32.653] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:32.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:99:0) after 1m0s
[19:11:32.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:99:0)
[19:11:32.709] Timer tick, asking Buildbucket for the build status
[19:11:32.776] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:32.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:100:0) after 1m0s
[19:12:32.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:100:0)
[19:12:32.779] Timer tick, asking Buildbucket for the build status
[19:12:32.899] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:32.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:101:0) after 1m0s
[19:13:32.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:101:0)
[19:13:32.874] Timer tick, asking Buildbucket for the build status
[19:13:33.211] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:33.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:102:0) after 1m0s
[19:14:33.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:102:0)
[19:14:33.288] Timer tick, asking Buildbucket for the build status
[19:14:34.429] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:34.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:103:0) after 1m0s
[19:15:34.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:103:0)
[19:15:34.462] Timer tick, asking Buildbucket for the build status
[19:15:35.567] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:35.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:104:0) after 1m0s
[19:16:35.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:104:0)
[19:16:35.587] Timer tick, asking Buildbucket for the build status
[19:16:35.659] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:35.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:105:0) after 1m0s
[19:17:35.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:105:0)
[19:17:35.919] Timer tick, asking Buildbucket for the build status
[19:17:36.235] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:36.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:106:0) after 1m0s
[19:18:36.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:106:0)
[19:18:36.340] Timer tick, asking Buildbucket for the build status
[19:18:36.400] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:36.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:107:0) after 1m0s
[19:19:36.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:107:0)
[19:19:36.430] Timer tick, asking Buildbucket for the build status
[19:19:36.538] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:36.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:108:0) after 1m0s
[19:20:36.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:108:0)
[19:20:36.549] Timer tick, asking Buildbucket for the build status
[19:20:36.639] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:36.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:109:0) after 1m0s
[19:21:36.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:109:0)
[19:21:36.717] Timer tick, asking Buildbucket for the build status
[19:21:36.784] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:36.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:110:0) after 1m0s
[19:22:37.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:110:0)
[19:22:37.081] Timer tick, asking Buildbucket for the build status
[19:22:40.680] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:40.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:111:0) after 1m0s
[19:23:40.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:111:0)
[19:23:40.828] Timer tick, asking Buildbucket for the build status
[19:23:40.960] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:40.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:112:0) after 1m0s
[19:24:41.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:112:0)
[19:24:41.068] Timer tick, asking Buildbucket for the build status
[19:24:41.378] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:41.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:113:0) after 1m0s
[19:25:41.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:113:0)
[19:25:41.491] Timer tick, asking Buildbucket for the build status
[19:25:41.557] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:41.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:114:0) after 1m0s
[19:26:41.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:114:0)
[19:26:41.753] Timer tick, asking Buildbucket for the build status
[19:26:41.818] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:41.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:115:0) after 1m0s
[19:27:41.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:115:0)
[19:27:41.907] Timer tick, asking Buildbucket for the build status
[19:27:42.014] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:42.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:116:0) after 1m0s
[19:28:42.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:116:0)
[19:28:42.093] Timer tick, asking Buildbucket for the build status
[19:28:42.365] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:42.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:117:0) after 1m0s
[19:29:42.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:117:0)
[19:29:42.490] Timer tick, asking Buildbucket for the build status
[19:29:43.004] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:43.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:118:0) after 1m0s
[19:30:43.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:118:0)
[19:30:43.216] Timer tick, asking Buildbucket for the build status
[19:30:43.528] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:43.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:119:0) after 1m0s
[19:31:43.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:119:0)
[19:31:43.531] Timer tick, asking Buildbucket for the build status
[19:31:43.600] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:43.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:120:0) after 1m0s
[19:32:43.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:120:0)
[19:32:43.650] Timer tick, asking Buildbucket for the build status
[19:32:43.907] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:43.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:121:0) after 1m0s
[19:33:43.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:121:0)
[19:33:43.957] Timer tick, asking Buildbucket for the build status
[19:33:44.090] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:44.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:122:0) after 1m0s
[19:34:44.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:122:0)
[19:34:44.110] Timer tick, asking Buildbucket for the build status
[19:34:44.232] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:44.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:123:0) after 1m0s
[19:35:44.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:123:0)
[19:35:44.318] Timer tick, asking Buildbucket for the build status
[19:35:44.372] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:44.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:124:0) after 1m0s
[19:36:44.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:124:0)
[19:36:44.411] Timer tick, asking Buildbucket for the build status
[19:36:44.468] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:44.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:125:0) after 1m0s
[19:37:44.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:125:0)
[19:37:44.492] Timer tick, asking Buildbucket for the build status
[19:37:44.625] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:44.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:126:0) after 1m0s
[19:38:44.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:126:0)
[19:38:44.619] Timer tick, asking Buildbucket for the build status
[19:38:44.687] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:44.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:127:0) after 1m0s
[19:39:44.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:127:0)
[19:39:44.672] Timer tick, asking Buildbucket for the build status
[19:39:45.023] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:45.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:128:0) after 1m0s
[19:40:45.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:128:0)
[19:40:45.184] Timer tick, asking Buildbucket for the build status
[19:40:45.321] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:45.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:129:0) after 1m0s
[19:41:45.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:129:0)
[19:41:45.498] Timer tick, asking Buildbucket for the build status
[19:41:45.604] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:45.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:130:0) after 1m0s
[19:42:45.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:130:0)
[19:42:45.629] Timer tick, asking Buildbucket for the build status
[19:42:45.712] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:45.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:131:0) after 1m0s
[19:43:45.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:131:0)
[19:43:45.764] Timer tick, asking Buildbucket for the build status
[19:43:45.906] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:45.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:132:0) after 1m0s
[19:44:46.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:132:0)
[19:44:46.050] Timer tick, asking Buildbucket for the build status
[19:44:46.164] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:46.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:133:0) after 1m0s
[19:45:46.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:133:0)
[19:45:46.231] Timer tick, asking Buildbucket for the build status
[19:45:46.634] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:46.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:134:0) after 1m0s
[19:46:46.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:134:0)
[19:46:46.802] Timer tick, asking Buildbucket for the build status
[19:46:46.968] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:46.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:135:0) after 1m0s
[19:47:47.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:135:0)
[19:47:47.039] Timer tick, asking Buildbucket for the build status
[19:47:47.108] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:47.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:136:0) after 1m0s
[19:48:47.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:136:0)
[19:48:47.131] Timer tick, asking Buildbucket for the build status
[19:48:47.903] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:47.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:137:0) after 1m0s
[19:49:47.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:137:0)
[19:49:47.948] Timer tick, asking Buildbucket for the build status
[19:49:48.082] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:48.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:138:0) after 1m0s
[19:50:48.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:138:0)
[19:50:48.097] Timer tick, asking Buildbucket for the build status
[19:50:48.178] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:48.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:139:0) after 1m0s
[19:51:48.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:139:0)
[19:51:48.307] Timer tick, asking Buildbucket for the build status
[19:51:48.404] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:48.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:140:0) after 1m0s
[19:52:48.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:140:0)
[19:52:48.433] Timer tick, asking Buildbucket for the build status
[19:52:48.534] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:48.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:141:0) after 1m0s
[19:53:48.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:141:0)
[19:53:48.545] Timer tick, asking Buildbucket for the build status
[19:53:48.617] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:48.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:142:0) after 1m0s
[19:54:48.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:142:0)
[19:54:48.627] Timer tick, asking Buildbucket for the build status
[19:54:48.714] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:48.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:143:0) after 1m0s
[19:55:48.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:143:0)
[19:55:48.755] Timer tick, asking Buildbucket for the build status
[19:55:48.814] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:48.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:144:0) after 1m0s
[19:56:48.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:144:0)
[19:56:48.872] Timer tick, asking Buildbucket for the build status
[19:56:48.933] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:48.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:145:0) after 1m0s
[19:57:48.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:145:0)
[19:57:48.943] Timer tick, asking Buildbucket for the build status
[19:57:49.004] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:49.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:146:0) after 1m0s
[19:58:49.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:146:0)
[19:58:49.055] Timer tick, asking Buildbucket for the build status
[19:58:49.302] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:49.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:147:0) after 1m0s
[19:59:49.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:147:0)
[19:59:49.383] Timer tick, asking Buildbucket for the build status
[19:59:49.554] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:49.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:148:0) after 1m0s
[20:00:49.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:148:0)
[20:00:49.584] Timer tick, asking Buildbucket for the build status
[20:00:49.646] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:49.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:149:0) after 1m0s
[20:01:49.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:149:0)
[20:01:49.689] Timer tick, asking Buildbucket for the build status
[20:01:49.788] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:49.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:150:0) after 1m0s
[20:02:49.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:150:0)
[20:02:49.870] Timer tick, asking Buildbucket for the build status
[20:02:49.938] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:49.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:151:0) after 1m0s
[20:03:49.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:151:0)
[20:03:49.976] Timer tick, asking Buildbucket for the build status
[20:03:50.052] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:50.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:152:0) after 1m0s
[20:04:50.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:152:0)
[20:04:50.103] Timer tick, asking Buildbucket for the build status
[20:04:50.237] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:50.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:153:0) after 1m0s
[20:05:50.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:153:0)
[20:05:50.282] Timer tick, asking Buildbucket for the build status
[20:05:50.348] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:50.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:154:0) after 1m0s
[20:06:50.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:154:0)
[20:06:50.398] Timer tick, asking Buildbucket for the build status
[20:06:50.522] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:50.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:155:0) after 1m0s
[20:07:50.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:155:0)
[20:07:50.564] Timer tick, asking Buildbucket for the build status
[20:07:50.635] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:50.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:156:0) after 1m0s
[20:08:50.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:156:0)
[20:08:50.660] Timer tick, asking Buildbucket for the build status
[20:08:51.739] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:51.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:157:0) after 1m0s
[20:09:51.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:157:0)
[20:09:51.719] Timer tick, asking Buildbucket for the build status
[20:09:51.859] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:51.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:158:0) after 1m0s
[20:10:51.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:158:0)
[20:10:51.918] Timer tick, asking Buildbucket for the build status
[20:10:51.969] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:51.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:159:0) after 1m0s
[20:11:52.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:159:0)
[20:11:52.023] Timer tick, asking Buildbucket for the build status
[20:11:52.078] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:52.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:160:0) after 1m0s
[20:12:52.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:160:0)
[20:12:52.148] Timer tick, asking Buildbucket for the build status
[20:12:52.209] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:52.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:161:0) after 1m0s
[20:13:52.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:161:0)
[20:13:52.321] Timer tick, asking Buildbucket for the build status
[20:13:52.513] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:52.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:162:0) after 1m0s
[20:14:52.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:162:0)
[20:14:52.590] Timer tick, asking Buildbucket for the build status
[20:14:52.781] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:52.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:163:0) after 1m0s
[20:15:52.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:163:0)
[20:15:52.765] Timer tick, asking Buildbucket for the build status
[20:15:52.830] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:52.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:164:0) after 1m0s
[20:16:53.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:164:0)
[20:16:53.164] Timer tick, asking Buildbucket for the build status
[20:16:53.245] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:53.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:165:0) after 1m0s
[20:17:53.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:165:0)
[20:17:53.470] Timer tick, asking Buildbucket for the build status
[20:17:53.570] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:53.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:166:0) after 1m0s
[20:18:53.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:166:0)
[20:18:53.814] Timer tick, asking Buildbucket for the build status
[20:18:54.932] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:54.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:167:0) after 1m0s
[20:19:54.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:167:0)
[20:19:54.935] Timer tick, asking Buildbucket for the build status
[20:19:55.204] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:55.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:168:0) after 1m0s
[20:20:55.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:168:0)
[20:20:55.282] Timer tick, asking Buildbucket for the build status
[20:20:55.354] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:55.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:169:0) after 1m0s
[20:21:55.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:169:0)
[20:21:55.393] Timer tick, asking Buildbucket for the build status
[20:21:55.542] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:55.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:170:0) after 1m0s
[20:22:55.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:170:0)
[20:22:55.618] Timer tick, asking Buildbucket for the build status
[20:22:55.723] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:55.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:171:0) after 1m0s
[20:23:55.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:171:0)
[20:23:55.758] Timer tick, asking Buildbucket for the build status
[20:23:55.829] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:55.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:172:0) after 1m0s
[20:24:55.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:172:0)
[20:24:55.838] Timer tick, asking Buildbucket for the build status
[20:24:56.040] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:56.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:173:0) after 1m0s
[20:25:56.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:173:0)
[20:25:56.071] Timer tick, asking Buildbucket for the build status
[20:25:56.188] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:56.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:174:0) after 1m0s
[20:26:56.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:174:0)
[20:26:56.259] Timer tick, asking Buildbucket for the build status
[20:26:56.342] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:56.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:175:0) after 1m0s
[20:27:56.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:175:0)
[20:27:56.386] Timer tick, asking Buildbucket for the build status
[20:27:56.519] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:56.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:176:0) after 1m0s
[20:28:56.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:176:0)
[20:28:56.546] Timer tick, asking Buildbucket for the build status
[20:28:58.694] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:58.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:177:0) after 1m0s
[20:29:58.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:177:0)
[20:29:58.909] Timer tick, asking Buildbucket for the build status
[20:29:59.012] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:59.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:178:0) after 1m0s
[20:30:59.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:178:0)
[20:30:59.233] Timer tick, asking Buildbucket for the build status
[20:30:59.325] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:59.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:179:0) after 1m0s
[20:31:59.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:179:0)
[20:31:59.593] Timer tick, asking Buildbucket for the build status
[20:31:59.755] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:59.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:180:0) after 1m0s
[20:32:59.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:180:0)
[20:32:59.824] Timer tick, asking Buildbucket for the build status
[20:32:59.889] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:59.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:181:0) after 1m0s
[20:33:59.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:181:0)
[20:33:59.975] Timer tick, asking Buildbucket for the build status
[20:34:00.196] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:00.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:182:0) after 1m0s
[20:35:00.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:182:0)
[20:35:00.211] Timer tick, asking Buildbucket for the build status
[20:35:00.336] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:00.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:183:0) after 1m0s
[20:36:00.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:183:0)
[20:36:00.370] Timer tick, asking Buildbucket for the build status
[20:36:02.729] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:02.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:184:0) after 1m0s
[20:37:02.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:184:0)
[20:37:02.874] Timer tick, asking Buildbucket for the build status
[20:37:03.306] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:03.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:185:0) after 1m0s
[20:38:03.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:185:0)
[20:38:03.461] Timer tick, asking Buildbucket for the build status
[20:38:03.526] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:03.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:186:0) after 1m0s
[20:39:03.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:186:0)
[20:39:03.526] Timer tick, asking Buildbucket for the build status
[20:39:03.620] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:03.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:187:0) after 1m0s
[20:40:03.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:187:0)
[20:40:03.717] Timer tick, asking Buildbucket for the build status
[20:40:03.874] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:03.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:188:0) after 1m0s
[20:41:03.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:188:0)
[20:41:03.982] Timer tick, asking Buildbucket for the build status
[20:41:04.046] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:04.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:189:0) after 1m0s
[20:42:04.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:189:0)
[20:42:04.124] Timer tick, asking Buildbucket for the build status
[20:42:04.260] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:04.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:190:0) after 1m0s
[20:43:04.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:190:0)
[20:43:04.294] Timer tick, asking Buildbucket for the build status
[20:43:04.364] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:04.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:191:0) after 1m0s
[20:44:04.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:191:0)
[20:44:04.406] Timer tick, asking Buildbucket for the build status
[20:44:04.533] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:04.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:192:0) after 1m0s
[20:45:04.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:192:0)
[20:45:04.567] Timer tick, asking Buildbucket for the build status
[20:45:04.898] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:04.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:193:0) after 1m0s
[20:46:04.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:193:0)
[20:46:04.978] Timer tick, asking Buildbucket for the build status
[20:46:05.119] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:05.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:194:0) after 1m0s
[20:47:05.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:194:0)
[20:47:05.125] Timer tick, asking Buildbucket for the build status
[20:47:05.242] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:05.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:195:0) after 1m0s
[20:48:05.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:195:0)
[20:48:05.407] Timer tick, asking Buildbucket for the build status
[20:48:05.475] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:05.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:196:0) after 1m0s
[20:49:05.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:196:0)
[20:49:05.526] Timer tick, asking Buildbucket for the build status
[20:49:05.677] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:05.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:197:0) after 1m0s
[20:50:05.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:197:0)
[20:50:05.695] Timer tick, asking Buildbucket for the build status
[20:50:05.802] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:05.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:198:0) after 1m0s
[20:51:06.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:198:0)
[20:51:06.054] Timer tick, asking Buildbucket for the build status
[20:51:06.129] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:06.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:199:0) after 1m0s
[20:52:06.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:199:0)
[20:52:06.201] Timer tick, asking Buildbucket for the build status
[20:52:06.453] Build 8861461429757457216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:06.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026891491894628560:200:0) after 1m0s
[20:52:37.214] Received PubSub notification, asking Buildbucket for the build status
[20:52:37.279] Build 8861461429757457216: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:52:37.279] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1607460755909886",
    "created_by": "project:infra",
    "created_ts": "1607448850823894",
    "id": "8861461429757457216",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4349.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"108c766c-283b-4ef8-af6b-5f2e3b4ad1ef\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4349.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-uj4s\"], \"image\": [\"chrome-xenial-20120600-c5cfbe11ce8\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1607448887227212",
    "status": "COMPLETED",
    "status_changed_ts": "1607460756813456",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026891491894628560",
      "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/8861461429757457216/+/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:505c3c7cedbe3410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1607460756827096",
    "url": "https://ci.chromium.org/b/8861461429757457216",
    "utcnow_ts": "1607460757272158"
  }
}
[20:52:37.279] Invocation finished in 3h18m27.878147654s with status SUCCEEDED