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

• parent_buildername:publish_tarball
• user_agent:recipe

[08:10:02.155] New invocation is queued and will start shortly
[08:10:02.155] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:10:03.434] Starting the invocation (attempt 1)
[08:10:03.475] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:10:03.475] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:10:03.475] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9037073843565805936",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4237.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"00803614-2279-4067-8de8-0c0ec3f4e345\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4237.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:9037073843565805936",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:10:04.690] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1597738203669095",
    "id": "8871643781308235824",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4237.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"00803614-2279-4067-8de8-0c0ec3f4e345\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4237.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1597738204362315",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9037073843565805936",
      "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/8871643781308235824/+/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": "1597738204362446",
    "url": "https://ci.chromium.org/b/8871643781308235824",
    "utcnow_ts": "1597738204675842"
  }
}
[08:10:04.690] Task URL: https://ci.chromium.org/b/8871643781308235824
[08:10:04.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:2:0) after 1m0s
[08:11:04.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:2:0)
[08:11:04.743] Timer tick, asking Buildbucket for the build status
[08:11:04.927] Build 8871643781308235824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:04.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:3:0) after 1m0s
[08:12:05.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:3:0)
[08:12:05.014] Timer tick, asking Buildbucket for the build status
[08:12:05.120] Build 8871643781308235824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:05.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:4:0) after 1m0s
[08:13:05.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:4:0)
[08:13:05.369] Timer tick, asking Buildbucket for the build status
[08:13:05.483] Build 8871643781308235824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:05.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:5:0) after 1m0s
[08:14:04.234] Received PubSub notification, asking Buildbucket for the build status
[08:14:04.344] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:05.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:5:0)
[08:14:05.654] Timer tick, asking Buildbucket for the build status
[08:14:05.739] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:05.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:7:0) after 1m0s
[08:15:05.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:7:0)
[08:15:05.911] Timer tick, asking Buildbucket for the build status
[08:15:06.023] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:06.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:8:0) after 1m0s
[08:16:06.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:8:0)
[08:16:06.105] Timer tick, asking Buildbucket for the build status
[08:16:06.334] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:06.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:9:0) after 1m0s
[08:17:06.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:9:0)
[08:17:06.384] Timer tick, asking Buildbucket for the build status
[08:17:06.495] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:06.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:10:0) after 1m0s
[08:18:06.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:10:0)
[08:18:06.606] Timer tick, asking Buildbucket for the build status
[08:18:07.253] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:07.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:11:0) after 1m0s
[08:19:07.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:11:0)
[08:19:07.238] Timer tick, asking Buildbucket for the build status
[08:19:07.347] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:07.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:12:0) after 1m0s
[08:20:07.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:12:0)
[08:20:07.334] Timer tick, asking Buildbucket for the build status
[08:20:07.426] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:07.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:13:0) after 1m0s
[08:21:07.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:13:0)
[08:21:07.506] Timer tick, asking Buildbucket for the build status
[08:21:08.446] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:08.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:14:0) after 1m0s
[08:22:08.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:14:0)
[08:22:08.555] Timer tick, asking Buildbucket for the build status
[08:22:08.712] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:08.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:15:0) after 1m0s
[08:23:08.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:15:0)
[08:23:08.767] Timer tick, asking Buildbucket for the build status
[08:23:08.831] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:08.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:16:0) after 1m0s
[08:24:08.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:16:0)
[08:24:08.913] Timer tick, asking Buildbucket for the build status
[08:24:09.019] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:09.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:17:0) after 1m0s
[08:25:09.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:17:0)
[08:25:09.045] Timer tick, asking Buildbucket for the build status
[08:25:09.225] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:09.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:18:0) after 1m0s
[08:26:09.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:18:0)
[08:26:09.254] Timer tick, asking Buildbucket for the build status
[08:26:09.317] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:09.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:19:0) after 1m0s
[08:27:09.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:19:0)
[08:27:09.377] Timer tick, asking Buildbucket for the build status
[08:27:09.967] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:09.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:20:0) after 1m0s
[08:28:09.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:20:0)
[08:28:09.953] Timer tick, asking Buildbucket for the build status
[08:28:10.096] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:10.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:21:0) after 1m0s
[08:29:10.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:21:0)
[08:29:10.145] Timer tick, asking Buildbucket for the build status
[08:29:10.423] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:10.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:22:0) after 1m0s
[08:30:10.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:22:0)
[08:30:10.628] Timer tick, asking Buildbucket for the build status
[08:30:10.995] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:10.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:23:0) after 1m0s
[08:31:11.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:23:0)
[08:31:11.086] Timer tick, asking Buildbucket for the build status
[08:31:11.171] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:11.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:24:0) after 1m0s
[08:32:11.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:24:0)
[08:32:11.179] Timer tick, asking Buildbucket for the build status
[08:32:11.247] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:11.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:25:0) after 1m0s
[08:33:11.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:25:0)
[08:33:11.333] Timer tick, asking Buildbucket for the build status
[08:33:11.613] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:11.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:26:0) after 1m0s
[08:34:11.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:26:0)
[08:34:11.738] Timer tick, asking Buildbucket for the build status
[08:34:12.091] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:12.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:27:0) after 1m0s
[08:35:12.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:27:0)
[08:35:12.152] Timer tick, asking Buildbucket for the build status
[08:35:12.440] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:12.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:28:0) after 1m0s
[08:36:12.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:28:0)
[08:36:12.497] Timer tick, asking Buildbucket for the build status
[08:36:12.583] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:12.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:29:0) after 1m0s
[08:37:12.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:29:0)
[08:37:12.592] Timer tick, asking Buildbucket for the build status
[08:37:12.716] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:12.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:30:0) after 1m0s
[08:38:12.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:30:0)
[08:38:12.823] Timer tick, asking Buildbucket for the build status
[08:38:13.042] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:13.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:31:0) after 1m0s
[08:39:13.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:31:0)
[08:39:13.110] Timer tick, asking Buildbucket for the build status
[08:39:13.248] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:13.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:32:0) after 1m0s
[08:40:13.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:32:0)
[08:40:13.279] Timer tick, asking Buildbucket for the build status
[08:40:13.427] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:13.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:33:0) after 1m0s
[08:41:13.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:33:0)
[08:41:13.553] Timer tick, asking Buildbucket for the build status
[08:41:13.651] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:13.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:34:0) after 1m0s
[08:42:13.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:34:0)
[08:42:13.721] Timer tick, asking Buildbucket for the build status
[08:42:13.938] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:13.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:35:0) after 1m0s
[08:43:13.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:35:0)
[08:43:13.949] Timer tick, asking Buildbucket for the build status
[08:43:14.060] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:14.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:36:0) after 1m0s
[08:44:14.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:36:0)
[08:44:14.151] Timer tick, asking Buildbucket for the build status
[08:44:14.255] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:14.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:37:0) after 1m0s
[08:45:14.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:37:0)
[08:45:14.283] Timer tick, asking Buildbucket for the build status
[08:45:14.548] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:14.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:38:0) after 1m0s
[08:46:14.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:38:0)
[08:46:14.557] Timer tick, asking Buildbucket for the build status
[08:46:14.678] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:14.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:39:0) after 1m0s
[08:47:14.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:39:0)
[08:47:14.774] Timer tick, asking Buildbucket for the build status
[08:47:14.855] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:14.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:40:0) after 1m0s
[08:48:15.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:40:0)
[08:48:15.027] Timer tick, asking Buildbucket for the build status
[08:48:15.231] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:15.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:41:0) after 1m0s
[08:49:15.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:41:0)
[08:49:15.253] Timer tick, asking Buildbucket for the build status
[08:49:16.098] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:16.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:42:0) after 1m0s
[08:50:16.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:42:0)
[08:50:16.214] Timer tick, asking Buildbucket for the build status
[08:50:16.378] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:16.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:43:0) after 1m0s
[08:51:16.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:43:0)
[08:51:16.446] Timer tick, asking Buildbucket for the build status
[08:51:16.515] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:16.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:44:0) after 1m0s
[08:52:16.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:44:0)
[08:52:16.730] Timer tick, asking Buildbucket for the build status
[08:52:16.909] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:16.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:45:0) after 1m0s
[08:53:17.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:45:0)
[08:53:17.037] Timer tick, asking Buildbucket for the build status
[08:53:17.104] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:17.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:46:0) after 1m0s
[08:54:17.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:46:0)
[08:54:17.240] Timer tick, asking Buildbucket for the build status
[08:54:17.411] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:17.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:47:0) after 1m0s
[08:55:17.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:47:0)
[08:55:17.446] Timer tick, asking Buildbucket for the build status
[08:55:17.806] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:17.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:48:0) after 1m0s
[08:56:17.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:48:0)
[08:56:17.785] Timer tick, asking Buildbucket for the build status
[08:56:17.843] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:17.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:49:0) after 1m0s
[08:57:17.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:49:0)
[08:57:17.916] Timer tick, asking Buildbucket for the build status
[08:57:17.976] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:17.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:50:0) after 1m0s
[08:58:18.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:50:0)
[08:58:18.011] Timer tick, asking Buildbucket for the build status
[08:58:18.093] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:18.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:51:0) after 1m0s
[08:59:18.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:51:0)
[08:59:18.172] Timer tick, asking Buildbucket for the build status
[08:59:18.252] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:18.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:52:0) after 1m0s
[09:00:18.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:52:0)
[09:00:18.310] Timer tick, asking Buildbucket for the build status
[09:00:18.991] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:18.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:53:0) after 1m0s
[09:01:18.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:53:0)
[09:01:18.999] Timer tick, asking Buildbucket for the build status
[09:01:19.517] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:19.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:54:0) after 1m0s
[09:02:19.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:54:0)
[09:02:19.720] Timer tick, asking Buildbucket for the build status
[09:02:19.816] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:19.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:55:0) after 1m0s
[09:03:19.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:55:0)
[09:03:19.876] Timer tick, asking Buildbucket for the build status
[09:03:19.988] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:19.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:56:0) after 1m0s
[09:04:20.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:56:0)
[09:04:20.409] Timer tick, asking Buildbucket for the build status
[09:04:20.739] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:20.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:57:0) after 1m0s
[09:05:22.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:57:0)
[09:05:22.147] Timer tick, asking Buildbucket for the build status
[09:05:22.589] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:22.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:58:0) after 1m0s
[09:06:22.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:58:0)
[09:06:22.780] Timer tick, asking Buildbucket for the build status
[09:06:22.875] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:22.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:59:0) after 1m0s
[09:07:23.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:59:0)
[09:07:23.008] Timer tick, asking Buildbucket for the build status
[09:07:23.363] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:23.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:60:0) after 1m0s
[09:08:23.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:60:0)
[09:08:23.438] Timer tick, asking Buildbucket for the build status
[09:08:23.597] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:23.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:61:0) after 1m0s
[09:09:23.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:61:0)
[09:09:23.638] Timer tick, asking Buildbucket for the build status
[09:09:23.715] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:23.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:62:0) after 1m0s
[09:10:23.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:62:0)
[09:10:23.801] Timer tick, asking Buildbucket for the build status
[09:10:24.123] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:24.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:63:0) after 1m0s
[09:11:24.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:63:0)
[09:11:24.225] Timer tick, asking Buildbucket for the build status
[09:11:24.295] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:24.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:64:0) after 1m0s
[09:12:24.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:64:0)
[09:12:24.316] Timer tick, asking Buildbucket for the build status
[09:12:24.413] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:24.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:65:0) after 1m0s
[09:13:24.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:65:0)
[09:13:24.861] Timer tick, asking Buildbucket for the build status
[09:13:24.953] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:24.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:66:0) after 1m0s
[09:14:25.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:66:0)
[09:14:25.109] Timer tick, asking Buildbucket for the build status
[09:14:25.205] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:25.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:67:0) after 1m0s
[09:15:26.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:67:0)
[09:15:26.105] Timer tick, asking Buildbucket for the build status
[09:15:26.261] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:26.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:68:0) after 1m0s
[09:16:26.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:68:0)
[09:16:26.514] Timer tick, asking Buildbucket for the build status
[09:16:26.752] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:26.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:69:0) after 1m0s
[09:17:26.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:69:0)
[09:17:26.788] Timer tick, asking Buildbucket for the build status
[09:17:26.870] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:26.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:70:0) after 1m0s
[09:18:27.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:70:0)
[09:18:27.014] Timer tick, asking Buildbucket for the build status
[09:18:28.288] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:28.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:71:0) after 1m0s
[09:19:28.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:71:0)
[09:19:28.401] Timer tick, asking Buildbucket for the build status
[09:19:28.478] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:28.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:72:0) after 1m0s
[09:20:28.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:72:0)
[09:20:28.571] Timer tick, asking Buildbucket for the build status
[09:20:29.938] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:29.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:73:0) after 1m0s
[09:21:30.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:73:0)
[09:21:30.092] Timer tick, asking Buildbucket for the build status
[09:21:30.164] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:30.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:74:0) after 1m0s
[09:22:30.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:74:0)
[09:22:30.195] Timer tick, asking Buildbucket for the build status
[09:22:30.265] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:30.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:75:0) after 1m0s
[09:23:30.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:75:0)
[09:23:30.296] Timer tick, asking Buildbucket for the build status
[09:23:30.394] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:30.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:76:0) after 1m0s
[09:24:30.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:76:0)
[09:24:30.424] Timer tick, asking Buildbucket for the build status
[09:24:30.723] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:30.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:77:0) after 1m0s
[09:25:30.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:77:0)
[09:25:30.811] Timer tick, asking Buildbucket for the build status
[09:25:31.050] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:31.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:78:0) after 1m0s
[09:26:31.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:78:0)
[09:26:31.135] Timer tick, asking Buildbucket for the build status
[09:26:31.251] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:31.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:79:0) after 1m0s
[09:27:31.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:79:0)
[09:27:31.358] Timer tick, asking Buildbucket for the build status
[09:27:31.539] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:31.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:80:0) after 1m0s
[09:28:31.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:80:0)
[09:28:31.638] Timer tick, asking Buildbucket for the build status
[09:28:31.704] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:31.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:81:0) after 1m0s
[09:29:31.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:81:0)
[09:29:31.788] Timer tick, asking Buildbucket for the build status
[09:29:31.848] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:31.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:82:0) after 1m0s
[09:30:32.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:82:0)
[09:30:32.020] Timer tick, asking Buildbucket for the build status
[09:30:32.211] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:32.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:83:0) after 1m0s
[09:31:32.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:83:0)
[09:31:32.391] Timer tick, asking Buildbucket for the build status
[09:31:32.522] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:32.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:84:0) after 1m0s
[09:32:32.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:84:0)
[09:32:32.679] Timer tick, asking Buildbucket for the build status
[09:32:32.788] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:32.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:85:0) after 1m0s
[09:33:33.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:85:0)
[09:33:33.065] Timer tick, asking Buildbucket for the build status
[09:33:33.286] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:33.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:86:0) after 1m0s
[09:34:33.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:86:0)
[09:34:33.401] Timer tick, asking Buildbucket for the build status
[09:34:33.606] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:33.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:87:0) after 1m0s
[09:35:33.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:87:0)
[09:35:33.756] Timer tick, asking Buildbucket for the build status
[09:35:33.994] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:33.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:88:0) after 1m0s
[09:36:34.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:88:0)
[09:36:34.128] Timer tick, asking Buildbucket for the build status
[09:36:34.202] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:34.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:89:0) after 1m0s
[09:37:34.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:89:0)
[09:37:34.479] Timer tick, asking Buildbucket for the build status
[09:37:34.582] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:34.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:90:0) after 1m0s
[09:38:34.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:90:0)
[09:38:34.702] Timer tick, asking Buildbucket for the build status
[09:38:34.815] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:34.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:91:0) after 1m0s
[09:39:34.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:91:0)
[09:39:34.846] Timer tick, asking Buildbucket for the build status
[09:39:34.921] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:34.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:92:0) after 1m0s
[09:40:34.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:92:0)
[09:40:34.949] Timer tick, asking Buildbucket for the build status
[09:40:35.328] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:35.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:93:0) after 1m0s
[09:41:35.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:93:0)
[09:41:35.397] Timer tick, asking Buildbucket for the build status
[09:41:35.480] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:35.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:94:0) after 1m0s
[09:42:36.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:94:0)
[09:42:36.592] Timer tick, asking Buildbucket for the build status
[09:42:36.831] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:36.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:95:0) after 1m0s
[09:43:36.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:95:0)
[09:43:36.889] Timer tick, asking Buildbucket for the build status
[09:43:37.216] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:37.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:96:0) after 1m0s
[09:44:37.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:96:0)
[09:44:37.240] Timer tick, asking Buildbucket for the build status
[09:44:37.310] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:37.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:97:0) after 1m0s
[09:45:37.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:97:0)
[09:45:37.444] Timer tick, asking Buildbucket for the build status
[09:45:37.615] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:37.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:98:0) after 1m0s
[09:46:37.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:98:0)
[09:46:37.657] Timer tick, asking Buildbucket for the build status
[09:46:37.990] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:37.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:99:0) after 1m0s
[09:47:38.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:99:0)
[09:47:38.052] Timer tick, asking Buildbucket for the build status
[09:47:38.140] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:38.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:100:0) after 1m0s
[09:48:38.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:100:0)
[09:48:38.212] Timer tick, asking Buildbucket for the build status
[09:48:38.543] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:38.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:101:0) after 1m0s
[09:49:38.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:101:0)
[09:49:38.569] Timer tick, asking Buildbucket for the build status
[09:49:38.629] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:38.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:102:0) after 1m0s
[09:50:38.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:102:0)
[09:50:38.653] Timer tick, asking Buildbucket for the build status
[09:50:39.180] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:39.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:103:0) after 1m0s
[09:51:39.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:103:0)
[09:51:39.243] Timer tick, asking Buildbucket for the build status
[09:51:39.321] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:39.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:104:0) after 1m0s
[09:52:39.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:104:0)
[09:52:39.370] Timer tick, asking Buildbucket for the build status
[09:52:39.641] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:39.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:105:0) after 1m0s
[09:53:39.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:105:0)
[09:53:39.655] Timer tick, asking Buildbucket for the build status
[09:53:40.565] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:40.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:106:0) after 1m0s
[09:54:40.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:106:0)
[09:54:40.592] Timer tick, asking Buildbucket for the build status
[09:54:40.736] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:40.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:107:0) after 1m0s
[09:55:40.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:107:0)
[09:55:40.747] Timer tick, asking Buildbucket for the build status
[09:55:40.916] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:40.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:108:0) after 1m0s
[09:56:40.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:108:0)
[09:56:40.914] Timer tick, asking Buildbucket for the build status
[09:56:41.019] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:41.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:109:0) after 1m0s
[09:57:41.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:109:0)
[09:57:41.044] Timer tick, asking Buildbucket for the build status
[09:57:41.155] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:41.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:110:0) after 1m0s
[09:58:41.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:110:0)
[09:58:41.206] Timer tick, asking Buildbucket for the build status
[09:58:41.306] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:41.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:111:0) after 1m0s
[09:59:41.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:111:0)
[09:59:41.333] Timer tick, asking Buildbucket for the build status
[09:59:41.418] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:41.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:112:0) after 1m0s
[10:00:41.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:112:0)
[10:00:41.455] Timer tick, asking Buildbucket for the build status
[10:00:41.603] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:41.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:113:0) after 1m0s
[10:01:41.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:113:0)
[10:01:41.636] Timer tick, asking Buildbucket for the build status
[10:01:41.942] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:41.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:114:0) after 1m0s
[10:02:41.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:114:0)
[10:02:41.934] Timer tick, asking Buildbucket for the build status
[10:02:42.241] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:42.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:115:0) after 1m0s
[10:03:42.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:115:0)
[10:03:42.228] Timer tick, asking Buildbucket for the build status
[10:03:42.774] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:42.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:116:0) after 1m0s
[10:04:42.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:116:0)
[10:04:42.852] Timer tick, asking Buildbucket for the build status
[10:04:43.118] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:43.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:117:0) after 1m0s
[10:05:43.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:117:0)
[10:05:43.149] Timer tick, asking Buildbucket for the build status
[10:05:43.483] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:43.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:118:0) after 1m0s
[10:06:43.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:118:0)
[10:06:43.513] Timer tick, asking Buildbucket for the build status
[10:06:43.616] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:43.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:119:0) after 1m0s
[10:07:43.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:119:0)
[10:07:43.647] Timer tick, asking Buildbucket for the build status
[10:07:43.731] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:43.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:120:0) after 1m0s
[10:08:43.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:120:0)
[10:08:43.714] Timer tick, asking Buildbucket for the build status
[10:08:44.079] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:44.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:121:0) after 1m0s
[10:09:44.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:121:0)
[10:09:44.113] Timer tick, asking Buildbucket for the build status
[10:09:44.197] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:44.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:122:0) after 1m0s
[10:10:44.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:122:0)
[10:10:44.300] Timer tick, asking Buildbucket for the build status
[10:10:44.500] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:44.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:123:0) after 1m0s
[10:11:44.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:123:0)
[10:11:44.606] Timer tick, asking Buildbucket for the build status
[10:11:44.719] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:44.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:124:0) after 1m0s
[10:12:44.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:124:0)
[10:12:44.745] Timer tick, asking Buildbucket for the build status
[10:12:44.851] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:44.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:125:0) after 1m0s
[10:13:44.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:125:0)
[10:13:44.888] Timer tick, asking Buildbucket for the build status
[10:13:44.974] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:44.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:126:0) after 1m0s
[10:14:45.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:126:0)
[10:14:45.030] Timer tick, asking Buildbucket for the build status
[10:14:45.155] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:45.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:127:0) after 1m0s
[10:15:45.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:127:0)
[10:15:45.185] Timer tick, asking Buildbucket for the build status
[10:15:45.296] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:45.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:128:0) after 1m0s
[10:16:45.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:128:0)
[10:16:45.324] Timer tick, asking Buildbucket for the build status
[10:16:45.460] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:45.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:129:0) after 1m0s
[10:17:45.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:129:0)
[10:17:45.466] Timer tick, asking Buildbucket for the build status
[10:17:45.719] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:45.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:130:0) after 1m0s
[10:18:45.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:130:0)
[10:18:45.750] Timer tick, asking Buildbucket for the build status
[10:18:45.842] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:45.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:131:0) after 1m0s
[10:19:45.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:131:0)
[10:19:45.954] Timer tick, asking Buildbucket for the build status
[10:19:46.009] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:46.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:132:0) after 1m0s
[10:20:46.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:132:0)
[10:20:46.087] Timer tick, asking Buildbucket for the build status
[10:20:46.261] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:46.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:133:0) after 1m0s
[10:21:46.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:133:0)
[10:21:46.277] Timer tick, asking Buildbucket for the build status
[10:21:46.349] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:46.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:134:0) after 1m0s
[10:22:46.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:134:0)
[10:22:46.405] Timer tick, asking Buildbucket for the build status
[10:22:46.508] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:46.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:135:0) after 1m0s
[10:23:46.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:135:0)
[10:23:46.555] Timer tick, asking Buildbucket for the build status
[10:23:46.611] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:46.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:136:0) after 1m0s
[10:24:46.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:136:0)
[10:24:46.626] Timer tick, asking Buildbucket for the build status
[10:24:46.698] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:46.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:137:0) after 1m0s
[10:25:46.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:137:0)
[10:25:46.802] Timer tick, asking Buildbucket for the build status
[10:25:47.089] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:47.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:138:0) after 1m0s
[10:26:47.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:138:0)
[10:26:47.161] Timer tick, asking Buildbucket for the build status
[10:26:47.255] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:47.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:139:0) after 1m0s
[10:27:47.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:139:0)
[10:27:47.321] Timer tick, asking Buildbucket for the build status
[10:27:47.510] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:47.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:140:0) after 1m0s
[10:28:47.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:140:0)
[10:28:47.555] Timer tick, asking Buildbucket for the build status
[10:28:47.642] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:47.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:141:0) after 1m0s
[10:29:47.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:141:0)
[10:29:47.636] Timer tick, asking Buildbucket for the build status
[10:29:47.942] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:47.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:142:0) after 1m0s
[10:30:48.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:142:0)
[10:30:48.104] Timer tick, asking Buildbucket for the build status
[10:30:48.205] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:48.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:143:0) after 1m0s
[10:31:48.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:143:0)
[10:31:48.675] Timer tick, asking Buildbucket for the build status
[10:31:48.747] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:48.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:144:0) after 1m0s
[10:32:49.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:144:0)
[10:32:49.071] Timer tick, asking Buildbucket for the build status
[10:32:49.224] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:49.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:145:0) after 1m0s
[10:33:49.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:145:0)
[10:33:49.264] Timer tick, asking Buildbucket for the build status
[10:33:49.562] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:49.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:146:0) after 1m0s
[10:34:49.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:146:0)
[10:34:49.593] Timer tick, asking Buildbucket for the build status
[10:34:49.981] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:49.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:147:0) after 1m0s
[10:35:50.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:147:0)
[10:35:50.094] Timer tick, asking Buildbucket for the build status
[10:35:50.312] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:50.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:148:0) after 1m0s
[10:36:50.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:148:0)
[10:36:50.346] Timer tick, asking Buildbucket for the build status
[10:36:50.550] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:50.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:149:0) after 1m0s
[10:37:50.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:149:0)
[10:37:50.677] Timer tick, asking Buildbucket for the build status
[10:37:50.763] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:50.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:150:0) after 1m0s
[10:38:50.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:150:0)
[10:38:50.785] Timer tick, asking Buildbucket for the build status
[10:38:50.872] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:50.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:151:0) after 1m0s
[10:39:50.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:151:0)
[10:39:50.924] Timer tick, asking Buildbucket for the build status
[10:39:51.031] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:51.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:152:0) after 1m0s
[10:40:51.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:152:0)
[10:40:51.067] Timer tick, asking Buildbucket for the build status
[10:40:51.187] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:51.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:153:0) after 1m0s
[10:41:51.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:153:0)
[10:41:51.273] Timer tick, asking Buildbucket for the build status
[10:41:51.402] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:51.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:154:0) after 1m0s
[10:42:51.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:154:0)
[10:42:51.491] Timer tick, asking Buildbucket for the build status
[10:42:51.656] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:51.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:155:0) after 1m0s
[10:43:51.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:155:0)
[10:43:51.691] Timer tick, asking Buildbucket for the build status
[10:43:51.948] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:51.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:156:0) after 1m0s
[10:44:52.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:156:0)
[10:44:52.146] Timer tick, asking Buildbucket for the build status
[10:44:52.413] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:52.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:157:0) after 1m0s
[10:45:52.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:157:0)
[10:45:52.478] Timer tick, asking Buildbucket for the build status
[10:45:52.890] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:52.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:158:0) after 1m0s
[10:46:52.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:158:0)
[10:46:52.921] Timer tick, asking Buildbucket for the build status
[10:46:52.998] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:52.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:159:0) after 1m0s
[10:47:53.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:159:0)
[10:47:53.036] Timer tick, asking Buildbucket for the build status
[10:47:53.246] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:53.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:160:0) after 1m0s
[10:48:53.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:160:0)
[10:48:53.319] Timer tick, asking Buildbucket for the build status
[10:48:53.398] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:53.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:161:0) after 1m0s
[10:49:53.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:161:0)
[10:49:53.485] Timer tick, asking Buildbucket for the build status
[10:49:53.604] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:53.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:162:0) after 1m0s
[10:50:53.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:162:0)
[10:50:53.626] Timer tick, asking Buildbucket for the build status
[10:50:53.909] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:53.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:163:0) after 1m0s
[10:51:54.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:163:0)
[10:51:54.004] Timer tick, asking Buildbucket for the build status
[10:51:54.114] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:54.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:164:0) after 1m0s
[10:52:54.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:164:0)
[10:52:54.223] Timer tick, asking Buildbucket for the build status
[10:52:54.376] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:54.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:165:0) after 1m0s
[10:53:54.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:165:0)
[10:53:54.557] Timer tick, asking Buildbucket for the build status
[10:53:54.697] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:54.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:166:0) after 1m0s
[10:54:54.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:166:0)
[10:54:54.828] Timer tick, asking Buildbucket for the build status
[10:54:54.945] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:54.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:167:0) after 1m0s
[10:55:55.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:167:0)
[10:55:55.065] Timer tick, asking Buildbucket for the build status
[10:55:55.504] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:55.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:168:0) after 1m0s
[10:56:55.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:168:0)
[10:56:55.488] Timer tick, asking Buildbucket for the build status
[10:56:55.721] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:55.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:169:0) after 1m0s
[10:57:55.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:169:0)
[10:57:55.843] Timer tick, asking Buildbucket for the build status
[10:57:56.120] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:56.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:170:0) after 1m0s
[10:58:56.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:170:0)
[10:58:56.229] Timer tick, asking Buildbucket for the build status
[10:58:57.739] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:57.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:171:0) after 1m0s
[10:59:57.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:171:0)
[10:59:57.724] Timer tick, asking Buildbucket for the build status
[10:59:57.800] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:57.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:172:0) after 1m0s
[11:00:57.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:172:0)
[11:00:57.873] Timer tick, asking Buildbucket for the build status
[11:00:58.076] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:58.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:173:0) after 1m0s
[11:01:58.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:173:0)
[11:01:58.110] Timer tick, asking Buildbucket for the build status
[11:01:58.327] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:58.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:174:0) after 1m0s
[11:02:58.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:174:0)
[11:02:58.423] Timer tick, asking Buildbucket for the build status
[11:02:58.500] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:58.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:175:0) after 1m0s
[11:03:58.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:175:0)
[11:03:58.578] Timer tick, asking Buildbucket for the build status
[11:03:58.740] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:58.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:176:0) after 1m0s
[11:04:58.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:176:0)
[11:04:58.788] Timer tick, asking Buildbucket for the build status
[11:04:58.881] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:58.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:177:0) after 1m0s
[11:05:59.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:177:0)
[11:05:59.026] Timer tick, asking Buildbucket for the build status
[11:05:59.140] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:59.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:178:0) after 1m0s
[11:06:59.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:178:0)
[11:06:59.167] Timer tick, asking Buildbucket for the build status
[11:06:59.279] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:59.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:179:0) after 1m0s
[11:07:59.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:179:0)
[11:07:59.326] Timer tick, asking Buildbucket for the build status
[11:07:59.432] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:59.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:180:0) after 1m0s
[11:08:59.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:180:0)
[11:08:59.514] Timer tick, asking Buildbucket for the build status
[11:08:59.600] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:59.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:181:0) after 1m0s
[11:09:59.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:181:0)
[11:09:59.635] Timer tick, asking Buildbucket for the build status
[11:10:00.454] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:00.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:182:0) after 1m0s
[11:11:00.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:182:0)
[11:11:00.492] Timer tick, asking Buildbucket for the build status
[11:11:00.591] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:00.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:183:0) after 1m0s
[11:12:02.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:183:0)
[11:12:02.468] Timer tick, asking Buildbucket for the build status
[11:12:02.559] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:02.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:184:0) after 1m0s
[11:13:03.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:184:0)
[11:13:03.289] Timer tick, asking Buildbucket for the build status
[11:13:03.397] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:03.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:185:0) after 1m0s
[11:14:03.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:185:0)
[11:14:03.772] Timer tick, asking Buildbucket for the build status
[11:14:03.888] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:03.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:186:0) after 1m0s
[11:15:03.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:186:0)
[11:15:03.912] Timer tick, asking Buildbucket for the build status
[11:15:04.287] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:04.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:187:0) after 1m0s
[11:16:04.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:187:0)
[11:16:04.387] Timer tick, asking Buildbucket for the build status
[11:16:04.514] Build 8871643781308235824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:04.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037073843565805936:188:0) after 1m0s
[11:16:45.882] Received PubSub notification, asking Buildbucket for the build status
[11:16:46.137] Build 8871643781308235824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:16:46.137] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1597749405171317",
    "created_by": "project:infra",
    "created_ts": "1597738203669095",
    "id": "8871643781308235824",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4237.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"00803614-2279-4067-8de8-0c0ec3f4e345\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4237.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_292036ca8cd0711903e829a3c5417e821d204af08fdef2e4b6d3affbee40eeb2_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-4icb\"], \"image\": [\"chrome-xenial-20081300-1358d8153ce\"], \"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\": [\"5494-59f692a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1597738442946728",
    "status": "COMPLETED",
    "status_changed_ts": "1597749405484664",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9037073843565805936",
      "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/8871643781308235824/+/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:4e196fca27c3c410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1597749405498095",
    "url": "https://ci.chromium.org/b/8871643781308235824",
    "utcnow_ts": "1597749406127274"
  }
}
[11:16:46.137] Invocation finished in 3h6m44.004843377s with status SUCCEEDED