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

• parent_buildername:publish_tarball
• user_agent:recipe

[08:08:38.177] New invocation is queued and will start shortly
[08:08:38.177] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:08:39.393] Starting the invocation (attempt 1)
[08:08:39.467] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:08:39.477] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:08:39.477] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9035261992289456432",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"87.0.4257.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"d52416a3-bcbf-47b0-b469-c45ad8f35c3c\",\"title\":\"publish_tarball/0\"}]},\"version\":\"87.0.4257.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:9035261992289456432",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:08:40.230] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1599466119658974",
    "id": "8869831930072034384",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4257.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d52416a3-bcbf-47b0-b469-c45ad8f35c3c\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4257.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1599466119941207",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9035261992289456432",
      "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/8869831930072034384/+/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": "1599466119941449",
    "url": "https://ci.chromium.org/b/8869831930072034384",
    "utcnow_ts": "1599466120207399"
  }
}
[08:08:40.230] Task URL: https://ci.chromium.org/b/8869831930072034384
[08:08:40.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:2:0) after 1m0s
[08:09:12.532] Received PubSub notification, asking Buildbucket for the build status
[08:09:12.669] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:40.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:2:0)
[08:09:40.323] Timer tick, asking Buildbucket for the build status
[08:09:40.889] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:40.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:4:0) after 1m0s
[08:10:40.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:4:0)
[08:10:40.949] Timer tick, asking Buildbucket for the build status
[08:10:41.109] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:41.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:5:0) after 1m0s
[08:11:41.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:5:0)
[08:11:41.398] Timer tick, asking Buildbucket for the build status
[08:11:41.663] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:41.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:6:0) after 1m0s
[08:12:41.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:6:0)
[08:12:41.843] Timer tick, asking Buildbucket for the build status
[08:12:42.044] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:42.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:7:0) after 1m0s
[08:13:42.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:7:0)
[08:13:42.068] Timer tick, asking Buildbucket for the build status
[08:13:42.337] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:42.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:8:0) after 1m0s
[08:14:42.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:8:0)
[08:14:42.574] Timer tick, asking Buildbucket for the build status
[08:14:42.938] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:42.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:9:0) after 1m0s
[08:15:43.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:9:0)
[08:15:43.055] Timer tick, asking Buildbucket for the build status
[08:15:43.150] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:43.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:10:0) after 1m0s
[08:16:43.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:10:0)
[08:16:43.177] Timer tick, asking Buildbucket for the build status
[08:16:43.271] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:43.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:11:0) after 1m0s
[08:17:43.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:11:0)
[08:17:43.304] Timer tick, asking Buildbucket for the build status
[08:17:43.527] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:43.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:12:0) after 1m0s
[08:18:43.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:12:0)
[08:18:43.570] Timer tick, asking Buildbucket for the build status
[08:18:43.744] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:43.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:13:0) after 1m0s
[08:19:43.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:13:0)
[08:19:43.762] Timer tick, asking Buildbucket for the build status
[08:19:43.864] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:43.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:14:0) after 1m0s
[08:20:43.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:14:0)
[08:20:43.870] Timer tick, asking Buildbucket for the build status
[08:20:43.946] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:43.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:15:0) after 1m0s
[08:21:43.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:15:0)
[08:21:43.965] Timer tick, asking Buildbucket for the build status
[08:21:44.054] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:44.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:16:0) after 1m0s
[08:22:44.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:16:0)
[08:22:44.180] Timer tick, asking Buildbucket for the build status
[08:22:44.293] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:44.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:17:0) after 1m0s
[08:23:44.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:17:0)
[08:23:44.454] Timer tick, asking Buildbucket for the build status
[08:23:44.685] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:44.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:18:0) after 1m0s
[08:24:44.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:18:0)
[08:24:44.737] Timer tick, asking Buildbucket for the build status
[08:24:44.987] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:44.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:19:0) after 1m0s
[08:25:45.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:19:0)
[08:25:45.113] Timer tick, asking Buildbucket for the build status
[08:25:45.193] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:45.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:20:0) after 1m0s
[08:26:45.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:20:0)
[08:26:45.233] Timer tick, asking Buildbucket for the build status
[08:26:45.311] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:45.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:21:0) after 1m0s
[08:27:45.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:21:0)
[08:27:45.341] Timer tick, asking Buildbucket for the build status
[08:27:45.408] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:45.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:22:0) after 1m0s
[08:28:45.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:22:0)
[08:28:45.504] Timer tick, asking Buildbucket for the build status
[08:28:46.552] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:46.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:23:0) after 1m0s
[08:29:46.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:23:0)
[08:29:46.607] Timer tick, asking Buildbucket for the build status
[08:29:46.758] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:46.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:24:0) after 1m0s
[08:30:46.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:24:0)
[08:30:46.812] Timer tick, asking Buildbucket for the build status
[08:30:46.926] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:46.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:25:0) after 1m0s
[08:31:46.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:25:0)
[08:31:46.929] Timer tick, asking Buildbucket for the build status
[08:31:47.420] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:47.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:26:0) after 1m0s
[08:32:47.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:26:0)
[08:32:47.693] Timer tick, asking Buildbucket for the build status
[08:32:48.302] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:48.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:27:0) after 1m0s
[08:33:48.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:27:0)
[08:33:48.367] Timer tick, asking Buildbucket for the build status
[08:33:48.819] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:48.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:28:0) after 1m0s
[08:34:49.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:28:0)
[08:34:49.379] Timer tick, asking Buildbucket for the build status
[08:34:49.601] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:49.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:29:0) after 1m0s
[08:35:49.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:29:0)
[08:35:49.657] Timer tick, asking Buildbucket for the build status
[08:35:49.839] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:49.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:30:0) after 1m0s
[08:36:49.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:30:0)
[08:36:49.862] Timer tick, asking Buildbucket for the build status
[08:36:49.927] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:49.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:31:0) after 1m0s
[08:37:49.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:31:0)
[08:37:49.972] Timer tick, asking Buildbucket for the build status
[08:37:50.244] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:50.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:32:0) after 1m0s
[08:38:50.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:32:0)
[08:38:50.330] Timer tick, asking Buildbucket for the build status
[08:38:50.453] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:50.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:33:0) after 1m0s
[08:39:50.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:33:0)
[08:39:50.498] Timer tick, asking Buildbucket for the build status
[08:39:50.595] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:50.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:34:0) after 1m0s
[08:40:50.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:34:0)
[08:40:50.643] Timer tick, asking Buildbucket for the build status
[08:40:50.724] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:50.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:35:0) after 1m0s
[08:41:50.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:35:0)
[08:41:50.725] Timer tick, asking Buildbucket for the build status
[08:41:51.592] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:51.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:36:0) after 1m0s
[08:42:51.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:36:0)
[08:42:51.614] Timer tick, asking Buildbucket for the build status
[08:42:51.878] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:51.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:37:0) after 1m0s
[08:43:51.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:37:0)
[08:43:51.978] Timer tick, asking Buildbucket for the build status
[08:43:52.113] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:52.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:38:0) after 1m0s
[08:44:52.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:38:0)
[08:44:52.135] Timer tick, asking Buildbucket for the build status
[08:44:52.271] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:52.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:39:0) after 1m0s
[08:45:52.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:39:0)
[08:45:52.349] Timer tick, asking Buildbucket for the build status
[08:45:52.464] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:52.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:40:0) after 1m0s
[08:46:52.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:40:0)
[08:46:52.492] Timer tick, asking Buildbucket for the build status
[08:46:52.816] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:52.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:41:0) after 1m0s
[08:47:52.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:41:0)
[08:47:52.821] Timer tick, asking Buildbucket for the build status
[08:47:52.929] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:52.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:42:0) after 1m0s
[08:48:52.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:42:0)
[08:48:52.995] Timer tick, asking Buildbucket for the build status
[08:48:53.406] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:53.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:43:0) after 1m0s
[08:49:53.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:43:0)
[08:49:53.539] Timer tick, asking Buildbucket for the build status
[08:49:53.735] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:53.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:44:0) after 1m0s
[08:50:54.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:44:0)
[08:50:54.227] Timer tick, asking Buildbucket for the build status
[08:50:54.486] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:54.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:45:0) after 1m0s
[08:51:54.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:45:0)
[08:51:54.597] Timer tick, asking Buildbucket for the build status
[08:51:54.683] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:54.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:46:0) after 1m0s
[08:52:54.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:46:0)
[08:52:54.705] Timer tick, asking Buildbucket for the build status
[08:52:54.809] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:54.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:47:0) after 1m0s
[08:53:54.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:47:0)
[08:53:54.868] Timer tick, asking Buildbucket for the build status
[08:53:55.368] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:55.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:48:0) after 1m0s
[08:54:55.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:48:0)
[08:54:55.425] Timer tick, asking Buildbucket for the build status
[08:54:55.687] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:55.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:49:0) after 1m0s
[08:55:55.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:49:0)
[08:55:55.721] Timer tick, asking Buildbucket for the build status
[08:55:55.846] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:55.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:50:0) after 1m0s
[08:56:55.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:50:0)
[08:56:55.868] Timer tick, asking Buildbucket for the build status
[08:56:56.219] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:56.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:51:0) after 1m0s
[08:57:56.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:51:0)
[08:57:56.285] Timer tick, asking Buildbucket for the build status
[08:57:56.378] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:56.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:52:0) after 1m0s
[08:58:56.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:52:0)
[08:58:56.651] Timer tick, asking Buildbucket for the build status
[08:58:56.957] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:56.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:53:0) after 1m0s
[08:59:56.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:53:0)
[08:59:56.944] Timer tick, asking Buildbucket for the build status
[08:59:57.199] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:57.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:54:0) after 1m0s
[09:00:57.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:54:0)
[09:00:57.511] Timer tick, asking Buildbucket for the build status
[09:00:57.701] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:57.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:55:0) after 1m0s
[09:01:57.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:55:0)
[09:01:57.748] Timer tick, asking Buildbucket for the build status
[09:01:57.842] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:57.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:56:0) after 1m0s
[09:02:57.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:56:0)
[09:02:57.866] Timer tick, asking Buildbucket for the build status
[09:02:59.229] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:59.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:57:0) after 1m0s
[09:03:59.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:57:0)
[09:03:59.291] Timer tick, asking Buildbucket for the build status
[09:04:00.138] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:00.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:58:0) after 1m0s
[09:05:00.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:58:0)
[09:05:00.188] Timer tick, asking Buildbucket for the build status
[09:05:00.533] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:00.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:59:0) after 1m0s
[09:06:00.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:59:0)
[09:06:00.562] Timer tick, asking Buildbucket for the build status
[09:06:00.651] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:00.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:60:0) after 1m0s
[09:07:02.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:60:0)
[09:07:02.139] Timer tick, asking Buildbucket for the build status
[09:07:02.378] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:02.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:61:0) after 1m0s
[09:08:02.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:61:0)
[09:08:02.637] Timer tick, asking Buildbucket for the build status
[09:08:02.742] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:02.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:62:0) after 1m0s
[09:09:02.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:62:0)
[09:09:02.774] Timer tick, asking Buildbucket for the build status
[09:09:03.039] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:03.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:63:0) after 1m0s
[09:10:03.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:63:0)
[09:10:03.108] Timer tick, asking Buildbucket for the build status
[09:10:03.318] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:03.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:64:0) after 1m0s
[09:11:03.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:64:0)
[09:11:03.439] Timer tick, asking Buildbucket for the build status
[09:11:03.585] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:03.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:65:0) after 1m0s
[09:12:03.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:65:0)
[09:12:03.595] Timer tick, asking Buildbucket for the build status
[09:12:03.658] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:03.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:66:0) after 1m0s
[09:13:03.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:66:0)
[09:13:03.686] Timer tick, asking Buildbucket for the build status
[09:13:03.792] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:03.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:67:0) after 1m0s
[09:14:03.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:67:0)
[09:14:03.815] Timer tick, asking Buildbucket for the build status
[09:14:03.999] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:03.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:68:0) after 1m0s
[09:15:04.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:68:0)
[09:15:04.125] Timer tick, asking Buildbucket for the build status
[09:15:04.283] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:04.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:69:0) after 1m0s
[09:16:04.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:69:0)
[09:16:04.333] Timer tick, asking Buildbucket for the build status
[09:16:04.444] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:04.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:70:0) after 1m0s
[09:17:04.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:70:0)
[09:17:04.486] Timer tick, asking Buildbucket for the build status
[09:17:04.546] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:04.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:71:0) after 1m0s
[09:18:04.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:71:0)
[09:18:04.781] Timer tick, asking Buildbucket for the build status
[09:18:04.847] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:04.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:72:0) after 1m0s
[09:19:05.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:72:0)
[09:19:05.196] Timer tick, asking Buildbucket for the build status
[09:19:05.647] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:05.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:73:0) after 1m0s
[09:20:05.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:73:0)
[09:20:05.801] Timer tick, asking Buildbucket for the build status
[09:20:05.907] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:05.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:74:0) after 1m0s
[09:21:06.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:74:0)
[09:21:06.832] Timer tick, asking Buildbucket for the build status
[09:21:07.047] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:07.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:75:0) after 1m0s
[09:22:07.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:75:0)
[09:22:07.095] Timer tick, asking Buildbucket for the build status
[09:22:07.197] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:07.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:76:0) after 1m0s
[09:23:07.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:76:0)
[09:23:07.400] Timer tick, asking Buildbucket for the build status
[09:23:07.782] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:07.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:77:0) after 1m0s
[09:24:08.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:77:0)
[09:24:08.194] Timer tick, asking Buildbucket for the build status
[09:24:08.917] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:08.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:78:0) after 1m0s
[09:25:10.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:78:0)
[09:25:10.239] Timer tick, asking Buildbucket for the build status
[09:25:10.347] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:10.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:79:0) after 1m0s
[09:26:10.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:79:0)
[09:26:10.425] Timer tick, asking Buildbucket for the build status
[09:26:10.564] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:10.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:80:0) after 1m0s
[09:27:10.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:80:0)
[09:27:10.618] Timer tick, asking Buildbucket for the build status
[09:27:10.782] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:10.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:81:0) after 1m0s
[09:28:10.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:81:0)
[09:28:10.975] Timer tick, asking Buildbucket for the build status
[09:28:11.106] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:11.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:82:0) after 1m0s
[09:29:11.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:82:0)
[09:29:11.151] Timer tick, asking Buildbucket for the build status
[09:29:11.625] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:11.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:83:0) after 1m0s
[09:30:11.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:83:0)
[09:30:11.729] Timer tick, asking Buildbucket for the build status
[09:30:11.872] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:11.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:84:0) after 1m0s
[09:31:12.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:84:0)
[09:31:12.187] Timer tick, asking Buildbucket for the build status
[09:31:12.350] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:12.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:85:0) after 1m0s
[09:32:12.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:85:0)
[09:32:12.486] Timer tick, asking Buildbucket for the build status
[09:32:12.760] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:12.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:86:0) after 1m0s
[09:33:12.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:86:0)
[09:33:12.880] Timer tick, asking Buildbucket for the build status
[09:33:13.205] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:13.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:87:0) after 1m0s
[09:34:13.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:87:0)
[09:34:13.259] Timer tick, asking Buildbucket for the build status
[09:34:13.683] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:13.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:88:0) after 1m0s
[09:35:13.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:88:0)
[09:35:13.838] Timer tick, asking Buildbucket for the build status
[09:35:13.976] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:13.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:89:0) after 1m0s
[09:36:14.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:89:0)
[09:36:14.072] Timer tick, asking Buildbucket for the build status
[09:36:14.242] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:14.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:90:0) after 1m0s
[09:37:14.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:90:0)
[09:37:14.265] Timer tick, asking Buildbucket for the build status
[09:37:14.492] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:14.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:91:0) after 1m0s
[09:38:14.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:91:0)
[09:38:14.487] Timer tick, asking Buildbucket for the build status
[09:38:14.623] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:14.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:92:0) after 1m0s
[09:39:15.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:92:0)
[09:39:15.088] Timer tick, asking Buildbucket for the build status
[09:39:15.323] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:15.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:93:0) after 1m0s
[09:40:15.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:93:0)
[09:40:15.521] Timer tick, asking Buildbucket for the build status
[09:40:15.623] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:15.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:94:0) after 1m0s
[09:41:15.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:94:0)
[09:41:15.812] Timer tick, asking Buildbucket for the build status
[09:41:15.919] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:15.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:95:0) after 1m0s
[09:42:16.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:95:0)
[09:42:16.002] Timer tick, asking Buildbucket for the build status
[09:42:16.116] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:16.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:96:0) after 1m0s
[09:43:16.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:96:0)
[09:43:16.153] Timer tick, asking Buildbucket for the build status
[09:43:16.614] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:16.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:97:0) after 1m0s
[09:44:16.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:97:0)
[09:44:16.731] Timer tick, asking Buildbucket for the build status
[09:44:16.958] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:16.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:98:0) after 1m0s
[09:45:17.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:98:0)
[09:45:17.174] Timer tick, asking Buildbucket for the build status
[09:45:17.348] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:17.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:99:0) after 1m0s
[09:46:17.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:99:0)
[09:46:17.422] Timer tick, asking Buildbucket for the build status
[09:46:17.486] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:17.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:100:0) after 1m0s
[09:47:17.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:100:0)
[09:47:17.532] Timer tick, asking Buildbucket for the build status
[09:47:17.612] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:17.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:101:0) after 1m0s
[09:48:17.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:101:0)
[09:48:17.726] Timer tick, asking Buildbucket for the build status
[09:48:17.824] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:17.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:102:0) after 1m0s
[09:49:17.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:102:0)
[09:49:17.818] Timer tick, asking Buildbucket for the build status
[09:49:18.120] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:18.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:103:0) after 1m0s
[09:50:18.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:103:0)
[09:50:18.173] Timer tick, asking Buildbucket for the build status
[09:50:18.269] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:18.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:104:0) after 1m0s
[09:51:18.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:104:0)
[09:51:18.271] Timer tick, asking Buildbucket for the build status
[09:51:18.378] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:18.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:105:0) after 1m0s
[09:52:18.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:105:0)
[09:52:18.436] Timer tick, asking Buildbucket for the build status
[09:52:18.523] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:18.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:106:0) after 1m0s
[09:53:18.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:106:0)
[09:53:18.584] Timer tick, asking Buildbucket for the build status
[09:53:18.695] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:18.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:107:0) after 1m0s
[09:54:18.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:107:0)
[09:54:18.747] Timer tick, asking Buildbucket for the build status
[09:54:19.683] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:19.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:108:0) after 1m0s
[09:55:19.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:108:0)
[09:55:19.850] Timer tick, asking Buildbucket for the build status
[09:55:19.974] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:19.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:109:0) after 1m0s
[09:56:20.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:109:0)
[09:56:20.049] Timer tick, asking Buildbucket for the build status
[09:56:20.130] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:20.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:110:0) after 1m0s
[09:57:20.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:110:0)
[09:57:20.292] Timer tick, asking Buildbucket for the build status
[09:57:20.728] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:20.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:111:0) after 1m0s
[09:58:20.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:111:0)
[09:58:20.901] Timer tick, asking Buildbucket for the build status
[09:58:21.259] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:21.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:112:0) after 1m0s
[09:59:22.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:112:0)
[09:59:22.024] Timer tick, asking Buildbucket for the build status
[09:59:22.273] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:22.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:113:0) after 1m0s
[10:00:22.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:113:0)
[10:00:22.354] Timer tick, asking Buildbucket for the build status
[10:00:22.784] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:22.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:114:0) after 1m0s
[10:01:24.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:114:0)
[10:01:24.419] Timer tick, asking Buildbucket for the build status
[10:01:24.533] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:24.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:115:0) after 1m0s
[10:02:24.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:115:0)
[10:02:24.589] Timer tick, asking Buildbucket for the build status
[10:02:24.761] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:24.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:116:0) after 1m0s
[10:03:24.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:116:0)
[10:03:24.813] Timer tick, asking Buildbucket for the build status
[10:03:25.183] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:25.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:117:0) after 1m0s
[10:04:25.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:117:0)
[10:04:25.283] Timer tick, asking Buildbucket for the build status
[10:04:25.469] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:25.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:118:0) after 1m0s
[10:05:25.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:118:0)
[10:05:25.580] Timer tick, asking Buildbucket for the build status
[10:05:25.679] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:25.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:119:0) after 1m0s
[10:06:25.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:119:0)
[10:06:25.794] Timer tick, asking Buildbucket for the build status
[10:06:25.899] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:25.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:120:0) after 1m0s
[10:07:26.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:120:0)
[10:07:26.022] Timer tick, asking Buildbucket for the build status
[10:07:26.419] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:26.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:121:0) after 1m0s
[10:08:26.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:121:0)
[10:08:26.567] Timer tick, asking Buildbucket for the build status
[10:08:26.847] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:26.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:122:0) after 1m0s
[10:09:27.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:122:0)
[10:09:27.156] Timer tick, asking Buildbucket for the build status
[10:09:27.837] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:27.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:123:0) after 1m0s
[10:10:28.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:123:0)
[10:10:28.013] Timer tick, asking Buildbucket for the build status
[10:10:28.078] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:28.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:124:0) after 1m0s
[10:11:28.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:124:0)
[10:11:28.341] Timer tick, asking Buildbucket for the build status
[10:11:28.531] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:28.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:125:0) after 1m0s
[10:12:28.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:125:0)
[10:12:28.650] Timer tick, asking Buildbucket for the build status
[10:12:28.711] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:28.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:126:0) after 1m0s
[10:13:28.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:126:0)
[10:13:28.817] Timer tick, asking Buildbucket for the build status
[10:13:29.307] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:29.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:127:0) after 1m0s
[10:14:29.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:127:0)
[10:14:29.872] Timer tick, asking Buildbucket for the build status
[10:14:30.054] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:30.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:128:0) after 1m0s
[10:15:30.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:128:0)
[10:15:30.230] Timer tick, asking Buildbucket for the build status
[10:15:30.342] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:30.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:129:0) after 1m0s
[10:16:30.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:129:0)
[10:16:30.621] Timer tick, asking Buildbucket for the build status
[10:16:30.705] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:30.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:130:0) after 1m0s
[10:17:30.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:130:0)
[10:17:30.800] Timer tick, asking Buildbucket for the build status
[10:17:30.929] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:30.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:131:0) after 1m0s
[10:18:30.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:131:0)
[10:18:30.918] Timer tick, asking Buildbucket for the build status
[10:18:31.196] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:31.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:132:0) after 1m0s
[10:19:31.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:132:0)
[10:19:31.313] Timer tick, asking Buildbucket for the build status
[10:19:31.646] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:31.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:133:0) after 1m0s
[10:20:31.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:133:0)
[10:20:31.683] Timer tick, asking Buildbucket for the build status
[10:20:31.773] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:31.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:134:0) after 1m0s
[10:21:31.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:134:0)
[10:21:31.804] Timer tick, asking Buildbucket for the build status
[10:21:31.886] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:31.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:135:0) after 1m0s
[10:22:31.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:135:0)
[10:22:31.939] Timer tick, asking Buildbucket for the build status
[10:22:32.127] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:32.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:136:0) after 1m0s
[10:23:32.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:136:0)
[10:23:32.165] Timer tick, asking Buildbucket for the build status
[10:23:32.548] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:32.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:137:0) after 1m0s
[10:24:32.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:137:0)
[10:24:32.819] Timer tick, asking Buildbucket for the build status
[10:24:33.032] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:33.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:138:0) after 1m0s
[10:25:33.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:138:0)
[10:25:33.116] Timer tick, asking Buildbucket for the build status
[10:25:33.455] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:33.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:139:0) after 1m0s
[10:26:33.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:139:0)
[10:26:33.532] Timer tick, asking Buildbucket for the build status
[10:26:33.602] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:33.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:140:0) after 1m0s
[10:27:33.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:140:0)
[10:27:33.635] Timer tick, asking Buildbucket for the build status
[10:27:33.951] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:33.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:141:0) after 1m0s
[10:28:34.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:141:0)
[10:28:34.043] Timer tick, asking Buildbucket for the build status
[10:28:34.270] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:34.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:142:0) after 1m0s
[10:29:34.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:142:0)
[10:29:34.311] Timer tick, asking Buildbucket for the build status
[10:29:34.472] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:34.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:143:0) after 1m0s
[10:30:34.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:143:0)
[10:30:34.520] Timer tick, asking Buildbucket for the build status
[10:30:34.649] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:34.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:144:0) after 1m0s
[10:31:34.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:144:0)
[10:31:34.674] Timer tick, asking Buildbucket for the build status
[10:31:34.746] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:34.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:145:0) after 1m0s
[10:32:34.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:145:0)
[10:32:34.769] Timer tick, asking Buildbucket for the build status
[10:32:34.868] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:34.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:146:0) after 1m0s
[10:33:34.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:146:0)
[10:33:34.895] Timer tick, asking Buildbucket for the build status
[10:33:34.981] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:34.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:147:0) after 1m0s
[10:34:35.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:147:0)
[10:34:35.385] Timer tick, asking Buildbucket for the build status
[10:34:36.237] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:36.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:148:0) after 1m0s
[10:35:36.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:148:0)
[10:35:36.331] Timer tick, asking Buildbucket for the build status
[10:35:36.450] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:36.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:149:0) after 1m0s
[10:36:36.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:149:0)
[10:36:36.470] Timer tick, asking Buildbucket for the build status
[10:36:36.623] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:36.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:150:0) after 1m0s
[10:37:36.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:150:0)
[10:37:36.674] Timer tick, asking Buildbucket for the build status
[10:37:36.766] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:36.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:151:0) after 1m0s
[10:38:36.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:151:0)
[10:38:36.821] Timer tick, asking Buildbucket for the build status
[10:38:36.967] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:36.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:152:0) after 1m0s
[10:39:37.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:152:0)
[10:39:37.143] Timer tick, asking Buildbucket for the build status
[10:39:37.329] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:37.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:153:0) after 1m0s
[10:40:37.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:153:0)
[10:40:37.337] Timer tick, asking Buildbucket for the build status
[10:40:37.444] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:37.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:154:0) after 1m0s
[10:41:37.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:154:0)
[10:41:37.557] Timer tick, asking Buildbucket for the build status
[10:41:37.798] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:37.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:155:0) after 1m0s
[10:42:38.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:155:0)
[10:42:38.127] Timer tick, asking Buildbucket for the build status
[10:42:38.214] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:38.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:156:0) after 1m0s
[10:43:38.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:156:0)
[10:43:38.261] Timer tick, asking Buildbucket for the build status
[10:43:38.339] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:38.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:157:0) after 1m0s
[10:44:38.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:157:0)
[10:44:38.360] Timer tick, asking Buildbucket for the build status
[10:44:38.709] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:38.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:158:0) after 1m0s
[10:45:38.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:158:0)
[10:45:38.733] Timer tick, asking Buildbucket for the build status
[10:45:38.802] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:38.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:159:0) after 1m0s
[10:46:38.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:159:0)
[10:46:38.891] Timer tick, asking Buildbucket for the build status
[10:46:39.035] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:39.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:160:0) after 1m0s
[10:47:39.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:160:0)
[10:47:39.113] Timer tick, asking Buildbucket for the build status
[10:47:39.198] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:39.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:161:0) after 1m0s
[10:48:39.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:161:0)
[10:48:39.512] Timer tick, asking Buildbucket for the build status
[10:48:39.610] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:39.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:162:0) after 1m0s
[10:49:39.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:162:0)
[10:49:39.936] Timer tick, asking Buildbucket for the build status
[10:49:40.117] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:40.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:163:0) after 1m0s
[10:50:40.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:163:0)
[10:50:40.150] Timer tick, asking Buildbucket for the build status
[10:50:40.258] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:40.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:164:0) after 1m0s
[10:51:40.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:164:0)
[10:51:40.897] Timer tick, asking Buildbucket for the build status
[10:51:41.140] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:41.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:165:0) after 1m0s
[10:52:41.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:165:0)
[10:52:41.246] Timer tick, asking Buildbucket for the build status
[10:52:41.659] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:41.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:166:0) after 1m0s
[10:53:41.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:166:0)
[10:53:41.761] Timer tick, asking Buildbucket for the build status
[10:53:42.216] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:42.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:167:0) after 1m0s
[10:54:42.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:167:0)
[10:54:42.310] Timer tick, asking Buildbucket for the build status
[10:54:42.803] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:42.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:168:0) after 1m0s
[10:55:42.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:168:0)
[10:55:42.870] Timer tick, asking Buildbucket for the build status
[10:55:42.964] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:42.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:169:0) after 1m0s
[10:56:42.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:169:0)
[10:56:42.993] Timer tick, asking Buildbucket for the build status
[10:56:43.546] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:43.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:170:0) after 1m0s
[10:57:43.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:170:0)
[10:57:43.597] Timer tick, asking Buildbucket for the build status
[10:57:43.787] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:43.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:171:0) after 1m0s
[10:58:43.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:171:0)
[10:58:43.891] Timer tick, asking Buildbucket for the build status
[10:58:44.157] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:44.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:172:0) after 1m0s
[10:59:44.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:172:0)
[10:59:44.247] Timer tick, asking Buildbucket for the build status
[10:59:44.401] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:44.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:173:0) after 1m0s
[11:00:44.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:173:0)
[11:00:44.499] Timer tick, asking Buildbucket for the build status
[11:00:45.437] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:45.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:174:0) after 1m0s
[11:01:45.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:174:0)
[11:01:45.470] Timer tick, asking Buildbucket for the build status
[11:01:45.565] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:45.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:175:0) after 1m0s
[11:02:45.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:175:0)
[11:02:45.591] Timer tick, asking Buildbucket for the build status
[11:02:45.656] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:45.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:176:0) after 1m0s
[11:03:45.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:176:0)
[11:03:45.679] Timer tick, asking Buildbucket for the build status
[11:03:45.765] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:45.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:177:0) after 1m0s
[11:04:45.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:177:0)
[11:04:45.791] Timer tick, asking Buildbucket for the build status
[11:04:45.955] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:45.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:178:0) after 1m0s
[11:05:46.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:178:0)
[11:05:46.085] Timer tick, asking Buildbucket for the build status
[11:05:46.229] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:46.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:179:0) after 1m0s
[11:06:46.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:179:0)
[11:06:46.484] Timer tick, asking Buildbucket for the build status
[11:06:46.588] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:46.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:180:0) after 1m0s
[11:07:46.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:180:0)
[11:07:46.720] Timer tick, asking Buildbucket for the build status
[11:07:46.891] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:46.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:181:0) after 1m0s
[11:08:47.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:181:0)
[11:08:47.038] Timer tick, asking Buildbucket for the build status
[11:08:57.771] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:57.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:182:0) after 1m0s
[11:09:57.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:182:0)
[11:09:57.847] Timer tick, asking Buildbucket for the build status
[11:09:57.946] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:57.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:183:0) after 1m0s
[11:10:57.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:183:0)
[11:10:57.984] Timer tick, asking Buildbucket for the build status
[11:10:58.153] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:58.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:184:0) after 1m0s
[11:11:58.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:184:0)
[11:11:58.291] Timer tick, asking Buildbucket for the build status
[11:11:58.405] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:58.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:185:0) after 1m0s
[11:12:58.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:185:0)
[11:12:58.801] Timer tick, asking Buildbucket for the build status
[11:12:58.916] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:58.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:186:0) after 1m0s
[11:13:58.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:186:0)
[11:13:58.968] Timer tick, asking Buildbucket for the build status
[11:13:59.215] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:59.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:187:0) after 1m0s
[11:14:59.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:187:0)
[11:14:59.306] Timer tick, asking Buildbucket for the build status
[11:14:59.405] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:59.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:188:0) after 1m0s
[11:15:59.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:188:0)
[11:15:59.439] Timer tick, asking Buildbucket for the build status
[11:15:59.535] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:59.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:189:0) after 1m0s
[11:16:59.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:189:0)
[11:16:59.916] Timer tick, asking Buildbucket for the build status
[11:17:00.167] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:00.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:190:0) after 1m0s
[11:18:00.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:190:0)
[11:18:00.209] Timer tick, asking Buildbucket for the build status
[11:18:00.333] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:00.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:191:0) after 1m0s
[11:19:00.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:191:0)
[11:19:00.398] Timer tick, asking Buildbucket for the build status
[11:19:00.893] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:00.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:192:0) after 1m0s
[11:20:00.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:192:0)
[11:20:00.950] Timer tick, asking Buildbucket for the build status
[11:20:01.098] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:01.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:193:0) after 1m0s
[11:21:02.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:193:0)
[11:21:02.091] Timer tick, asking Buildbucket for the build status
[11:21:02.731] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:02.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:194:0) after 1m0s
[11:22:02.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:194:0)
[11:22:02.777] Timer tick, asking Buildbucket for the build status
[11:22:02.926] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:02.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:195:0) after 1m0s
[11:23:03.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:195:0)
[11:23:03.837] Timer tick, asking Buildbucket for the build status
[11:23:04.588] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:04.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:196:0) after 1m0s
[11:24:04.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:196:0)
[11:24:04.701] Timer tick, asking Buildbucket for the build status
[11:24:04.932] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:04.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:197:0) after 1m0s
[11:25:05.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:197:0)
[11:25:05.187] Timer tick, asking Buildbucket for the build status
[11:25:05.337] Build 8869831930072034384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:05.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9035261992289456432:198:0) after 1m0s
[11:25:33.992] Received PubSub notification, asking Buildbucket for the build status
[11:25:34.174] Build 8869831930072034384: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:25:34.174] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1599477933120753",
    "created_by": "project:infra",
    "created_ts": "1599466119658974",
    "id": "8869831930072034384",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4257.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d52416a3-bcbf-47b0-b469-c45ad8f35c3c\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4257.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-m4wc\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5511-73542fe\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1599466151787696",
    "status": "COMPLETED",
    "status_changed_ts": "1599477933619090",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9035261992289456432",
      "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/8869831930072034384/+/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:4e806dae6e722510",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1599477933678811",
    "url": "https://ci.chromium.org/b/8869831930072034384",
    "utcnow_ts": "1599477934161292"
  }
}
[11:25:34.174] Invocation finished in 3h16m56.01561237s with status SUCCEEDED