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

• parent_buildername:publish_tarball
• user_agent:recipe

[17:02:27.324] New invocation is queued and will start shortly
[17:02:27.324] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[17:02:28.602] Starting the invocation (attempt 1)
[17:02:28.665] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:02:28.665] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:02:28.665] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9026621695487176512",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4352.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"eb4c0ee8-3574-49d8-b732-fd1de653a188\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4352.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:9026621695487176512",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[17:02:29.459] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1607706148839838",
    "id": "8861191633233564144",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4352.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"eb4c0ee8-3574-49d8-b732-fd1de653a188\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4352.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1607706149226363",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026621695487176512",
      "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/8861191633233564144/+/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": "1607706149226476",
    "url": "https://ci.chromium.org/b/8861191633233564144",
    "utcnow_ts": "1607706149450430"
  }
}
[17:02:29.459] Task URL: https://ci.chromium.org/b/8861191633233564144
[17:02:29.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:2:0) after 1m0s
[17:02:49.745] Received PubSub notification, asking Buildbucket for the build status
[17:02:49.828] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:29.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:2:0)
[17:03:29.492] Timer tick, asking Buildbucket for the build status
[17:03:29.635] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:29.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:4:0) after 1m0s
[17:04:29.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:4:0)
[17:04:29.688] Timer tick, asking Buildbucket for the build status
[17:04:29.835] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:29.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:5:0) after 1m0s
[17:05:29.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:5:0)
[17:05:29.881] Timer tick, asking Buildbucket for the build status
[17:05:30.330] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:30.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:6:0) after 1m0s
[17:06:30.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:6:0)
[17:06:30.367] Timer tick, asking Buildbucket for the build status
[17:06:30.490] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:30.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:7:0) after 1m0s
[17:07:30.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:7:0)
[17:07:30.509] Timer tick, asking Buildbucket for the build status
[17:07:30.576] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:30.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:8:0) after 1m0s
[17:08:30.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:8:0)
[17:08:30.589] Timer tick, asking Buildbucket for the build status
[17:08:30.717] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:30.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:9:0) after 1m0s
[17:09:30.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:9:0)
[17:09:30.958] Timer tick, asking Buildbucket for the build status
[17:09:31.030] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:31.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:10:0) after 1m0s
[17:10:31.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:10:0)
[17:10:31.054] Timer tick, asking Buildbucket for the build status
[17:10:31.149] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:31.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:11:0) after 1m0s
[17:11:31.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:11:0)
[17:11:31.229] Timer tick, asking Buildbucket for the build status
[17:11:31.308] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:31.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:12:0) after 1m0s
[17:12:31.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:12:0)
[17:12:31.498] Timer tick, asking Buildbucket for the build status
[17:12:31.611] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:31.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:13:0) after 1m0s
[17:13:31.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:13:0)
[17:13:31.671] Timer tick, asking Buildbucket for the build status
[17:13:31.753] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:31.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:14:0) after 1m0s
[17:14:31.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:14:0)
[17:14:31.859] Timer tick, asking Buildbucket for the build status
[17:14:32.213] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:32.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:15:0) after 1m0s
[17:15:32.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:15:0)
[17:15:32.356] Timer tick, asking Buildbucket for the build status
[17:15:32.425] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:32.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:16:0) after 1m0s
[17:16:32.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:16:0)
[17:16:32.550] Timer tick, asking Buildbucket for the build status
[17:16:32.623] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:32.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:17:0) after 1m0s
[17:17:32.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:17:0)
[17:17:32.696] Timer tick, asking Buildbucket for the build status
[17:17:32.938] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:32.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:18:0) after 1m0s
[17:18:33.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:18:0)
[17:18:33.026] Timer tick, asking Buildbucket for the build status
[17:18:33.208] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:33.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:19:0) after 1m0s
[17:19:33.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:19:0)
[17:19:33.205] Timer tick, asking Buildbucket for the build status
[17:19:33.273] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:33.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:20:0) after 1m0s
[17:20:33.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:20:0)
[17:20:33.321] Timer tick, asking Buildbucket for the build status
[17:20:33.650] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:33.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:21:0) after 1m0s
[17:21:33.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:21:0)
[17:21:33.727] Timer tick, asking Buildbucket for the build status
[17:21:33.805] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:33.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:22:0) after 1m0s
[17:22:33.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:22:0)
[17:22:33.792] Timer tick, asking Buildbucket for the build status
[17:22:33.969] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:33.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:23:0) after 1m0s
[17:23:33.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:23:0)
[17:23:33.982] Timer tick, asking Buildbucket for the build status
[17:23:34.139] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:34.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:24:0) after 1m0s
[17:24:34.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:24:0)
[17:24:34.166] Timer tick, asking Buildbucket for the build status
[17:24:34.225] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:34.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:25:0) after 1m0s
[17:25:34.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:25:0)
[17:25:34.279] Timer tick, asking Buildbucket for the build status
[17:25:34.641] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:34.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:26:0) after 1m0s
[17:26:34.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:26:0)
[17:26:34.662] Timer tick, asking Buildbucket for the build status
[17:26:34.836] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:34.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:27:0) after 1m0s
[17:27:34.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:27:0)
[17:27:34.953] Timer tick, asking Buildbucket for the build status
[17:27:35.054] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:35.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:28:0) after 1m0s
[17:28:35.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:28:0)
[17:28:35.074] Timer tick, asking Buildbucket for the build status
[17:28:35.168] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:35.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:29:0) after 1m0s
[17:29:35.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:29:0)
[17:29:35.195] Timer tick, asking Buildbucket for the build status
[17:29:36.097] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:36.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:30:0) after 1m0s
[17:30:36.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:30:0)
[17:30:36.786] Timer tick, asking Buildbucket for the build status
[17:30:36.939] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:36.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:31:0) after 1m0s
[17:31:37.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:31:0)
[17:31:37.021] Timer tick, asking Buildbucket for the build status
[17:31:37.139] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:37.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:32:0) after 1m0s
[17:32:37.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:32:0)
[17:32:37.554] Timer tick, asking Buildbucket for the build status
[17:32:37.663] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:37.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:33:0) after 1m0s
[17:33:37.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:33:0)
[17:33:37.734] Timer tick, asking Buildbucket for the build status
[17:33:37.811] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:37.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:34:0) after 1m0s
[17:34:37.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:34:0)
[17:34:37.919] Timer tick, asking Buildbucket for the build status
[17:34:38.042] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:38.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:35:0) after 1m0s
[17:35:38.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:35:0)
[17:35:38.204] Timer tick, asking Buildbucket for the build status
[17:35:38.255] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:38.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:36:0) after 1m0s
[17:36:38.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:36:0)
[17:36:38.307] Timer tick, asking Buildbucket for the build status
[17:36:38.427] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:38.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:37:0) after 1m0s
[17:37:38.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:37:0)
[17:37:38.521] Timer tick, asking Buildbucket for the build status
[17:37:38.644] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:38.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:38:0) after 1m0s
[17:38:38.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:38:0)
[17:38:38.716] Timer tick, asking Buildbucket for the build status
[17:38:44.299] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:44.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:39:0) after 1m0s
[17:39:44.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:39:0)
[17:39:44.328] Timer tick, asking Buildbucket for the build status
[17:39:44.393] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:44.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:40:0) after 1m0s
[17:40:44.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:40:0)
[17:40:44.424] Timer tick, asking Buildbucket for the build status
[17:40:44.546] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:44.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:41:0) after 1m0s
[17:41:44.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:41:0)
[17:41:44.567] Timer tick, asking Buildbucket for the build status
[17:41:44.632] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:44.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:42:0) after 1m0s
[17:42:44.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:42:0)
[17:42:44.665] Timer tick, asking Buildbucket for the build status
[17:42:44.826] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:44.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:43:0) after 1m0s
[17:43:45.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:43:0)
[17:43:45.940] Timer tick, asking Buildbucket for the build status
[17:43:46.096] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:46.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:44:0) after 1m0s
[17:44:46.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:44:0)
[17:44:46.147] Timer tick, asking Buildbucket for the build status
[17:44:46.400] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:46.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:45:0) after 1m0s
[17:45:46.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:45:0)
[17:45:46.453] Timer tick, asking Buildbucket for the build status
[17:45:46.547] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:46.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:46:0) after 1m0s
[17:46:46.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:46:0)
[17:46:46.706] Timer tick, asking Buildbucket for the build status
[17:46:46.771] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:46.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:47:0) after 1m0s
[17:47:46.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:47:0)
[17:47:46.914] Timer tick, asking Buildbucket for the build status
[17:47:47.072] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:47.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:48:0) after 1m0s
[17:48:47.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:48:0)
[17:48:47.090] Timer tick, asking Buildbucket for the build status
[17:48:47.268] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:47.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:49:0) after 1m0s
[17:49:47.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:49:0)
[17:49:47.272] Timer tick, asking Buildbucket for the build status
[17:49:47.584] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:47.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:50:0) after 1m0s
[17:50:47.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:50:0)
[17:50:47.775] Timer tick, asking Buildbucket for the build status
[17:50:47.844] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:47.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:51:0) after 1m0s
[17:51:47.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:51:0)
[17:51:47.894] Timer tick, asking Buildbucket for the build status
[17:51:48.006] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:48.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:52:0) after 1m0s
[17:52:47.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:52:0)
[17:52:47.981] Timer tick, asking Buildbucket for the build status
[17:52:48.172] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:48.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:53:0) after 1m0s
[17:53:48.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:53:0)
[17:53:48.151] Timer tick, asking Buildbucket for the build status
[17:53:48.219] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:48.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:54:0) after 1m0s
[17:54:48.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:54:0)
[17:54:48.274] Timer tick, asking Buildbucket for the build status
[17:54:48.328] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:48.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:55:0) after 1m0s
[17:55:48.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:55:0)
[17:55:48.394] Timer tick, asking Buildbucket for the build status
[17:55:48.468] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:48.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:56:0) after 1m0s
[17:56:48.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:56:0)
[17:56:48.482] Timer tick, asking Buildbucket for the build status
[17:56:48.566] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:48.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:57:0) after 1m0s
[17:57:48.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:57:0)
[17:57:48.555] Timer tick, asking Buildbucket for the build status
[17:57:48.677] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:48.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:58:0) after 1m0s
[17:58:48.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:58:0)
[17:58:48.699] Timer tick, asking Buildbucket for the build status
[17:58:48.759] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:48.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:59:0) after 1m0s
[17:59:48.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:59:0)
[17:59:48.783] Timer tick, asking Buildbucket for the build status
[17:59:48.854] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:48.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:60:0) after 1m0s
[18:00:48.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:60:0)
[18:00:48.924] Timer tick, asking Buildbucket for the build status
[18:00:48.987] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:48.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:61:0) after 1m0s
[18:01:49.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:61:0)
[18:01:49.013] Timer tick, asking Buildbucket for the build status
[18:01:49.137] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:49.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:62:0) after 1m0s
[18:02:49.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:62:0)
[18:02:49.203] Timer tick, asking Buildbucket for the build status
[18:02:49.442] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:49.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:63:0) after 1m0s
[18:03:49.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:63:0)
[18:03:49.492] Timer tick, asking Buildbucket for the build status
[18:03:49.564] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:49.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:64:0) after 1m0s
[18:04:49.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:64:0)
[18:04:49.641] Timer tick, asking Buildbucket for the build status
[18:04:49.703] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:49.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:65:0) after 1m0s
[18:05:49.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:65:0)
[18:05:49.789] Timer tick, asking Buildbucket for the build status
[18:05:49.866] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:49.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:66:0) after 1m0s
[18:06:50.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:66:0)
[18:06:50.057] Timer tick, asking Buildbucket for the build status
[18:06:50.261] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:50.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:67:0) after 1m0s
[18:07:50.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:67:0)
[18:07:50.288] Timer tick, asking Buildbucket for the build status
[18:07:50.605] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:50.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:68:0) after 1m0s
[18:08:50.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:68:0)
[18:08:50.628] Timer tick, asking Buildbucket for the build status
[18:08:50.696] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:50.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:69:0) after 1m0s
[18:09:50.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:69:0)
[18:09:50.730] Timer tick, asking Buildbucket for the build status
[18:09:50.790] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:50.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:70:0) after 1m0s
[18:10:50.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:70:0)
[18:10:50.889] Timer tick, asking Buildbucket for the build status
[18:10:51.085] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:51.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:71:0) after 1m0s
[18:11:51.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:71:0)
[18:11:51.060] Timer tick, asking Buildbucket for the build status
[18:11:53.786] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:53.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:72:0) after 1m0s
[18:12:53.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:72:0)
[18:12:53.934] Timer tick, asking Buildbucket for the build status
[18:12:54.052] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:54.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:73:0) after 1m0s
[18:13:54.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:73:0)
[18:13:54.122] Timer tick, asking Buildbucket for the build status
[18:13:54.218] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:54.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:74:0) after 1m0s
[18:14:54.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:74:0)
[18:14:54.220] Timer tick, asking Buildbucket for the build status
[18:14:54.296] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:54.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:75:0) after 1m0s
[18:15:54.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:75:0)
[18:15:54.370] Timer tick, asking Buildbucket for the build status
[18:15:54.468] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:54.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:76:0) after 1m0s
[18:16:54.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:76:0)
[18:16:54.544] Timer tick, asking Buildbucket for the build status
[18:16:54.628] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:54.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:77:0) after 1m0s
[18:17:54.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:77:0)
[18:17:54.629] Timer tick, asking Buildbucket for the build status
[18:17:55.227] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:55.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:78:0) after 1m0s
[18:18:55.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:78:0)
[18:18:55.282] Timer tick, asking Buildbucket for the build status
[18:18:55.351] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:55.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:79:0) after 1m0s
[18:19:55.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:79:0)
[18:19:55.459] Timer tick, asking Buildbucket for the build status
[18:19:55.737] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:55.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:80:0) after 1m0s
[18:20:56.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:80:0)
[18:20:56.157] Timer tick, asking Buildbucket for the build status
[18:20:56.244] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:56.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:81:0) after 1m0s
[18:21:56.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:81:0)
[18:21:56.364] Timer tick, asking Buildbucket for the build status
[18:21:56.945] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:56.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:82:0) after 1m0s
[18:22:57.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:82:0)
[18:22:57.129] Timer tick, asking Buildbucket for the build status
[18:22:57.246] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:57.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:83:0) after 1m0s
[18:23:57.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:83:0)
[18:23:57.393] Timer tick, asking Buildbucket for the build status
[18:23:57.586] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:57.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:84:0) after 1m0s
[18:24:57.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:84:0)
[18:24:57.609] Timer tick, asking Buildbucket for the build status
[18:24:57.713] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:57.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:85:0) after 1m0s
[18:25:57.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:85:0)
[18:25:57.759] Timer tick, asking Buildbucket for the build status
[18:25:57.823] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:57.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:86:0) after 1m0s
[18:26:57.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:86:0)
[18:26:57.899] Timer tick, asking Buildbucket for the build status
[18:26:57.961] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:57.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:87:0) after 1m0s
[18:27:58.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:87:0)
[18:27:58.008] Timer tick, asking Buildbucket for the build status
[18:27:58.294] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:58.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:88:0) after 1m0s
[18:28:58.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:88:0)
[18:28:58.324] Timer tick, asking Buildbucket for the build status
[18:28:58.393] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:58.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:89:0) after 1m0s
[18:29:58.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:89:0)
[18:29:58.494] Timer tick, asking Buildbucket for the build status
[18:29:58.562] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:58.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:90:0) after 1m0s
[18:30:58.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:90:0)
[18:30:58.612] Timer tick, asking Buildbucket for the build status
[18:30:58.668] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:58.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:91:0) after 1m0s
[18:31:58.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:91:0)
[18:31:58.755] Timer tick, asking Buildbucket for the build status
[18:31:58.807] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:58.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:92:0) after 1m0s
[18:32:58.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:92:0)
[18:32:58.831] Timer tick, asking Buildbucket for the build status
[18:32:59.122] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:59.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:93:0) after 1m0s
[18:33:59.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:93:0)
[18:33:59.125] Timer tick, asking Buildbucket for the build status
[18:33:59.189] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:59.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:94:0) after 1m0s
[18:34:59.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:94:0)
[18:34:59.216] Timer tick, asking Buildbucket for the build status
[18:34:59.283] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:59.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:95:0) after 1m0s
[18:35:59.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:95:0)
[18:35:59.327] Timer tick, asking Buildbucket for the build status
[18:35:59.383] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:59.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:96:0) after 1m0s
[18:36:59.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:96:0)
[18:36:59.620] Timer tick, asking Buildbucket for the build status
[18:36:59.890] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:59.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:97:0) after 1m0s
[18:38:00.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:97:0)
[18:38:00.090] Timer tick, asking Buildbucket for the build status
[18:38:00.280] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:00.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:98:0) after 1m0s
[18:39:00.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:98:0)
[18:39:00.320] Timer tick, asking Buildbucket for the build status
[18:39:00.383] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:00.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:99:0) after 1m0s
[18:40:00.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:99:0)
[18:40:00.459] Timer tick, asking Buildbucket for the build status
[18:40:00.534] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:00.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:100:0) after 1m0s
[18:41:01.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:100:0)
[18:41:01.937] Timer tick, asking Buildbucket for the build status
[18:41:02.146] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:02.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:101:0) after 1m0s
[18:42:02.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:101:0)
[18:42:02.165] Timer tick, asking Buildbucket for the build status
[18:42:02.438] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:02.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:102:0) after 1m0s
[18:43:02.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:102:0)
[18:43:02.876] Timer tick, asking Buildbucket for the build status
[18:43:03.010] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:03.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:103:0) after 1m0s
[18:44:03.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:103:0)
[18:44:03.075] Timer tick, asking Buildbucket for the build status
[18:44:03.199] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:03.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:104:0) after 1m0s
[18:45:03.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:104:0)
[18:45:03.251] Timer tick, asking Buildbucket for the build status
[18:45:03.345] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:03.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:105:0) after 1m0s
[18:46:03.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:105:0)
[18:46:03.416] Timer tick, asking Buildbucket for the build status
[18:46:03.483] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:03.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:106:0) after 1m0s
[18:47:03.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:106:0)
[18:47:03.556] Timer tick, asking Buildbucket for the build status
[18:47:03.631] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:03.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:107:0) after 1m0s
[18:48:03.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:107:0)
[18:48:03.749] Timer tick, asking Buildbucket for the build status
[18:48:03.861] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:03.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:108:0) after 1m0s
[18:49:03.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:108:0)
[18:49:03.882] Timer tick, asking Buildbucket for the build status
[18:49:03.998] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:03.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:109:0) after 1m0s
[18:50:04.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:109:0)
[18:50:04.141] Timer tick, asking Buildbucket for the build status
[18:50:04.336] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:04.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:110:0) after 1m0s
[18:51:04.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:110:0)
[18:51:04.358] Timer tick, asking Buildbucket for the build status
[18:51:04.437] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:04.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:111:0) after 1m0s
[18:52:04.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:111:0)
[18:52:04.507] Timer tick, asking Buildbucket for the build status
[18:52:04.571] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:04.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:112:0) after 1m0s
[18:53:04.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:112:0)
[18:53:04.629] Timer tick, asking Buildbucket for the build status
[18:53:05.039] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:05.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:113:0) after 1m0s
[18:54:05.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:113:0)
[18:54:05.098] Timer tick, asking Buildbucket for the build status
[18:54:05.163] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:05.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:114:0) after 1m0s
[18:55:05.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:114:0)
[18:55:05.196] Timer tick, asking Buildbucket for the build status
[18:55:05.262] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:05.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:115:0) after 1m0s
[18:56:05.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:115:0)
[18:56:05.345] Timer tick, asking Buildbucket for the build status
[18:56:05.408] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:05.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:116:0) after 1m0s
[18:57:05.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:116:0)
[18:57:05.440] Timer tick, asking Buildbucket for the build status
[18:57:05.519] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:05.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:117:0) after 1m0s
[18:58:05.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:117:0)
[18:58:05.538] Timer tick, asking Buildbucket for the build status
[18:58:06.022] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:06.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:118:0) after 1m0s
[18:59:06.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:118:0)
[18:59:06.091] Timer tick, asking Buildbucket for the build status
[18:59:06.159] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:06.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:119:0) after 1m0s
[19:00:06.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:119:0)
[19:00:06.198] Timer tick, asking Buildbucket for the build status
[19:00:06.267] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:06.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:120:0) after 1m0s
[19:01:06.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:120:0)
[19:01:06.319] Timer tick, asking Buildbucket for the build status
[19:01:06.527] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:06.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:121:0) after 1m0s
[19:02:06.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:121:0)
[19:02:06.661] Timer tick, asking Buildbucket for the build status
[19:02:07.035] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:07.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:122:0) after 1m0s
[19:03:07.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:122:0)
[19:03:07.271] Timer tick, asking Buildbucket for the build status
[19:03:07.415] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:07.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:123:0) after 1m0s
[19:04:07.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:123:0)
[19:04:07.505] Timer tick, asking Buildbucket for the build status
[19:04:07.620] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:07.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:124:0) after 1m0s
[19:05:07.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:124:0)
[19:05:07.641] Timer tick, asking Buildbucket for the build status
[19:05:07.754] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:07.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:125:0) after 1m0s
[19:06:07.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:125:0)
[19:06:07.851] Timer tick, asking Buildbucket for the build status
[19:06:08.032] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:08.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:126:0) after 1m0s
[19:07:08.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:126:0)
[19:07:08.177] Timer tick, asking Buildbucket for the build status
[19:07:08.371] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:08.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:127:0) after 1m0s
[19:08:08.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:127:0)
[19:08:08.458] Timer tick, asking Buildbucket for the build status
[19:08:09.128] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:09.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:128:0) after 1m0s
[19:09:09.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:128:0)
[19:09:09.314] Timer tick, asking Buildbucket for the build status
[19:09:09.442] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:09.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:129:0) after 1m0s
[19:10:09.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:129:0)
[19:10:09.532] Timer tick, asking Buildbucket for the build status
[19:10:09.815] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:09.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:130:0) after 1m0s
[19:11:09.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:130:0)
[19:11:09.902] Timer tick, asking Buildbucket for the build status
[19:11:10.344] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:10.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:131:0) after 1m0s
[19:12:10.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:131:0)
[19:12:10.414] Timer tick, asking Buildbucket for the build status
[19:12:10.496] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:10.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:132:0) after 1m0s
[19:13:10.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:132:0)
[19:13:10.519] Timer tick, asking Buildbucket for the build status
[19:13:10.718] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:10.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:133:0) after 1m0s
[19:14:10.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:133:0)
[19:14:10.747] Timer tick, asking Buildbucket for the build status
[19:14:10.850] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:10.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:134:0) after 1m0s
[19:15:10.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:134:0)
[19:15:10.958] Timer tick, asking Buildbucket for the build status
[19:15:11.318] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:11.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:135:0) after 1m0s
[19:16:11.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:135:0)
[19:16:11.445] Timer tick, asking Buildbucket for the build status
[19:16:11.524] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:11.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:136:0) after 1m0s
[19:17:11.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:136:0)
[19:17:11.697] Timer tick, asking Buildbucket for the build status
[19:17:11.770] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:11.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:137:0) after 1m0s
[19:18:11.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:137:0)
[19:18:11.893] Timer tick, asking Buildbucket for the build status
[19:18:12.012] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:12.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:138:0) after 1m0s
[19:19:12.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:138:0)
[19:19:12.027] Timer tick, asking Buildbucket for the build status
[19:19:12.088] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:12.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:139:0) after 1m0s
[19:20:12.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:139:0)
[19:20:12.507] Timer tick, asking Buildbucket for the build status
[19:20:12.578] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:12.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:140:0) after 1m0s
[19:21:12.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:140:0)
[19:21:12.654] Timer tick, asking Buildbucket for the build status
[19:21:13.262] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:13.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:141:0) after 1m0s
[19:22:13.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:141:0)
[19:22:13.380] Timer tick, asking Buildbucket for the build status
[19:22:13.455] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:13.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:142:0) after 1m0s
[19:23:13.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:142:0)
[19:23:13.539] Timer tick, asking Buildbucket for the build status
[19:23:13.648] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:13.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:143:0) after 1m0s
[19:24:13.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:143:0)
[19:24:13.934] Timer tick, asking Buildbucket for the build status
[19:24:14.029] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:14.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:144:0) after 1m0s
[19:25:14.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:144:0)
[19:25:14.159] Timer tick, asking Buildbucket for the build status
[19:25:14.238] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:14.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:145:0) after 1m0s
[19:26:14.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:145:0)
[19:26:14.267] Timer tick, asking Buildbucket for the build status
[19:26:14.579] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:14.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:146:0) after 1m0s
[19:27:14.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:146:0)
[19:27:14.662] Timer tick, asking Buildbucket for the build status
[19:27:14.724] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:14.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:147:0) after 1m0s
[19:28:14.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:147:0)
[19:28:14.871] Timer tick, asking Buildbucket for the build status
[19:28:15.116] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:15.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:148:0) after 1m0s
[19:29:15.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:148:0)
[19:29:15.195] Timer tick, asking Buildbucket for the build status
[19:29:15.343] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:15.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:149:0) after 1m0s
[19:30:15.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:149:0)
[19:30:15.466] Timer tick, asking Buildbucket for the build status
[19:30:15.543] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:15.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:150:0) after 1m0s
[19:31:15.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:150:0)
[19:31:15.745] Timer tick, asking Buildbucket for the build status
[19:31:15.803] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:15.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:151:0) after 1m0s
[19:32:15.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:151:0)
[19:32:15.925] Timer tick, asking Buildbucket for the build status
[19:32:15.990] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:15.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:152:0) after 1m0s
[19:33:16.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:152:0)
[19:33:16.252] Timer tick, asking Buildbucket for the build status
[19:33:16.347] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:16.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:153:0) after 1m0s
[19:34:16.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:153:0)
[19:34:16.494] Timer tick, asking Buildbucket for the build status
[19:34:16.560] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:16.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:154:0) after 1m0s
[19:35:16.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:154:0)
[19:35:16.691] Timer tick, asking Buildbucket for the build status
[19:35:16.763] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:16.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:155:0) after 1m0s
[19:36:16.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:155:0)
[19:36:16.844] Timer tick, asking Buildbucket for the build status
[19:36:16.901] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:16.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:156:0) after 1m0s
[19:37:17.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:156:0)
[19:37:17.002] Timer tick, asking Buildbucket for the build status
[19:37:17.077] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:17.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:157:0) after 1m0s
[19:38:17.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:157:0)
[19:38:17.212] Timer tick, asking Buildbucket for the build status
[19:38:17.305] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:17.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:158:0) after 1m0s
[19:39:17.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:158:0)
[19:39:17.585] Timer tick, asking Buildbucket for the build status
[19:39:17.741] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:17.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:159:0) after 1m0s
[19:40:18.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:159:0)
[19:40:18.192] Timer tick, asking Buildbucket for the build status
[19:40:18.258] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:18.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:160:0) after 1m0s
[19:41:18.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:160:0)
[19:41:18.378] Timer tick, asking Buildbucket for the build status
[19:41:18.603] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:18.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:161:0) after 1m0s
[19:42:18.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:161:0)
[19:42:18.835] Timer tick, asking Buildbucket for the build status
[19:42:18.899] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:18.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:162:0) after 1m0s
[19:43:18.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:162:0)
[19:43:18.977] Timer tick, asking Buildbucket for the build status
[19:43:19.068] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:19.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:163:0) after 1m0s
[19:44:19.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:163:0)
[19:44:19.140] Timer tick, asking Buildbucket for the build status
[19:44:19.201] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:19.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:164:0) after 1m0s
[19:45:19.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:164:0)
[19:45:19.266] Timer tick, asking Buildbucket for the build status
[19:45:19.346] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:19.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:165:0) after 1m0s
[19:46:19.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:165:0)
[19:46:19.534] Timer tick, asking Buildbucket for the build status
[19:46:19.605] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:19.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:166:0) after 1m0s
[19:47:19.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:166:0)
[19:47:19.892] Timer tick, asking Buildbucket for the build status
[19:47:20.161] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:20.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:167:0) after 1m0s
[19:48:20.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:167:0)
[19:48:20.368] Timer tick, asking Buildbucket for the build status
[19:48:20.556] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:20.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:168:0) after 1m0s
[19:49:20.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:168:0)
[19:49:20.750] Timer tick, asking Buildbucket for the build status
[19:49:20.867] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:20.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:169:0) after 1m0s
[19:50:21.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:169:0)
[19:50:21.240] Timer tick, asking Buildbucket for the build status
[19:50:21.442] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:21.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:170:0) after 1m0s
[19:51:21.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:170:0)
[19:51:21.464] Timer tick, asking Buildbucket for the build status
[19:51:21.549] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:21.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:171:0) after 1m0s
[19:52:21.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:171:0)
[19:52:21.647] Timer tick, asking Buildbucket for the build status
[19:52:21.746] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:21.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:172:0) after 1m0s
[19:53:21.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:172:0)
[19:53:21.772] Timer tick, asking Buildbucket for the build status
[19:53:21.910] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:21.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:173:0) after 1m0s
[19:54:21.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:173:0)
[19:54:21.920] Timer tick, asking Buildbucket for the build status
[19:54:22.231] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:22.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:174:0) after 1m0s
[19:55:22.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:174:0)
[19:55:22.357] Timer tick, asking Buildbucket for the build status
[19:55:22.435] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:22.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:175:0) after 1m0s
[19:56:22.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:175:0)
[19:56:22.554] Timer tick, asking Buildbucket for the build status
[19:56:22.622] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:22.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:176:0) after 1m0s
[19:57:22.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:176:0)
[19:57:22.714] Timer tick, asking Buildbucket for the build status
[19:57:22.835] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:22.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:177:0) after 1m0s
[19:58:22.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:177:0)
[19:58:22.858] Timer tick, asking Buildbucket for the build status
[19:58:22.994] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:22.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:178:0) after 1m0s
[19:59:23.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:178:0)
[19:59:23.049] Timer tick, asking Buildbucket for the build status
[19:59:23.349] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:23.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:179:0) after 1m0s
[20:00:23.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:179:0)
[20:00:23.409] Timer tick, asking Buildbucket for the build status
[20:00:23.590] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:23.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:180:0) after 1m0s
[20:01:23.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:180:0)
[20:01:23.665] Timer tick, asking Buildbucket for the build status
[20:01:23.749] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:23.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:181:0) after 1m0s
[20:02:23.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:181:0)
[20:02:23.805] Timer tick, asking Buildbucket for the build status
[20:02:24.112] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:24.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:182:0) after 1m0s
[20:03:24.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:182:0)
[20:03:24.207] Timer tick, asking Buildbucket for the build status
[20:03:24.379] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:24.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:183:0) after 1m0s
[20:04:24.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:183:0)
[20:04:24.426] Timer tick, asking Buildbucket for the build status
[20:04:24.515] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:24.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:184:0) after 1m0s
[20:05:24.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:184:0)
[20:05:24.669] Timer tick, asking Buildbucket for the build status
[20:05:24.805] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:24.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:185:0) after 1m0s
[20:06:24.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:185:0)
[20:06:24.829] Timer tick, asking Buildbucket for the build status
[20:06:24.891] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:24.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:186:0) after 1m0s
[20:07:24.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:186:0)
[20:07:24.871] Timer tick, asking Buildbucket for the build status
[20:07:24.982] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:24.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:187:0) after 1m0s
[20:08:25.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:187:0)
[20:08:25.021] Timer tick, asking Buildbucket for the build status
[20:08:25.242] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:25.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:188:0) after 1m0s
[20:09:25.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:188:0)
[20:09:25.260] Timer tick, asking Buildbucket for the build status
[20:09:25.340] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:25.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:189:0) after 1m0s
[20:10:25.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:189:0)
[20:10:25.384] Timer tick, asking Buildbucket for the build status
[20:10:25.450] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:25.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:190:0) after 1m0s
[20:11:25.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:190:0)
[20:11:25.516] Timer tick, asking Buildbucket for the build status
[20:11:25.725] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:25.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:191:0) after 1m0s
[20:12:25.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:191:0)
[20:12:25.740] Timer tick, asking Buildbucket for the build status
[20:12:25.858] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:25.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:192:0) after 1m0s
[20:13:25.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:192:0)
[20:13:25.909] Timer tick, asking Buildbucket for the build status
[20:13:26.029] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:26.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:193:0) after 1m0s
[20:14:26.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:193:0)
[20:14:26.100] Timer tick, asking Buildbucket for the build status
[20:14:26.179] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:26.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:194:0) after 1m0s
[20:15:26.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:194:0)
[20:15:26.202] Timer tick, asking Buildbucket for the build status
[20:15:26.281] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:26.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:195:0) after 1m0s
[20:16:26.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:195:0)
[20:16:26.297] Timer tick, asking Buildbucket for the build status
[20:16:26.381] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:26.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:196:0) after 1m0s
[20:17:26.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:196:0)
[20:17:26.383] Timer tick, asking Buildbucket for the build status
[20:17:26.441] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:26.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:197:0) after 1m0s
[20:18:26.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:197:0)
[20:18:26.614] Timer tick, asking Buildbucket for the build status
[20:18:26.728] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:26.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:198:0) after 1m0s
[20:19:26.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:198:0)
[20:19:26.804] Timer tick, asking Buildbucket for the build status
[20:19:26.880] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:26.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:199:0) after 1m0s
[20:20:26.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:199:0)
[20:20:26.934] Timer tick, asking Buildbucket for the build status
[20:20:27.118] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:27.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:200:0) after 1m0s
[20:21:27.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:200:0)
[20:21:27.192] Timer tick, asking Buildbucket for the build status
[20:21:27.745] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:27.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:201:0) after 1m0s
[20:22:27.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:201:0)
[20:22:27.819] Timer tick, asking Buildbucket for the build status
[20:22:27.936] Build 8861191633233564144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:27.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026621695487176512:202:0) after 1m0s
[20:22:36.116] Received PubSub notification, asking Buildbucket for the build status
[20:22:36.319] Build 8861191633233564144: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:22:36.319] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1607718154907604",
    "created_by": "project:infra",
    "created_ts": "1607706148839838",
    "id": "8861191633233564144",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4352.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"eb4c0ee8-3574-49d8-b732-fd1de653a188\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4352.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-zhca\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1607706168521233",
    "status": "COMPLETED",
    "status_changed_ts": "1607718155524845",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026621695487176512",
      "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/8861191633233564144/+/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:506b928be553df10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1607718155703317",
    "url": "https://ci.chromium.org/b/8861191633233564144",
    "utcnow_ts": "1607718156309184"
  }
}
[20:22:36.320] Invocation finished in 3h20m9.013557338s with status SUCCEEDED