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

• parent_buildername:publish_tarball
• user_agent:recipe

[12:03:04.197] New invocation is queued and will start shortly
[12:03:04.197] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:03:05.343] Starting the invocation (attempt 1)
[12:03:05.381] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:03:05.381] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:03:05.381] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9026821725121276352",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4350.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"723b143f-7d69-4c74-ad9b-9d0f73fbd168\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4350.2\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9026821725121276352",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:03:05.929] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1607515385472438",
    "id": "8861391663121475312",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4350.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"723b143f-7d69-4c74-ad9b-9d0f73fbd168\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4350.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1607515385671056",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026821725121276352",
      "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/8861391663121475312/+/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": "1607515385671242",
    "url": "https://ci.chromium.org/b/8861391663121475312",
    "utcnow_ts": "1607515385922500"
  }
}
[12:03:05.929] Task URL: https://ci.chromium.org/b/8861391663121475312
[12:03:05.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:2:0) after 1m0s
[12:03:54.802] Received PubSub notification, asking Buildbucket for the build status
[12:03:54.889] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:05.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:2:0)
[12:04:05.958] Timer tick, asking Buildbucket for the build status
[12:04:07.726] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:07.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:4:0) after 1m0s
[12:05:07.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:4:0)
[12:05:07.765] Timer tick, asking Buildbucket for the build status
[12:05:08.120] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:08.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:5:0) after 1m0s
[12:06:08.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:5:0)
[12:06:08.144] Timer tick, asking Buildbucket for the build status
[12:06:08.253] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:08.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:6:0) after 1m0s
[12:07:08.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:6:0)
[12:07:08.350] Timer tick, asking Buildbucket for the build status
[12:07:08.608] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:08.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:7:0) after 1m0s
[12:08:08.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:7:0)
[12:08:08.668] Timer tick, asking Buildbucket for the build status
[12:08:08.740] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:08.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:8:0) after 1m0s
[12:09:08.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:8:0)
[12:09:08.798] Timer tick, asking Buildbucket for the build status
[12:09:09.078] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:09.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:9:0) after 1m0s
[12:10:09.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:9:0)
[12:10:09.964] Timer tick, asking Buildbucket for the build status
[12:10:10.221] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:10.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:10:0) after 1m0s
[12:11:10.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:10:0)
[12:11:10.393] Timer tick, asking Buildbucket for the build status
[12:11:10.460] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:10.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:11:0) after 1m0s
[12:12:10.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:11:0)
[12:12:10.622] Timer tick, asking Buildbucket for the build status
[12:12:10.754] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:10.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:12:0) after 1m0s
[12:13:10.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:12:0)
[12:13:10.869] Timer tick, asking Buildbucket for the build status
[12:13:11.317] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:11.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:13:0) after 1m0s
[12:14:11.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:13:0)
[12:14:11.399] Timer tick, asking Buildbucket for the build status
[12:14:16.109] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:16.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:14:0) after 1m0s
[12:15:16.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:14:0)
[12:15:16.163] Timer tick, asking Buildbucket for the build status
[12:15:16.253] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:16.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:15:0) after 1m0s
[12:16:16.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:15:0)
[12:16:16.328] Timer tick, asking Buildbucket for the build status
[12:16:16.561] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:16.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:16:0) after 1m0s
[12:17:16.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:16:0)
[12:17:16.590] Timer tick, asking Buildbucket for the build status
[12:17:16.695] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:16.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:17:0) after 1m0s
[12:18:16.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:17:0)
[12:18:16.716] Timer tick, asking Buildbucket for the build status
[12:18:17.224] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:17.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:18:0) after 1m0s
[12:19:17.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:18:0)
[12:19:17.249] Timer tick, asking Buildbucket for the build status
[12:19:17.337] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:17.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:19:0) after 1m0s
[12:20:17.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:19:0)
[12:20:17.475] Timer tick, asking Buildbucket for the build status
[12:20:17.822] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:17.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:20:0) after 1m0s
[12:21:17.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:20:0)
[12:21:17.883] Timer tick, asking Buildbucket for the build status
[12:21:17.948] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:17.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:21:0) after 1m0s
[12:22:18.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:21:0)
[12:22:18.025] Timer tick, asking Buildbucket for the build status
[12:22:18.162] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:18.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:22:0) after 1m0s
[12:23:18.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:22:0)
[12:23:18.195] Timer tick, asking Buildbucket for the build status
[12:23:18.609] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:18.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:23:0) after 1m0s
[12:24:18.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:23:0)
[12:24:18.653] Timer tick, asking Buildbucket for the build status
[12:24:18.722] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:18.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:24:0) after 1m0s
[12:25:18.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:24:0)
[12:25:18.748] Timer tick, asking Buildbucket for the build status
[12:25:19.918] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:19.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:25:0) after 1m0s
[12:26:20.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:25:0)
[12:26:20.013] Timer tick, asking Buildbucket for the build status
[12:26:20.236] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:20.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:26:0) after 1m0s
[12:27:20.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:26:0)
[12:27:20.212] Timer tick, asking Buildbucket for the build status
[12:27:20.311] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:20.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:27:0) after 1m0s
[12:28:20.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:27:0)
[12:28:20.332] Timer tick, asking Buildbucket for the build status
[12:28:20.547] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:20.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:28:0) after 1m0s
[12:29:20.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:28:0)
[12:29:20.570] Timer tick, asking Buildbucket for the build status
[12:29:20.713] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:20.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:29:0) after 1m0s
[12:30:20.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:29:0)
[12:30:20.703] Timer tick, asking Buildbucket for the build status
[12:30:20.922] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:20.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:30:0) after 1m0s
[12:31:21.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:30:0)
[12:31:21.737] Timer tick, asking Buildbucket for the build status
[12:31:21.822] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:21.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:31:0) after 1m0s
[12:32:21.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:31:0)
[12:32:21.866] Timer tick, asking Buildbucket for the build status
[12:32:22.103] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:22.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:32:0) after 1m0s
[12:33:22.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:32:0)
[12:33:22.211] Timer tick, asking Buildbucket for the build status
[12:33:22.402] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:22.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:33:0) after 1m0s
[12:34:22.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:33:0)
[12:34:22.459] Timer tick, asking Buildbucket for the build status
[12:34:22.513] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:22.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:34:0) after 1m0s
[12:35:22.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:34:0)
[12:35:22.551] Timer tick, asking Buildbucket for the build status
[12:35:22.800] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:22.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:35:0) after 1m0s
[12:36:22.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:35:0)
[12:36:22.888] Timer tick, asking Buildbucket for the build status
[12:36:22.964] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:22.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:36:0) after 1m0s
[12:37:23.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:36:0)
[12:37:23.309] Timer tick, asking Buildbucket for the build status
[12:37:23.505] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:23.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:37:0) after 1m0s
[12:38:23.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:37:0)
[12:38:23.670] Timer tick, asking Buildbucket for the build status
[12:38:23.791] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:23.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:38:0) after 1m0s
[12:39:23.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:38:0)
[12:39:23.888] Timer tick, asking Buildbucket for the build status
[12:39:23.967] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:23.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:39:0) after 1m0s
[12:40:24.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:39:0)
[12:40:24.069] Timer tick, asking Buildbucket for the build status
[12:40:24.139] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:24.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:40:0) after 1m0s
[12:41:24.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:40:0)
[12:41:24.169] Timer tick, asking Buildbucket for the build status
[12:41:24.295] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:24.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:41:0) after 1m0s
[12:42:24.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:41:0)
[12:42:24.342] Timer tick, asking Buildbucket for the build status
[12:42:24.415] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:24.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:42:0) after 1m0s
[12:43:24.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:42:0)
[12:43:24.447] Timer tick, asking Buildbucket for the build status
[12:43:24.831] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:24.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:43:0) after 1m0s
[12:44:24.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:43:0)
[12:44:24.967] Timer tick, asking Buildbucket for the build status
[12:44:25.426] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:25.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:44:0) after 1m0s
[12:45:25.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:44:0)
[12:45:25.551] Timer tick, asking Buildbucket for the build status
[12:45:25.628] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:25.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:45:0) after 1m0s
[12:46:25.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:45:0)
[12:46:25.738] Timer tick, asking Buildbucket for the build status
[12:46:25.894] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:25.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:46:0) after 1m0s
[12:47:26.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:46:0)
[12:47:26.136] Timer tick, asking Buildbucket for the build status
[12:47:26.299] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:26.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:47:0) after 1m0s
[12:48:26.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:47:0)
[12:48:26.463] Timer tick, asking Buildbucket for the build status
[12:48:26.574] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:26.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:48:0) after 1m0s
[12:49:26.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:48:0)
[12:49:26.668] Timer tick, asking Buildbucket for the build status
[12:49:26.738] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:26.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:49:0) after 1m0s
[12:50:26.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:49:0)
[12:50:26.922] Timer tick, asking Buildbucket for the build status
[12:50:26.998] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:26.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:50:0) after 1m0s
[12:51:27.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:50:0)
[12:51:27.372] Timer tick, asking Buildbucket for the build status
[12:51:27.442] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:27.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:51:0) after 1m0s
[12:52:27.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:51:0)
[12:52:27.503] Timer tick, asking Buildbucket for the build status
[12:52:29.277] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:29.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:52:0) after 1m0s
[12:53:29.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:52:0)
[12:53:29.367] Timer tick, asking Buildbucket for the build status
[12:53:29.484] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:29.484] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:53:0) after 1m0s
[12:54:29.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:53:0)
[12:54:29.560] Timer tick, asking Buildbucket for the build status
[12:54:29.626] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:29.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:54:0) after 1m0s
[12:55:29.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:54:0)
[12:55:29.706] Timer tick, asking Buildbucket for the build status
[12:55:29.782] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:29.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:55:0) after 1m0s
[12:56:29.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:55:0)
[12:56:29.842] Timer tick, asking Buildbucket for the build status
[12:56:29.967] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:29.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:56:0) after 1m0s
[12:57:29.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:56:0)
[12:57:29.988] Timer tick, asking Buildbucket for the build status
[12:57:31.328] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:31.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:57:0) after 1m0s
[12:58:31.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:57:0)
[12:58:31.371] Timer tick, asking Buildbucket for the build status
[12:58:31.514] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:31.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:58:0) after 1m0s
[12:59:31.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:58:0)
[12:59:31.580] Timer tick, asking Buildbucket for the build status
[12:59:31.900] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:31.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:59:0) after 1m0s
[13:00:31.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:59:0)
[13:00:31.934] Timer tick, asking Buildbucket for the build status
[13:00:32.056] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:32.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:60:0) after 1m0s
[13:01:32.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:60:0)
[13:01:32.128] Timer tick, asking Buildbucket for the build status
[13:01:32.234] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:32.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:61:0) after 1m0s
[13:02:32.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:61:0)
[13:02:32.365] Timer tick, asking Buildbucket for the build status
[13:02:32.429] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:32.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:62:0) after 1m0s
[13:03:32.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:62:0)
[13:03:32.482] Timer tick, asking Buildbucket for the build status
[13:03:32.613] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:32.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:63:0) after 1m0s
[13:04:32.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:63:0)
[13:04:32.641] Timer tick, asking Buildbucket for the build status
[13:04:32.695] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:32.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:64:0) after 1m0s
[13:05:32.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:64:0)
[13:05:32.739] Timer tick, asking Buildbucket for the build status
[13:05:32.854] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:32.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:65:0) after 1m0s
[13:06:32.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:65:0)
[13:06:32.878] Timer tick, asking Buildbucket for the build status
[13:06:32.938] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:32.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:66:0) after 1m0s
[13:07:32.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:66:0)
[13:07:32.938] Timer tick, asking Buildbucket for the build status
[13:07:33.430] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:33.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:67:0) after 1m0s
[13:08:33.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:67:0)
[13:08:33.429] Timer tick, asking Buildbucket for the build status
[13:08:34.753] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:34.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:68:0) after 1m0s
[13:09:34.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:68:0)
[13:09:34.837] Timer tick, asking Buildbucket for the build status
[13:09:34.920] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:34.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:69:0) after 1m0s
[13:10:34.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:69:0)
[13:10:34.938] Timer tick, asking Buildbucket for the build status
[13:10:35.004] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:35.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:70:0) after 1m0s
[13:11:35.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:70:0)
[13:11:35.099] Timer tick, asking Buildbucket for the build status
[13:11:35.163] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:35.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:71:0) after 1m0s
[13:12:35.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:71:0)
[13:12:35.278] Timer tick, asking Buildbucket for the build status
[13:12:35.772] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:35.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:72:0) after 1m0s
[13:13:35.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:72:0)
[13:13:35.829] Timer tick, asking Buildbucket for the build status
[13:13:36.216] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:36.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:73:0) after 1m0s
[13:14:36.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:73:0)
[13:14:36.282] Timer tick, asking Buildbucket for the build status
[13:14:36.342] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:36.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:74:0) after 1m0s
[13:15:36.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:74:0)
[13:15:36.435] Timer tick, asking Buildbucket for the build status
[13:15:36.483] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:36.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:75:0) after 1m0s
[13:16:36.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:75:0)
[13:16:36.677] Timer tick, asking Buildbucket for the build status
[13:16:36.749] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:36.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:76:0) after 1m0s
[13:17:36.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:76:0)
[13:17:36.881] Timer tick, asking Buildbucket for the build status
[13:17:37.168] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:37.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:77:0) after 1m0s
[13:18:37.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:77:0)
[13:18:37.228] Timer tick, asking Buildbucket for the build status
[13:18:37.408] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:37.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:78:0) after 1m0s
[13:19:37.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:78:0)
[13:19:37.466] Timer tick, asking Buildbucket for the build status
[13:19:38.285] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:38.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:79:0) after 1m0s
[13:20:38.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:79:0)
[13:20:38.330] Timer tick, asking Buildbucket for the build status
[13:20:38.393] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:38.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:80:0) after 1m0s
[13:21:38.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:80:0)
[13:21:38.420] Timer tick, asking Buildbucket for the build status
[13:21:38.574] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:38.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:81:0) after 1m0s
[13:22:38.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:81:0)
[13:22:38.638] Timer tick, asking Buildbucket for the build status
[13:22:38.710] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:38.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:82:0) after 1m0s
[13:23:38.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:82:0)
[13:23:38.789] Timer tick, asking Buildbucket for the build status
[13:23:38.909] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:38.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:83:0) after 1m0s
[13:24:38.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:83:0)
[13:24:38.909] Timer tick, asking Buildbucket for the build status
[13:24:38.976] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:38.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:84:0) after 1m0s
[13:25:39.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:84:0)
[13:25:39.026] Timer tick, asking Buildbucket for the build status
[13:25:39.443] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:39.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:85:0) after 1m0s
[13:26:39.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:85:0)
[13:26:39.455] Timer tick, asking Buildbucket for the build status
[13:26:39.516] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:39.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:86:0) after 1m0s
[13:27:39.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:86:0)
[13:27:39.596] Timer tick, asking Buildbucket for the build status
[13:27:39.675] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:39.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:87:0) after 1m0s
[13:28:39.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:87:0)
[13:28:39.707] Timer tick, asking Buildbucket for the build status
[13:28:39.815] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:39.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:88:0) after 1m0s
[13:29:40.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:88:0)
[13:29:40.767] Timer tick, asking Buildbucket for the build status
[13:29:40.831] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:40.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:89:0) after 1m0s
[13:30:40.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:89:0)
[13:30:40.995] Timer tick, asking Buildbucket for the build status
[13:30:43.113] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:43.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:90:0) after 1m0s
[13:31:43.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:90:0)
[13:31:43.135] Timer tick, asking Buildbucket for the build status
[13:31:43.195] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:43.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:91:0) after 1m0s
[13:32:43.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:91:0)
[13:32:43.259] Timer tick, asking Buildbucket for the build status
[13:32:43.328] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:43.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:92:0) after 1m0s
[13:33:43.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:92:0)
[13:33:43.379] Timer tick, asking Buildbucket for the build status
[13:33:43.482] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:43.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:93:0) after 1m0s
[13:34:43.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:93:0)
[13:34:43.554] Timer tick, asking Buildbucket for the build status
[13:34:43.622] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:43.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:94:0) after 1m0s
[13:35:43.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:94:0)
[13:35:43.746] Timer tick, asking Buildbucket for the build status
[13:35:43.987] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:43.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:95:0) after 1m0s
[13:36:44.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:95:0)
[13:36:44.061] Timer tick, asking Buildbucket for the build status
[13:36:44.140] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:44.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:96:0) after 1m0s
[13:37:44.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:96:0)
[13:37:44.235] Timer tick, asking Buildbucket for the build status
[13:37:44.302] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:44.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:97:0) after 1m0s
[13:38:44.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:97:0)
[13:38:44.324] Timer tick, asking Buildbucket for the build status
[13:38:44.544] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:44.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:98:0) after 1m0s
[13:39:44.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:98:0)
[13:39:44.596] Timer tick, asking Buildbucket for the build status
[13:39:44.663] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:44.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:99:0) after 1m0s
[13:40:44.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:99:0)
[13:40:44.693] Timer tick, asking Buildbucket for the build status
[13:40:45.287] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:45.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:100:0) after 1m0s
[13:41:45.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:100:0)
[13:41:45.334] Timer tick, asking Buildbucket for the build status
[13:41:45.404] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:45.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:101:0) after 1m0s
[13:42:45.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:101:0)
[13:42:45.554] Timer tick, asking Buildbucket for the build status
[13:42:45.619] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:45.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:102:0) after 1m0s
[13:43:45.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:102:0)
[13:43:45.676] Timer tick, asking Buildbucket for the build status
[13:43:45.785] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:45.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:103:0) after 1m0s
[13:44:45.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:103:0)
[13:44:45.887] Timer tick, asking Buildbucket for the build status
[13:44:45.953] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:45.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:104:0) after 1m0s
[13:45:45.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:104:0)
[13:45:45.994] Timer tick, asking Buildbucket for the build status
[13:45:46.118] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:46.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:105:0) after 1m0s
[13:46:46.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:105:0)
[13:46:46.192] Timer tick, asking Buildbucket for the build status
[13:46:46.285] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:46.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:106:0) after 1m0s
[13:47:46.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:106:0)
[13:47:46.379] Timer tick, asking Buildbucket for the build status
[13:47:46.539] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:46.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:107:0) after 1m0s
[13:48:47.010] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:107:0)
[13:48:47.010] Timer tick, asking Buildbucket for the build status
[13:48:47.130] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:47.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:108:0) after 1m0s
[13:49:47.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:108:0)
[13:49:47.435] Timer tick, asking Buildbucket for the build status
[13:49:47.511] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:47.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:109:0) after 1m0s
[13:50:47.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:109:0)
[13:50:47.598] Timer tick, asking Buildbucket for the build status
[13:50:47.663] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:47.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:110:0) after 1m0s
[13:51:47.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:110:0)
[13:51:47.905] Timer tick, asking Buildbucket for the build status
[13:51:48.035] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:48.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:111:0) after 1m0s
[13:52:48.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:111:0)
[13:52:48.113] Timer tick, asking Buildbucket for the build status
[13:52:48.184] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:48.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:112:0) after 1m0s
[13:53:48.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:112:0)
[13:53:48.178] Timer tick, asking Buildbucket for the build status
[13:53:49.190] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:49.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:113:0) after 1m0s
[13:54:49.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:113:0)
[13:54:49.379] Timer tick, asking Buildbucket for the build status
[13:54:49.722] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:49.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:114:0) after 1m0s
[13:55:49.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:114:0)
[13:55:49.825] Timer tick, asking Buildbucket for the build status
[13:55:49.884] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:49.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:115:0) after 1m0s
[13:56:49.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:115:0)
[13:56:49.909] Timer tick, asking Buildbucket for the build status
[13:56:50.021] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:50.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:116:0) after 1m0s
[13:57:50.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:116:0)
[13:57:50.052] Timer tick, asking Buildbucket for the build status
[13:57:50.211] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:50.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:117:0) after 1m0s
[13:58:50.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:117:0)
[13:58:50.239] Timer tick, asking Buildbucket for the build status
[13:58:50.331] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:50.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:118:0) after 1m0s
[13:59:50.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:118:0)
[13:59:50.382] Timer tick, asking Buildbucket for the build status
[13:59:50.524] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:50.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:119:0) after 1m0s
[14:00:50.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:119:0)
[14:00:50.551] Timer tick, asking Buildbucket for the build status
[14:00:50.616] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:50.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:120:0) after 1m0s
[14:01:50.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:120:0)
[14:01:50.659] Timer tick, asking Buildbucket for the build status
[14:01:50.784] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:50.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:121:0) after 1m0s
[14:02:50.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:121:0)
[14:02:50.839] Timer tick, asking Buildbucket for the build status
[14:02:50.905] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:50.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:122:0) after 1m0s
[14:03:50.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:122:0)
[14:03:50.934] Timer tick, asking Buildbucket for the build status
[14:03:51.075] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:51.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:123:0) after 1m0s
[14:04:51.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:123:0)
[14:04:51.065] Timer tick, asking Buildbucket for the build status
[14:04:51.139] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:51.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:124:0) after 1m0s
[14:05:51.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:124:0)
[14:05:51.276] Timer tick, asking Buildbucket for the build status
[14:05:51.354] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:51.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:125:0) after 1m0s
[14:06:51.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:125:0)
[14:06:51.405] Timer tick, asking Buildbucket for the build status
[14:06:51.535] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:51.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:126:0) after 1m0s
[14:07:51.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:126:0)
[14:07:51.685] Timer tick, asking Buildbucket for the build status
[14:07:51.755] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:51.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:127:0) after 1m0s
[14:08:52.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:127:0)
[14:08:52.014] Timer tick, asking Buildbucket for the build status
[14:08:52.285] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:52.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:128:0) after 1m0s
[14:09:52.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:128:0)
[14:09:52.333] Timer tick, asking Buildbucket for the build status
[14:09:52.386] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:52.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:129:0) after 1m0s
[14:10:52.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:129:0)
[14:10:52.456] Timer tick, asking Buildbucket for the build status
[14:10:52.581] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:52.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:130:0) after 1m0s
[14:11:52.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:130:0)
[14:11:52.567] Timer tick, asking Buildbucket for the build status
[14:11:52.795] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:52.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:131:0) after 1m0s
[14:12:52.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:131:0)
[14:12:52.831] Timer tick, asking Buildbucket for the build status
[14:12:52.901] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:52.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:132:0) after 1m0s
[14:13:52.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:132:0)
[14:13:52.953] Timer tick, asking Buildbucket for the build status
[14:13:53.092] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:53.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:133:0) after 1m0s
[14:14:53.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:133:0)
[14:14:53.145] Timer tick, asking Buildbucket for the build status
[14:14:53.218] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:53.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:134:0) after 1m0s
[14:15:53.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:134:0)
[14:15:53.249] Timer tick, asking Buildbucket for the build status
[14:15:53.507] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:53.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:135:0) after 1m0s
[14:16:53.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:135:0)
[14:16:53.582] Timer tick, asking Buildbucket for the build status
[14:16:53.705] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:53.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:136:0) after 1m0s
[14:17:53.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:136:0)
[14:17:53.783] Timer tick, asking Buildbucket for the build status
[14:17:53.894] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:53.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:137:0) after 1m0s
[14:18:53.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:137:0)
[14:18:53.887] Timer tick, asking Buildbucket for the build status
[14:18:53.984] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:53.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:138:0) after 1m0s
[14:19:53.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:138:0)
[14:19:53.999] Timer tick, asking Buildbucket for the build status
[14:19:54.069] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:54.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:139:0) after 1m0s
[14:20:54.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:139:0)
[14:20:54.108] Timer tick, asking Buildbucket for the build status
[14:20:54.178] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:54.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:140:0) after 1m0s
[14:21:54.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:140:0)
[14:21:54.231] Timer tick, asking Buildbucket for the build status
[14:21:54.296] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:54.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:141:0) after 1m0s
[14:22:54.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:141:0)
[14:22:54.358] Timer tick, asking Buildbucket for the build status
[14:22:54.424] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:54.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:142:0) after 1m0s
[14:23:54.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:142:0)
[14:23:54.792] Timer tick, asking Buildbucket for the build status
[14:23:54.886] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:54.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:143:0) after 1m0s
[14:24:55.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:143:0)
[14:24:55.079] Timer tick, asking Buildbucket for the build status
[14:24:55.155] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:55.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:144:0) after 1m0s
[14:25:55.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:144:0)
[14:25:55.252] Timer tick, asking Buildbucket for the build status
[14:25:55.319] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:55.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:145:0) after 1m0s
[14:26:55.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:145:0)
[14:26:55.358] Timer tick, asking Buildbucket for the build status
[14:26:55.515] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:55.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:146:0) after 1m0s
[14:27:55.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:146:0)
[14:27:55.544] Timer tick, asking Buildbucket for the build status
[14:27:55.618] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:55.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:147:0) after 1m0s
[14:28:55.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:147:0)
[14:28:55.703] Timer tick, asking Buildbucket for the build status
[14:28:56.202] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:56.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:148:0) after 1m0s
[14:29:56.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:148:0)
[14:29:56.241] Timer tick, asking Buildbucket for the build status
[14:29:56.316] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:56.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:149:0) after 1m0s
[14:30:56.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:149:0)
[14:30:56.369] Timer tick, asking Buildbucket for the build status
[14:30:56.487] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:56.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:150:0) after 1m0s
[14:31:56.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:150:0)
[14:31:56.568] Timer tick, asking Buildbucket for the build status
[14:31:56.662] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:56.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:151:0) after 1m0s
[14:32:56.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:151:0)
[14:32:56.756] Timer tick, asking Buildbucket for the build status
[14:32:56.832] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:56.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:152:0) after 1m0s
[14:33:56.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:152:0)
[14:33:56.892] Timer tick, asking Buildbucket for the build status
[14:33:57.022] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:57.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:153:0) after 1m0s
[14:34:57.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:153:0)
[14:34:57.225] Timer tick, asking Buildbucket for the build status
[14:34:57.286] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:57.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:154:0) after 1m0s
[14:35:57.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:154:0)
[14:35:57.612] Timer tick, asking Buildbucket for the build status
[14:35:57.818] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:57.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:155:0) after 1m0s
[14:36:58.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:155:0)
[14:36:58.021] Timer tick, asking Buildbucket for the build status
[14:36:58.346] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:58.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:156:0) after 1m0s
[14:37:58.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:156:0)
[14:37:58.459] Timer tick, asking Buildbucket for the build status
[14:37:58.669] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:58.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:157:0) after 1m0s
[14:38:58.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:157:0)
[14:38:58.899] Timer tick, asking Buildbucket for the build status
[14:38:59.045] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:59.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:158:0) after 1m0s
[14:39:59.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:158:0)
[14:39:59.112] Timer tick, asking Buildbucket for the build status
[14:39:59.212] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:59.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:159:0) after 1m0s
[14:40:59.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:159:0)
[14:40:59.466] Timer tick, asking Buildbucket for the build status
[14:40:59.602] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:59.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:160:0) after 1m0s
[14:41:59.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:160:0)
[14:41:59.765] Timer tick, asking Buildbucket for the build status
[14:41:59.853] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:59.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:161:0) after 1m0s
[14:43:00.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:161:0)
[14:43:00.094] Timer tick, asking Buildbucket for the build status
[14:43:00.220] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:00.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:162:0) after 1m0s
[14:44:00.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:162:0)
[14:44:00.904] Timer tick, asking Buildbucket for the build status
[14:44:01.035] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:01.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:163:0) after 1m0s
[14:45:02.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:163:0)
[14:45:02.831] Timer tick, asking Buildbucket for the build status
[14:45:02.919] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:02.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:164:0) after 1m0s
[14:46:03.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:164:0)
[14:46:03.524] Timer tick, asking Buildbucket for the build status
[14:46:03.732] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:03.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:165:0) after 1m0s
[14:47:04.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:165:0)
[14:47:04.478] Timer tick, asking Buildbucket for the build status
[14:47:04.573] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:04.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:166:0) after 1m0s
[14:48:05.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:166:0)
[14:48:05.353] Timer tick, asking Buildbucket for the build status
[14:48:05.446] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:05.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:167:0) after 1m0s
[14:49:05.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:167:0)
[14:49:05.482] Timer tick, asking Buildbucket for the build status
[14:49:05.633] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:05.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:168:0) after 1m0s
[14:50:05.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:168:0)
[14:50:05.693] Timer tick, asking Buildbucket for the build status
[14:50:05.758] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:05.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:169:0) after 1m0s
[14:51:05.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:169:0)
[14:51:05.830] Timer tick, asking Buildbucket for the build status
[14:51:05.949] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:05.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:170:0) after 1m0s
[14:52:06.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:170:0)
[14:52:06.082] Timer tick, asking Buildbucket for the build status
[14:52:06.160] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:06.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:171:0) after 1m0s
[14:53:06.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:171:0)
[14:53:06.194] Timer tick, asking Buildbucket for the build status
[14:53:08.030] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:08.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:172:0) after 1m0s
[14:54:08.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:172:0)
[14:54:08.104] Timer tick, asking Buildbucket for the build status
[14:54:08.245] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:08.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:173:0) after 1m0s
[14:55:08.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:173:0)
[14:55:08.312] Timer tick, asking Buildbucket for the build status
[14:55:08.378] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:08.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:174:0) after 1m0s
[14:56:08.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:174:0)
[14:56:08.406] Timer tick, asking Buildbucket for the build status
[14:56:08.613] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:08.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:175:0) after 1m0s
[14:57:08.605] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:175:0)
[14:57:08.605] Timer tick, asking Buildbucket for the build status
[14:57:08.660] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:08.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:176:0) after 1m0s
[14:58:08.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:176:0)
[14:58:08.699] Timer tick, asking Buildbucket for the build status
[14:58:08.765] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:08.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:177:0) after 1m0s
[14:59:08.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:177:0)
[14:59:08.797] Timer tick, asking Buildbucket for the build status
[14:59:09.103] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:09.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:178:0) after 1m0s
[15:00:09.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:178:0)
[15:00:09.276] Timer tick, asking Buildbucket for the build status
[15:00:09.344] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:09.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:179:0) after 1m0s
[15:01:10.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:179:0)
[15:01:10.586] Timer tick, asking Buildbucket for the build status
[15:01:10.671] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:10.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:180:0) after 1m0s
[15:02:11.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:180:0)
[15:02:11.505] Timer tick, asking Buildbucket for the build status
[15:02:11.648] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:11.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:181:0) after 1m0s
[15:03:11.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:181:0)
[15:03:11.744] Timer tick, asking Buildbucket for the build status
[15:03:12.076] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:12.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:182:0) after 1m0s
[15:04:12.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:182:0)
[15:04:12.134] Timer tick, asking Buildbucket for the build status
[15:04:12.239] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:12.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:183:0) after 1m0s
[15:05:12.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:183:0)
[15:05:12.266] Timer tick, asking Buildbucket for the build status
[15:05:14.663] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:14.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:184:0) after 1m0s
[15:06:14.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:184:0)
[15:06:14.747] Timer tick, asking Buildbucket for the build status
[15:06:14.819] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:14.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:185:0) after 1m0s
[15:07:14.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:185:0)
[15:07:14.875] Timer tick, asking Buildbucket for the build status
[15:07:14.944] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:14.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:186:0) after 1m0s
[15:08:15.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:186:0)
[15:08:15.033] Timer tick, asking Buildbucket for the build status
[15:08:15.129] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:15.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:187:0) after 1m0s
[15:09:15.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:187:0)
[15:09:15.345] Timer tick, asking Buildbucket for the build status
[15:09:15.505] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:15.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:188:0) after 1m0s
[15:10:15.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:188:0)
[15:10:15.744] Timer tick, asking Buildbucket for the build status
[15:10:15.853] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:15.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:189:0) after 1m0s
[15:11:15.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:189:0)
[15:11:15.858] Timer tick, asking Buildbucket for the build status
[15:11:16.083] Build 8861391663121475312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:16.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026821725121276352:190:0) after 1m0s
[15:11:43.494] Received PubSub notification, asking Buildbucket for the build status
[15:11:43.626] Build 8861391663121475312: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:11:43.626] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1607526702409871",
    "created_by": "project:infra",
    "created_ts": "1607515385472438",
    "id": "8861391663121475312",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4350.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"723b143f-7d69-4c74-ad9b-9d0f73fbd168\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4350.2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-uj4s\"], \"image\": [\"chrome-xenial-20120600-c5cfbe11ce8\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1607515433832323",
    "status": "COMPLETED",
    "status_changed_ts": "1607526702988089",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026821725121276352",
      "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/8861391663121475312/+/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:506033c618d88410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1607526703093537",
    "url": "https://ci.chromium.org/b/8861391663121475312",
    "utcnow_ts": "1607526703619055"
  }
}
[15:11:43.626] Invocation finished in 3h8m39.445415044s with status SUCCEEDED