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

• parent_buildername:publish_tarball
• user_agent:recipe

[11:33:48.158] New invocation is queued and will start shortly
[11:33:48.158] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:33:49.208] Starting the invocation (attempt 1)
[11:33:49.248] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:33:49.248] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:33:49.248] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9027457745227733648",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"87.0.4280.86\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"48822ba5-3eaf-4a93-b081-106f77e8f4f2\",\"title\":\"publish_tarball/0\"}]},\"version\":\"87.0.4280.86\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9027457745227733648",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[11:33:49.654] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1606908829344332",
    "id": "8862027683320010272",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4280.86\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"48822ba5-3eaf-4a93-b081-106f77e8f4f2\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4280.86\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1606908829511456",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9027457745227733648",
      "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/8862027683320010272/+/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": "1606908829511595",
    "url": "https://ci.chromium.org/b/8862027683320010272",
    "utcnow_ts": "1606908829647795"
  }
}
[11:33:49.654] Task URL: https://ci.chromium.org/b/8862027683320010272
[11:33:49.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:2:0) after 1m0s
[11:34:36.691] Received PubSub notification, asking Buildbucket for the build status
[11:34:36.755] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:50.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:2:0)
[11:34:50.038] Timer tick, asking Buildbucket for the build status
[11:34:50.141] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:50.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:4:0) after 1m0s
[11:35:50.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:4:0)
[11:35:50.192] Timer tick, asking Buildbucket for the build status
[11:35:50.297] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:50.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:5:0) after 1m0s
[11:36:50.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:5:0)
[11:36:50.349] Timer tick, asking Buildbucket for the build status
[11:36:50.480] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:50.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:6:0) after 1m0s
[11:37:50.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:6:0)
[11:37:50.564] Timer tick, asking Buildbucket for the build status
[11:37:50.620] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:50.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:7:0) after 1m0s
[11:38:50.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:7:0)
[11:38:50.761] Timer tick, asking Buildbucket for the build status
[11:38:50.989] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:50.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:8:0) after 1m0s
[11:39:51.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:8:0)
[11:39:51.259] Timer tick, asking Buildbucket for the build status
[11:39:52.246] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:52.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:9:0) after 1m0s
[11:40:52.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:9:0)
[11:40:52.290] Timer tick, asking Buildbucket for the build status
[11:40:52.352] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:52.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:10:0) after 1m0s
[11:41:52.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:10:0)
[11:41:52.379] Timer tick, asking Buildbucket for the build status
[11:41:52.621] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:52.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:11:0) after 1m0s
[11:42:52.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:11:0)
[11:42:52.679] Timer tick, asking Buildbucket for the build status
[11:42:52.762] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:52.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:12:0) after 1m0s
[11:43:52.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:12:0)
[11:43:52.815] Timer tick, asking Buildbucket for the build status
[11:43:53.043] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:53.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:13:0) after 1m0s
[11:44:53.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:13:0)
[11:44:53.191] Timer tick, asking Buildbucket for the build status
[11:44:54.341] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:54.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:14:0) after 1m0s
[11:45:54.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:14:0)
[11:45:54.337] Timer tick, asking Buildbucket for the build status
[11:45:54.391] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:54.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:15:0) after 1m0s
[11:46:54.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:15:0)
[11:46:54.368] Timer tick, asking Buildbucket for the build status
[11:46:54.472] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:54.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:16:0) after 1m0s
[11:47:54.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:16:0)
[11:47:54.452] Timer tick, asking Buildbucket for the build status
[11:47:54.518] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:54.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:17:0) after 1m0s
[11:48:54.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:17:0)
[11:48:54.517] Timer tick, asking Buildbucket for the build status
[11:48:54.631] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:54.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:18:0) after 1m0s
[11:49:54.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:18:0)
[11:49:54.641] Timer tick, asking Buildbucket for the build status
[11:49:54.700] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:54.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:19:0) after 1m0s
[11:50:54.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:19:0)
[11:50:54.842] Timer tick, asking Buildbucket for the build status
[11:50:55.072] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:55.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:20:0) after 1m0s
[11:51:55.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:20:0)
[11:51:55.340] Timer tick, asking Buildbucket for the build status
[11:51:55.594] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:55.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:21:0) after 1m0s
[11:52:55.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:21:0)
[11:52:55.804] Timer tick, asking Buildbucket for the build status
[11:52:55.890] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:55.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:22:0) after 1m0s
[11:53:56.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:22:0)
[11:53:56.082] Timer tick, asking Buildbucket for the build status
[11:53:56.224] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:56.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:23:0) after 1m0s
[11:54:56.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:23:0)
[11:54:56.392] Timer tick, asking Buildbucket for the build status
[11:54:56.999] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:56.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:24:0) after 1m0s
[11:55:57.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:24:0)
[11:55:57.442] Timer tick, asking Buildbucket for the build status
[11:55:57.629] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:57.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:25:0) after 1m0s
[11:56:57.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:25:0)
[11:56:57.845] Timer tick, asking Buildbucket for the build status
[11:56:58.055] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:58.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:26:0) after 1m0s
[11:57:58.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:26:0)
[11:57:58.150] Timer tick, asking Buildbucket for the build status
[11:57:58.250] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:58.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:27:0) after 1m0s
[11:58:58.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:27:0)
[11:58:58.372] Timer tick, asking Buildbucket for the build status
[11:58:58.478] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:58.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:28:0) after 1m0s
[11:59:58.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:28:0)
[11:59:58.750] Timer tick, asking Buildbucket for the build status
[11:59:58.904] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:58.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:29:0) after 1m0s
[12:00:59.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:29:0)
[12:00:59.197] Timer tick, asking Buildbucket for the build status
[12:00:59.284] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:59.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:30:0) after 1m0s
[12:01:59.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:30:0)
[12:01:59.760] Timer tick, asking Buildbucket for the build status
[12:01:59.963] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:59.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:31:0) after 1m0s
[12:02:59.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:31:0)
[12:02:59.994] Timer tick, asking Buildbucket for the build status
[12:03:00.172] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:00.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:32:0) after 1m0s
[12:04:00.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:32:0)
[12:04:00.247] Timer tick, asking Buildbucket for the build status
[12:04:00.392] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:00.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:33:0) after 1m0s
[12:05:00.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:33:0)
[12:05:00.479] Timer tick, asking Buildbucket for the build status
[12:05:00.772] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:00.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:34:0) after 1m0s
[12:06:01.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:34:0)
[12:06:01.343] Timer tick, asking Buildbucket for the build status
[12:06:01.409] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:01.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:35:0) after 1m0s
[12:07:01.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:35:0)
[12:07:01.454] Timer tick, asking Buildbucket for the build status
[12:07:01.546] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:01.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:36:0) after 1m0s
[12:08:01.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:36:0)
[12:08:01.549] Timer tick, asking Buildbucket for the build status
[12:08:03.778] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:03.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:37:0) after 1m0s
[12:09:03.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:37:0)
[12:09:03.818] Timer tick, asking Buildbucket for the build status
[12:09:04.547] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:04.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:38:0) after 1m0s
[12:10:04.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:38:0)
[12:10:04.638] Timer tick, asking Buildbucket for the build status
[12:10:04.702] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:04.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:39:0) after 1m0s
[12:11:04.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:39:0)
[12:11:04.941] Timer tick, asking Buildbucket for the build status
[12:11:05.040] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:05.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:40:0) after 1m0s
[12:12:05.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:40:0)
[12:12:05.174] Timer tick, asking Buildbucket for the build status
[12:12:05.270] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:05.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:41:0) after 1m0s
[12:13:05.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:41:0)
[12:13:05.348] Timer tick, asking Buildbucket for the build status
[12:13:05.496] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:05.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:42:0) after 1m0s
[12:14:05.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:42:0)
[12:14:05.542] Timer tick, asking Buildbucket for the build status
[12:14:05.658] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:05.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:43:0) after 1m0s
[12:15:05.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:43:0)
[12:15:05.752] Timer tick, asking Buildbucket for the build status
[12:15:06.171] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:06.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:44:0) after 1m0s
[12:16:06.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:44:0)
[12:16:06.195] Timer tick, asking Buildbucket for the build status
[12:16:06.258] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:06.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:45:0) after 1m0s
[12:17:06.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:45:0)
[12:17:06.307] Timer tick, asking Buildbucket for the build status
[12:17:06.368] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:06.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:46:0) after 1m0s
[12:18:06.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:46:0)
[12:18:06.441] Timer tick, asking Buildbucket for the build status
[12:18:06.510] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:06.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:47:0) after 1m0s
[12:19:06.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:47:0)
[12:19:06.555] Timer tick, asking Buildbucket for the build status
[12:19:06.648] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:06.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:48:0) after 1m0s
[12:20:06.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:48:0)
[12:20:06.635] Timer tick, asking Buildbucket for the build status
[12:20:06.733] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:06.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:49:0) after 1m0s
[12:21:06.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:49:0)
[12:21:06.904] Timer tick, asking Buildbucket for the build status
[12:21:07.046] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:07.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:50:0) after 1m0s
[12:22:07.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:50:0)
[12:22:07.074] Timer tick, asking Buildbucket for the build status
[12:22:07.163] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:07.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:51:0) after 1m0s
[12:23:07.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:51:0)
[12:23:07.249] Timer tick, asking Buildbucket for the build status
[12:23:07.312] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:07.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:52:0) after 1m0s
[12:24:07.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:52:0)
[12:24:07.443] Timer tick, asking Buildbucket for the build status
[12:24:07.577] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:07.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:53:0) after 1m0s
[12:25:07.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:53:0)
[12:25:07.673] Timer tick, asking Buildbucket for the build status
[12:25:09.333] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:09.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:54:0) after 1m0s
[12:26:09.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:54:0)
[12:26:09.353] Timer tick, asking Buildbucket for the build status
[12:26:09.427] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:09.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:55:0) after 1m0s
[12:27:09.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:55:0)
[12:27:09.465] Timer tick, asking Buildbucket for the build status
[12:27:09.648] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:09.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:56:0) after 1m0s
[12:28:09.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:56:0)
[12:28:09.699] Timer tick, asking Buildbucket for the build status
[12:28:09.821] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:09.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:57:0) after 1m0s
[12:29:09.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:57:0)
[12:29:09.866] Timer tick, asking Buildbucket for the build status
[12:29:09.965] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:09.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:58:0) after 1m0s
[12:30:10.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:58:0)
[12:30:10.003] Timer tick, asking Buildbucket for the build status
[12:30:10.061] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:10.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:59:0) after 1m0s
[12:31:10.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:59:0)
[12:31:10.077] Timer tick, asking Buildbucket for the build status
[12:31:10.952] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:10.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:60:0) after 1m0s
[12:32:11.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:60:0)
[12:32:11.339] Timer tick, asking Buildbucket for the build status
[12:32:11.458] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:11.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:61:0) after 1m0s
[12:33:11.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:61:0)
[12:33:11.551] Timer tick, asking Buildbucket for the build status
[12:33:11.613] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:11.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:62:0) after 1m0s
[12:34:11.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:62:0)
[12:34:11.697] Timer tick, asking Buildbucket for the build status
[12:34:11.792] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:11.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:63:0) after 1m0s
[12:35:11.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:63:0)
[12:35:11.839] Timer tick, asking Buildbucket for the build status
[12:35:11.906] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:11.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:64:0) after 1m0s
[12:36:12.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:64:0)
[12:36:12.003] Timer tick, asking Buildbucket for the build status
[12:36:12.083] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:12.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:65:0) after 1m0s
[12:37:12.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:65:0)
[12:37:12.192] Timer tick, asking Buildbucket for the build status
[12:37:12.293] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:12.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:66:0) after 1m0s
[12:38:12.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:66:0)
[12:38:12.587] Timer tick, asking Buildbucket for the build status
[12:38:12.897] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:12.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:67:0) after 1m0s
[12:39:12.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:67:0)
[12:39:12.997] Timer tick, asking Buildbucket for the build status
[12:39:13.144] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:13.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:68:0) after 1m0s
[12:40:13.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:68:0)
[12:40:13.793] Timer tick, asking Buildbucket for the build status
[12:40:13.854] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:13.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:69:0) after 1m0s
[12:41:13.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:69:0)
[12:41:13.924] Timer tick, asking Buildbucket for the build status
[12:41:14.004] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:14.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:70:0) after 1m0s
[12:42:14.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:70:0)
[12:42:14.129] Timer tick, asking Buildbucket for the build status
[12:42:14.324] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:14.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:71:0) after 1m0s
[12:43:14.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:71:0)
[12:43:14.458] Timer tick, asking Buildbucket for the build status
[12:43:17.192] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:17.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:72:0) after 1m0s
[12:44:17.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:72:0)
[12:44:17.359] Timer tick, asking Buildbucket for the build status
[12:44:17.584] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:17.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:73:0) after 1m0s
[12:45:17.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:73:0)
[12:45:17.639] Timer tick, asking Buildbucket for the build status
[12:45:17.839] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:17.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:74:0) after 1m0s
[12:46:17.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:74:0)
[12:46:17.837] Timer tick, asking Buildbucket for the build status
[12:46:17.894] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:17.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:75:0) after 1m0s
[12:47:17.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:75:0)
[12:47:17.982] Timer tick, asking Buildbucket for the build status
[12:47:18.083] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:18.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:76:0) after 1m0s
[12:48:18.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:76:0)
[12:48:18.146] Timer tick, asking Buildbucket for the build status
[12:48:18.222] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:18.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:77:0) after 1m0s
[12:49:18.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:77:0)
[12:49:18.275] Timer tick, asking Buildbucket for the build status
[12:49:18.647] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:18.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:78:0) after 1m0s
[12:50:18.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:78:0)
[12:50:18.629] Timer tick, asking Buildbucket for the build status
[12:50:18.722] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:18.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:79:0) after 1m0s
[12:51:18.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:79:0)
[12:51:18.764] Timer tick, asking Buildbucket for the build status
[12:51:18.866] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:18.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:80:0) after 1m0s
[12:52:18.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:80:0)
[12:52:18.911] Timer tick, asking Buildbucket for the build status
[12:52:18.998] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:18.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:81:0) after 1m0s
[12:53:19.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:81:0)
[12:53:19.012] Timer tick, asking Buildbucket for the build status
[12:53:19.075] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:19.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:82:0) after 1m0s
[12:54:19.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:82:0)
[12:54:19.125] Timer tick, asking Buildbucket for the build status
[12:54:19.340] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:19.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:83:0) after 1m0s
[12:55:19.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:83:0)
[12:55:19.366] Timer tick, asking Buildbucket for the build status
[12:55:19.465] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:19.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:84:0) after 1m0s
[12:56:19.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:84:0)
[12:56:19.574] Timer tick, asking Buildbucket for the build status
[12:56:19.653] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:19.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:85:0) after 1m0s
[12:57:19.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:85:0)
[12:57:19.683] Timer tick, asking Buildbucket for the build status
[12:57:19.914] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:19.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:86:0) after 1m0s
[12:58:20.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:86:0)
[12:58:20.939] Timer tick, asking Buildbucket for the build status
[12:58:21.009] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:21.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:87:0) after 1m0s
[12:59:22.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:87:0)
[12:59:22.293] Timer tick, asking Buildbucket for the build status
[12:59:22.421] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:22.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:88:0) after 1m0s
[13:00:22.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:88:0)
[13:00:22.480] Timer tick, asking Buildbucket for the build status
[13:00:24.172] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:24.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:89:0) after 1m0s
[13:01:24.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:89:0)
[13:01:24.283] Timer tick, asking Buildbucket for the build status
[13:01:24.343] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:24.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:90:0) after 1m0s
[13:02:24.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:90:0)
[13:02:24.515] Timer tick, asking Buildbucket for the build status
[13:02:24.635] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:24.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:91:0) after 1m0s
[13:03:24.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:91:0)
[13:03:24.697] Timer tick, asking Buildbucket for the build status
[13:03:24.966] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:24.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:92:0) after 1m0s
[13:04:24.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:92:0)
[13:04:24.995] Timer tick, asking Buildbucket for the build status
[13:04:25.136] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:25.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:93:0) after 1m0s
[13:05:25.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:93:0)
[13:05:25.138] Timer tick, asking Buildbucket for the build status
[13:05:25.268] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:25.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:94:0) after 1m0s
[13:06:25.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:94:0)
[13:06:25.286] Timer tick, asking Buildbucket for the build status
[13:06:25.354] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:25.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:95:0) after 1m0s
[13:07:25.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:95:0)
[13:07:25.410] Timer tick, asking Buildbucket for the build status
[13:07:25.545] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:25.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:96:0) after 1m0s
[13:08:25.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:96:0)
[13:08:25.540] Timer tick, asking Buildbucket for the build status
[13:08:25.612] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:25.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:97:0) after 1m0s
[13:09:26.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:97:0)
[13:09:26.144] Timer tick, asking Buildbucket for the build status
[13:09:26.355] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:26.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:98:0) after 1m0s
[13:10:26.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:98:0)
[13:10:26.452] Timer tick, asking Buildbucket for the build status
[13:10:26.523] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:26.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:99:0) after 1m0s
[13:11:26.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:99:0)
[13:11:26.560] Timer tick, asking Buildbucket for the build status
[13:11:26.617] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:26.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:100:0) after 1m0s
[13:12:26.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:100:0)
[13:12:26.677] Timer tick, asking Buildbucket for the build status
[13:12:26.821] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:26.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:101:0) after 1m0s
[13:13:26.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:101:0)
[13:13:26.937] Timer tick, asking Buildbucket for the build status
[13:13:27.017] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:27.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:102:0) after 1m0s
[13:14:27.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:102:0)
[13:14:27.102] Timer tick, asking Buildbucket for the build status
[13:14:27.250] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:27.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:103:0) after 1m0s
[13:15:27.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:103:0)
[13:15:27.332] Timer tick, asking Buildbucket for the build status
[13:15:28.351] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:28.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:104:0) after 1m0s
[13:16:28.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:104:0)
[13:16:28.504] Timer tick, asking Buildbucket for the build status
[13:16:29.207] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:29.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:105:0) after 1m0s
[13:17:29.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:105:0)
[13:17:29.277] Timer tick, asking Buildbucket for the build status
[13:17:29.888] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:29.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:106:0) after 1m0s
[13:18:30.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:106:0)
[13:18:30.143] Timer tick, asking Buildbucket for the build status
[13:18:30.195] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:30.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:107:0) after 1m0s
[13:19:30.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:107:0)
[13:19:30.209] Timer tick, asking Buildbucket for the build status
[13:19:30.343] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:30.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:108:0) after 1m0s
[13:20:30.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:108:0)
[13:20:30.369] Timer tick, asking Buildbucket for the build status
[13:20:30.427] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:30.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:109:0) after 1m0s
[13:21:30.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:109:0)
[13:21:30.482] Timer tick, asking Buildbucket for the build status
[13:21:30.537] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:30.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:110:0) after 1m0s
[13:22:30.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:110:0)
[13:22:30.624] Timer tick, asking Buildbucket for the build status
[13:22:30.696] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:30.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:111:0) after 1m0s
[13:23:30.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:111:0)
[13:23:30.743] Timer tick, asking Buildbucket for the build status
[13:23:31.076] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:31.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:112:0) after 1m0s
[13:24:31.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:112:0)
[13:24:31.108] Timer tick, asking Buildbucket for the build status
[13:24:31.200] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:31.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:113:0) after 1m0s
[13:25:31.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:113:0)
[13:25:31.193] Timer tick, asking Buildbucket for the build status
[13:25:31.262] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:31.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:114:0) after 1m0s
[13:26:31.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:114:0)
[13:26:31.248] Timer tick, asking Buildbucket for the build status
[13:26:31.316] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:31.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:115:0) after 1m0s
[13:27:31.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:115:0)
[13:27:31.332] Timer tick, asking Buildbucket for the build status
[13:27:32.343] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:32.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:116:0) after 1m0s
[13:28:32.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:116:0)
[13:28:32.368] Timer tick, asking Buildbucket for the build status
[13:28:32.433] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:32.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:117:0) after 1m0s
[13:29:32.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:117:0)
[13:29:32.481] Timer tick, asking Buildbucket for the build status
[13:29:32.614] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:32.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:118:0) after 1m0s
[13:30:32.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:118:0)
[13:30:32.709] Timer tick, asking Buildbucket for the build status
[13:30:32.838] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:32.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:119:0) after 1m0s
[13:31:32.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:119:0)
[13:31:32.909] Timer tick, asking Buildbucket for the build status
[13:31:32.980] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:32.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:120:0) after 1m0s
[13:32:33.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:120:0)
[13:32:33.093] Timer tick, asking Buildbucket for the build status
[13:32:33.175] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:33.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:121:0) after 1m0s
[13:33:33.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:121:0)
[13:33:33.186] Timer tick, asking Buildbucket for the build status
[13:33:33.362] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:33.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:122:0) after 1m0s
[13:34:33.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:122:0)
[13:34:33.455] Timer tick, asking Buildbucket for the build status
[13:34:33.621] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:33.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:123:0) after 1m0s
[13:35:33.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:123:0)
[13:35:33.672] Timer tick, asking Buildbucket for the build status
[13:35:34.017] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:34.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:124:0) after 1m0s
[13:36:34.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:124:0)
[13:36:34.033] Timer tick, asking Buildbucket for the build status
[13:36:34.398] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:34.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:125:0) after 1m0s
[13:37:34.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:125:0)
[13:37:34.387] Timer tick, asking Buildbucket for the build status
[13:37:34.708] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:34.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:126:0) after 1m0s
[13:38:34.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:126:0)
[13:38:34.837] Timer tick, asking Buildbucket for the build status
[13:38:34.915] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:34.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:127:0) after 1m0s
[13:39:34.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:127:0)
[13:39:34.973] Timer tick, asking Buildbucket for the build status
[13:39:35.788] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:35.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:128:0) after 1m0s
[13:40:35.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:128:0)
[13:40:35.799] Timer tick, asking Buildbucket for the build status
[13:40:35.860] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:35.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:129:0) after 1m0s
[13:41:35.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:129:0)
[13:41:35.882] Timer tick, asking Buildbucket for the build status
[13:41:36.032] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:36.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:130:0) after 1m0s
[13:42:36.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:130:0)
[13:42:36.063] Timer tick, asking Buildbucket for the build status
[13:42:36.352] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:36.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:131:0) after 1m0s
[13:43:36.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:131:0)
[13:43:36.379] Timer tick, asking Buildbucket for the build status
[13:43:36.645] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:36.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:132:0) after 1m0s
[13:44:36.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:132:0)
[13:44:36.700] Timer tick, asking Buildbucket for the build status
[13:44:36.834] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:36.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:133:0) after 1m0s
[13:45:36.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:133:0)
[13:45:36.857] Timer tick, asking Buildbucket for the build status
[13:45:36.933] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:36.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:134:0) after 1m0s
[13:46:36.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:134:0)
[13:46:36.986] Timer tick, asking Buildbucket for the build status
[13:46:37.077] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:37.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:135:0) after 1m0s
[13:47:37.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:135:0)
[13:47:37.232] Timer tick, asking Buildbucket for the build status
[13:47:37.465] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:37.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:136:0) after 1m0s
[13:48:37.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:136:0)
[13:48:37.568] Timer tick, asking Buildbucket for the build status
[13:48:37.639] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:37.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:137:0) after 1m0s
[13:49:37.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:137:0)
[13:49:37.710] Timer tick, asking Buildbucket for the build status
[13:49:38.167] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:38.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:138:0) after 1m0s
[13:50:38.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:138:0)
[13:50:38.261] Timer tick, asking Buildbucket for the build status
[13:50:39.466] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:39.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:139:0) after 1m0s
[13:51:39.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:139:0)
[13:51:39.486] Timer tick, asking Buildbucket for the build status
[13:51:39.550] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:39.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:140:0) after 1m0s
[13:52:39.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:140:0)
[13:52:39.606] Timer tick, asking Buildbucket for the build status
[13:52:39.796] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:39.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:141:0) after 1m0s
[13:53:39.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:141:0)
[13:53:39.934] Timer tick, asking Buildbucket for the build status
[13:53:40.015] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:40.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:142:0) after 1m0s
[13:54:40.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:142:0)
[13:54:40.178] Timer tick, asking Buildbucket for the build status
[13:54:40.418] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:40.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:143:0) after 1m0s
[13:55:40.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:143:0)
[13:55:40.555] Timer tick, asking Buildbucket for the build status
[13:55:40.668] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:40.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:144:0) after 1m0s
[13:56:40.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:144:0)
[13:56:40.863] Timer tick, asking Buildbucket for the build status
[13:56:41.061] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:41.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:145:0) after 1m0s
[13:57:41.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:145:0)
[13:57:41.258] Timer tick, asking Buildbucket for the build status
[13:57:41.539] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:41.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:146:0) after 1m0s
[13:58:41.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:146:0)
[13:58:41.641] Timer tick, asking Buildbucket for the build status
[13:58:41.926] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:41.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:147:0) after 1m0s
[13:59:42.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:147:0)
[13:59:42.124] Timer tick, asking Buildbucket for the build status
[13:59:42.283] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:42.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:148:0) after 1m0s
[14:00:42.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:148:0)
[14:00:42.318] Timer tick, asking Buildbucket for the build status
[14:00:43.629] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:43.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:149:0) after 1m0s
[14:01:43.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:149:0)
[14:01:43.680] Timer tick, asking Buildbucket for the build status
[14:01:44.028] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:44.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:150:0) after 1m0s
[14:02:44.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:150:0)
[14:02:44.038] Timer tick, asking Buildbucket for the build status
[14:02:44.106] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:44.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:151:0) after 1m0s
[14:03:44.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:151:0)
[14:03:44.297] Timer tick, asking Buildbucket for the build status
[14:03:44.460] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:44.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:152:0) after 1m0s
[14:04:44.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:152:0)
[14:04:44.682] Timer tick, asking Buildbucket for the build status
[14:04:44.850] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:44.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:153:0) after 1m0s
[14:05:44.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:153:0)
[14:05:44.917] Timer tick, asking Buildbucket for the build status
[14:05:44.995] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:44.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:154:0) after 1m0s
[14:06:45.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:154:0)
[14:06:45.024] Timer tick, asking Buildbucket for the build status
[14:06:45.111] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:45.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:155:0) after 1m0s
[14:07:45.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:155:0)
[14:07:45.117] Timer tick, asking Buildbucket for the build status
[14:07:45.192] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:45.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:156:0) after 1m0s
[14:08:45.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:156:0)
[14:08:45.227] Timer tick, asking Buildbucket for the build status
[14:08:45.848] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:45.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:157:0) after 1m0s
[14:09:45.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:157:0)
[14:09:45.939] Timer tick, asking Buildbucket for the build status
[14:09:46.117] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:46.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:158:0) after 1m0s
[14:10:46.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:158:0)
[14:10:46.219] Timer tick, asking Buildbucket for the build status
[14:10:46.941] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:46.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:159:0) after 1m0s
[14:11:47.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:159:0)
[14:11:47.029] Timer tick, asking Buildbucket for the build status
[14:11:47.102] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:47.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:160:0) after 1m0s
[14:12:47.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:160:0)
[14:12:47.251] Timer tick, asking Buildbucket for the build status
[14:12:47.348] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:47.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:161:0) after 1m0s
[14:13:47.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:161:0)
[14:13:47.459] Timer tick, asking Buildbucket for the build status
[14:13:47.594] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:47.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:162:0) after 1m0s
[14:14:47.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:162:0)
[14:14:47.671] Timer tick, asking Buildbucket for the build status
[14:14:47.801] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:47.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:163:0) after 1m0s
[14:15:47.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:163:0)
[14:15:47.886] Timer tick, asking Buildbucket for the build status
[14:15:47.978] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:47.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:164:0) after 1m0s
[14:16:48.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:164:0)
[14:16:48.034] Timer tick, asking Buildbucket for the build status
[14:16:48.089] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:48.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:165:0) after 1m0s
[14:17:48.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:165:0)
[14:17:48.160] Timer tick, asking Buildbucket for the build status
[14:17:48.276] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:48.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:166:0) after 1m0s
[14:18:48.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:166:0)
[14:18:48.303] Timer tick, asking Buildbucket for the build status
[14:18:48.491] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:48.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:167:0) after 1m0s
[14:19:48.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:167:0)
[14:19:48.541] Timer tick, asking Buildbucket for the build status
[14:19:48.663] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:48.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:168:0) after 1m0s
[14:20:48.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:168:0)
[14:20:48.693] Timer tick, asking Buildbucket for the build status
[14:20:48.838] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:48.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:169:0) after 1m0s
[14:21:48.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:169:0)
[14:21:48.908] Timer tick, asking Buildbucket for the build status
[14:21:48.983] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:48.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:170:0) after 1m0s
[14:22:49.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:170:0)
[14:22:49.033] Timer tick, asking Buildbucket for the build status
[14:22:49.096] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:49.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:171:0) after 1m0s
[14:23:49.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:171:0)
[14:23:49.196] Timer tick, asking Buildbucket for the build status
[14:23:49.303] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:49.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:172:0) after 1m0s
[14:24:49.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:172:0)
[14:24:49.354] Timer tick, asking Buildbucket for the build status
[14:24:49.487] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:49.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:173:0) after 1m0s
[14:25:49.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:173:0)
[14:25:49.577] Timer tick, asking Buildbucket for the build status
[14:25:49.647] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:49.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:174:0) after 1m0s
[14:26:49.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:174:0)
[14:26:49.724] Timer tick, asking Buildbucket for the build status
[14:26:49.991] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:49.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:175:0) after 1m0s
[14:27:50.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:175:0)
[14:27:50.018] Timer tick, asking Buildbucket for the build status
[14:27:50.345] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:50.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:176:0) after 1m0s
[14:28:50.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:176:0)
[14:28:50.370] Timer tick, asking Buildbucket for the build status
[14:28:50.482] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:50.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:177:0) after 1m0s
[14:29:50.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:177:0)
[14:29:50.556] Timer tick, asking Buildbucket for the build status
[14:29:50.878] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:50.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:178:0) after 1m0s
[14:30:50.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:178:0)
[14:30:50.994] Timer tick, asking Buildbucket for the build status
[14:30:51.112] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:51.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:179:0) after 1m0s
[14:31:51.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:179:0)
[14:31:51.180] Timer tick, asking Buildbucket for the build status
[14:31:51.300] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:51.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:180:0) after 1m0s
[14:32:51.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:180:0)
[14:32:51.363] Timer tick, asking Buildbucket for the build status
[14:32:51.475] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:51.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:181:0) after 1m0s
[14:33:51.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:181:0)
[14:33:51.581] Timer tick, asking Buildbucket for the build status
[14:33:51.640] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:51.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:182:0) after 1m0s
[14:34:51.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:182:0)
[14:34:51.672] Timer tick, asking Buildbucket for the build status
[14:34:52.052] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:52.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:183:0) after 1m0s
[14:35:52.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:183:0)
[14:35:52.135] Timer tick, asking Buildbucket for the build status
[14:35:52.195] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:52.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:184:0) after 1m0s
[14:36:52.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:184:0)
[14:36:52.310] Timer tick, asking Buildbucket for the build status
[14:36:52.398] Build 8862027683320010272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:52.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027457745227733648:185:0) after 1m0s
[14:37:22.547] Received PubSub notification, asking Buildbucket for the build status
[14:37:22.714] Build 8862027683320010272: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:37:22.714] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1606919841392661",
    "created_by": "project:infra",
    "created_ts": "1606908829344332",
    "id": "8862027683320010272",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4280.86\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"48822ba5-3eaf-4a93-b081-106f77e8f4f2\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4280.86\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-4as1\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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": "1606908875302824",
    "status": "COMPLETED",
    "status_changed_ts": "1606919842256548",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9027457745227733648",
      "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/8862027683320010272/+/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:503c0c6f7ff73610",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1606919842274128",
    "url": "https://ci.chromium.org/b/8862027683320010272",
    "utcnow_ts": "1606919842694871"
  }
}
[14:37:22.714] Invocation finished in 3h3m34.573282414s with status SUCCEEDED