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

• parent_buildername:publish_tarball
• user_agent:recipe

[10:52:20.409] New invocation is queued and will start shortly
[10:52:20.409] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:52:21.665] Starting the invocation (attempt 1)
[10:52:21.722] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:52:21.722] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:52:21.722] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9051830937787740640",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"82.0.4078.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"5620e337-5c3e-48a1-bc9f-f0a955f0b1f5\",\"title\":\"publish_tarball/0\"}]},\"version\":\"82.0.4078.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:9051830937787740640",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[10:52:22.174] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1583664741826483",
    "id": "8886400875633670608",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4078.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5620e337-5c3e-48a1-bc9f-f0a955f0b1f5\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4078.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1583664742011269",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9051830937787740640",
      "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/8886400875633670608/+/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": "1583664742011497",
    "url": "https://ci.chromium.org/b/8886400875633670608",
    "utcnow_ts": "1583664742160894"
  }
}
[10:52:22.174] Task URL: https://ci.chromium.org/b/8886400875633670608
[10:52:22.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:2:0) after 1m0s
[10:52:44.260] Received PubSub notification, asking Buildbucket for the build status
[10:52:44.351] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:22.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:2:0)
[10:53:22.248] Timer tick, asking Buildbucket for the build status
[10:53:22.354] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:22.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:4:0) after 1m0s
[10:54:22.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:4:0)
[10:54:22.352] Timer tick, asking Buildbucket for the build status
[10:54:22.882] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:22.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:5:0) after 1m0s
[10:55:22.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:5:0)
[10:55:22.941] Timer tick, asking Buildbucket for the build status
[10:55:23.261] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:23.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:6:0) after 1m0s
[10:56:23.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:6:0)
[10:56:23.288] Timer tick, asking Buildbucket for the build status
[10:56:23.512] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:23.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:7:0) after 1m0s
[10:57:23.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:7:0)
[10:57:23.587] Timer tick, asking Buildbucket for the build status
[10:57:23.694] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:23.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:8:0) after 1m0s
[10:58:23.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:8:0)
[10:58:23.671] Timer tick, asking Buildbucket for the build status
[10:58:23.775] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:23.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:9:0) after 1m0s
[10:59:23.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:9:0)
[10:59:23.793] Timer tick, asking Buildbucket for the build status
[10:59:23.920] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:23.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:10:0) after 1m0s
[11:00:24.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:10:0)
[11:00:24.023] Timer tick, asking Buildbucket for the build status
[11:00:24.219] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:24.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:11:0) after 1m0s
[11:01:24.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:11:0)
[11:01:24.284] Timer tick, asking Buildbucket for the build status
[11:01:24.405] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:24.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:12:0) after 1m0s
[11:02:24.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:12:0)
[11:02:24.494] Timer tick, asking Buildbucket for the build status
[11:02:24.742] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:24.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:13:0) after 1m0s
[11:03:24.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:13:0)
[11:03:24.769] Timer tick, asking Buildbucket for the build status
[11:03:25.017] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:25.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:14:0) after 1m0s
[11:04:25.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:14:0)
[11:04:25.051] Timer tick, asking Buildbucket for the build status
[11:04:25.204] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:25.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:15:0) after 1m0s
[11:05:25.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:15:0)
[11:05:25.286] Timer tick, asking Buildbucket for the build status
[11:05:25.496] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:25.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:16:0) after 1m0s
[11:06:25.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:16:0)
[11:06:25.513] Timer tick, asking Buildbucket for the build status
[11:06:25.752] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:25.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:17:0) after 1m0s
[11:07:25.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:17:0)
[11:07:25.814] Timer tick, asking Buildbucket for the build status
[11:07:26.060] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:26.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:18:0) after 1m0s
[11:08:26.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:18:0)
[11:08:26.086] Timer tick, asking Buildbucket for the build status
[11:08:26.216] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:26.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:19:0) after 1m0s
[11:09:26.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:19:0)
[11:09:26.294] Timer tick, asking Buildbucket for the build status
[11:09:26.589] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:26.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:20:0) after 1m0s
[11:10:26.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:20:0)
[11:10:26.657] Timer tick, asking Buildbucket for the build status
[11:10:26.765] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:26.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:21:0) after 1m0s
[11:11:26.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:21:0)
[11:11:26.838] Timer tick, asking Buildbucket for the build status
[11:11:27.165] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:27.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:22:0) after 1m0s
[11:12:27.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:22:0)
[11:12:27.219] Timer tick, asking Buildbucket for the build status
[11:12:27.338] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:27.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:23:0) after 1m0s
[11:13:27.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:23:0)
[11:13:27.320] Timer tick, asking Buildbucket for the build status
[11:13:27.534] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:27.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:24:0) after 1m0s
[11:14:27.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:24:0)
[11:14:27.520] Timer tick, asking Buildbucket for the build status
[11:14:27.699] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:27.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:25:0) after 1m0s
[11:15:27.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:25:0)
[11:15:27.810] Timer tick, asking Buildbucket for the build status
[11:15:28.023] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:28.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:26:0) after 1m0s
[11:16:28.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:26:0)
[11:16:28.145] Timer tick, asking Buildbucket for the build status
[11:16:28.301] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:28.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:27:0) after 1m0s
[11:17:28.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:27:0)
[11:17:28.506] Timer tick, asking Buildbucket for the build status
[11:17:28.604] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:28.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:28:0) after 1m0s
[11:18:28.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:28:0)
[11:18:28.670] Timer tick, asking Buildbucket for the build status
[11:18:29.542] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:29.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:29:0) after 1m0s
[11:19:29.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:29:0)
[11:19:29.782] Timer tick, asking Buildbucket for the build status
[11:19:29.918] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:29.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:30:0) after 1m0s
[11:20:30.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:30:0)
[11:20:30.082] Timer tick, asking Buildbucket for the build status
[11:20:30.298] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:30.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:31:0) after 1m0s
[11:21:30.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:31:0)
[11:21:30.388] Timer tick, asking Buildbucket for the build status
[11:21:30.535] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:30.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:32:0) after 1m0s
[11:22:30.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:32:0)
[11:22:30.564] Timer tick, asking Buildbucket for the build status
[11:22:30.702] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:30.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:33:0) after 1m0s
[11:23:30.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:33:0)
[11:23:30.812] Timer tick, asking Buildbucket for the build status
[11:23:31.137] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:31.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:34:0) after 1m0s
[11:24:31.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:34:0)
[11:24:31.130] Timer tick, asking Buildbucket for the build status
[11:24:31.262] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:31.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:35:0) after 1m0s
[11:25:31.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:35:0)
[11:25:31.395] Timer tick, asking Buildbucket for the build status
[11:25:31.547] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:31.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:36:0) after 1m0s
[11:26:31.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:36:0)
[11:26:31.569] Timer tick, asking Buildbucket for the build status
[11:26:31.835] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:31.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:37:0) after 1m0s
[11:27:31.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:37:0)
[11:27:31.859] Timer tick, asking Buildbucket for the build status
[11:27:32.095] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:32.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:38:0) after 1m0s
[11:28:32.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:38:0)
[11:28:32.238] Timer tick, asking Buildbucket for the build status
[11:28:32.548] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:32.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:39:0) after 1m0s
[11:29:32.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:39:0)
[11:29:32.692] Timer tick, asking Buildbucket for the build status
[11:29:33.157] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:33.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:40:0) after 1m0s
[11:30:33.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:40:0)
[11:30:33.179] Timer tick, asking Buildbucket for the build status
[11:30:33.633] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:33.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:41:0) after 1m0s
[11:31:33.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:41:0)
[11:31:33.669] Timer tick, asking Buildbucket for the build status
[11:31:33.881] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:33.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:42:0) after 1m0s
[11:32:33.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:42:0)
[11:32:34.000] Timer tick, asking Buildbucket for the build status
[11:32:34.143] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:34.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:43:0) after 1m0s
[11:33:34.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:43:0)
[11:33:34.276] Timer tick, asking Buildbucket for the build status
[11:33:34.426] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:34.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:44:0) after 1m0s
[11:34:34.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:44:0)
[11:34:34.421] Timer tick, asking Buildbucket for the build status
[11:34:34.540] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:34.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:45:0) after 1m0s
[11:35:34.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:45:0)
[11:35:34.587] Timer tick, asking Buildbucket for the build status
[11:35:34.814] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:34.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:46:0) after 1m0s
[11:36:34.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:46:0)
[11:36:34.931] Timer tick, asking Buildbucket for the build status
[11:36:35.585] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:35.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:47:0) after 1m0s
[11:37:35.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:47:0)
[11:37:35.676] Timer tick, asking Buildbucket for the build status
[11:37:35.790] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:35.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:48:0) after 1m0s
[11:38:35.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:48:0)
[11:38:35.872] Timer tick, asking Buildbucket for the build status
[11:38:36.001] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:36.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:49:0) after 1m0s
[11:39:36.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:49:0)
[11:39:36.128] Timer tick, asking Buildbucket for the build status
[11:39:36.251] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:36.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:50:0) after 1m0s
[11:40:36.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:50:0)
[11:40:36.279] Timer tick, asking Buildbucket for the build status
[11:40:36.503] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:36.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:51:0) after 1m0s
[11:41:36.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:51:0)
[11:41:36.716] Timer tick, asking Buildbucket for the build status
[11:41:36.857] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:36.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:52:0) after 1m0s
[11:42:36.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:52:0)
[11:42:36.892] Timer tick, asking Buildbucket for the build status
[11:42:37.035] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:37.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:53:0) after 1m0s
[11:43:37.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:53:0)
[11:43:37.126] Timer tick, asking Buildbucket for the build status
[11:43:37.240] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:37.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:54:0) after 1m0s
[11:44:37.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:54:0)
[11:44:37.265] Timer tick, asking Buildbucket for the build status
[11:44:37.401] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:37.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:55:0) after 1m0s
[11:45:37.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:55:0)
[11:45:37.538] Timer tick, asking Buildbucket for the build status
[11:45:37.905] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:37.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:56:0) after 1m0s
[11:46:38.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:56:0)
[11:46:38.102] Timer tick, asking Buildbucket for the build status
[11:46:38.283] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:38.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:57:0) after 1m0s
[11:47:38.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:57:0)
[11:47:38.376] Timer tick, asking Buildbucket for the build status
[11:47:38.552] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:38.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:58:0) after 1m0s
[11:48:38.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:58:0)
[11:48:38.619] Timer tick, asking Buildbucket for the build status
[11:48:38.706] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:38.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:59:0) after 1m0s
[11:49:38.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:59:0)
[11:49:38.762] Timer tick, asking Buildbucket for the build status
[11:49:38.921] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:38.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:60:0) after 1m0s
[11:50:39.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:60:0)
[11:50:39.004] Timer tick, asking Buildbucket for the build status
[11:50:39.129] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:39.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:61:0) after 1m0s
[11:51:39.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:61:0)
[11:51:39.306] Timer tick, asking Buildbucket for the build status
[11:51:39.688] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:39.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:62:0) after 1m0s
[11:52:39.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:62:0)
[11:52:39.820] Timer tick, asking Buildbucket for the build status
[11:52:39.916] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:39.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:63:0) after 1m0s
[11:53:39.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:63:0)
[11:53:39.967] Timer tick, asking Buildbucket for the build status
[11:53:40.109] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:40.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:64:0) after 1m0s
[11:54:40.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:64:0)
[11:54:40.201] Timer tick, asking Buildbucket for the build status
[11:54:40.548] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:40.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:65:0) after 1m0s
[11:55:40.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:65:0)
[11:55:40.684] Timer tick, asking Buildbucket for the build status
[11:55:40.809] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:40.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:66:0) after 1m0s
[11:56:40.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:66:0)
[11:56:40.832] Timer tick, asking Buildbucket for the build status
[11:56:40.960] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:40.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:67:0) after 1m0s
[11:57:40.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:67:0)
[11:57:40.980] Timer tick, asking Buildbucket for the build status
[11:57:41.261] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:41.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:68:0) after 1m0s
[11:58:41.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:68:0)
[11:58:41.318] Timer tick, asking Buildbucket for the build status
[11:58:41.429] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:41.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:69:0) after 1m0s
[11:59:41.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:69:0)
[11:59:41.451] Timer tick, asking Buildbucket for the build status
[11:59:41.614] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:41.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:70:0) after 1m0s
[12:00:41.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:70:0)
[12:00:41.641] Timer tick, asking Buildbucket for the build status
[12:00:41.758] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:41.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:71:0) after 1m0s
[12:01:41.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:71:0)
[12:01:41.875] Timer tick, asking Buildbucket for the build status
[12:01:42.033] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:42.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:72:0) after 1m0s
[12:02:42.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:72:0)
[12:02:42.176] Timer tick, asking Buildbucket for the build status
[12:02:42.274] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:42.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:73:0) after 1m0s
[12:03:42.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:73:0)
[12:03:42.350] Timer tick, asking Buildbucket for the build status
[12:03:42.586] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:42.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:74:0) after 1m0s
[12:04:44.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:74:0)
[12:04:44.297] Timer tick, asking Buildbucket for the build status
[12:04:44.459] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:44.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:75:0) after 1m0s
[12:05:44.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:75:0)
[12:05:44.485] Timer tick, asking Buildbucket for the build status
[12:05:44.587] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:44.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:76:0) after 1m0s
[12:06:44.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:76:0)
[12:06:44.606] Timer tick, asking Buildbucket for the build status
[12:06:44.981] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:44.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:77:0) after 1m0s
[12:07:45.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:77:0)
[12:07:45.004] Timer tick, asking Buildbucket for the build status
[12:07:45.198] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:45.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:78:0) after 1m0s
[12:08:45.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:78:0)
[12:08:45.227] Timer tick, asking Buildbucket for the build status
[12:08:45.409] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:45.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:79:0) after 1m0s
[12:09:45.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:79:0)
[12:09:45.432] Timer tick, asking Buildbucket for the build status
[12:09:45.551] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:45.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:80:0) after 1m0s
[12:10:45.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:80:0)
[12:10:45.575] Timer tick, asking Buildbucket for the build status
[12:10:46.055] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:46.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:81:0) after 1m0s
[12:11:46.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:81:0)
[12:11:46.104] Timer tick, asking Buildbucket for the build status
[12:11:46.279] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:46.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:82:0) after 1m0s
[12:12:46.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:82:0)
[12:12:46.349] Timer tick, asking Buildbucket for the build status
[12:12:46.460] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:46.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:83:0) after 1m0s
[12:13:46.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:83:0)
[12:13:46.443] Timer tick, asking Buildbucket for the build status
[12:13:46.661] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:46.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:84:0) after 1m0s
[12:14:46.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:84:0)
[12:14:46.745] Timer tick, asking Buildbucket for the build status
[12:14:46.857] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:46.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:85:0) after 1m0s
[12:15:46.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:85:0)
[12:15:46.988] Timer tick, asking Buildbucket for the build status
[12:15:47.151] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:47.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:86:0) after 1m0s
[12:16:47.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:86:0)
[12:16:47.320] Timer tick, asking Buildbucket for the build status
[12:16:47.780] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:47.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:87:0) after 1m0s
[12:17:47.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:87:0)
[12:17:47.882] Timer tick, asking Buildbucket for the build status
[12:17:48.001] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:48.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:88:0) after 1m0s
[12:18:48.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:88:0)
[12:18:48.048] Timer tick, asking Buildbucket for the build status
[12:18:48.289] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:48.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:89:0) after 1m0s
[12:19:48.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:89:0)
[12:19:48.323] Timer tick, asking Buildbucket for the build status
[12:19:48.657] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:48.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:90:0) after 1m0s
[12:20:48.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:90:0)
[12:20:48.697] Timer tick, asking Buildbucket for the build status
[12:20:48.965] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:48.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:91:0) after 1m0s
[12:21:49.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:91:0)
[12:21:49.097] Timer tick, asking Buildbucket for the build status
[12:21:49.213] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:49.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:92:0) after 1m0s
[12:22:49.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:92:0)
[12:22:49.558] Timer tick, asking Buildbucket for the build status
[12:22:50.357] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:50.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:93:0) after 1m0s
[12:23:55.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:93:0)
[12:23:55.401] Timer tick, asking Buildbucket for the build status
[12:23:55.567] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:55.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:94:0) after 1m0s
[12:24:55.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:94:0)
[12:24:55.603] Timer tick, asking Buildbucket for the build status
[12:24:55.731] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:55.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:95:0) after 1m0s
[12:25:55.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:95:0)
[12:25:55.789] Timer tick, asking Buildbucket for the build status
[12:25:56.047] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:56.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:96:0) after 1m0s
[12:26:56.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:96:0)
[12:26:56.141] Timer tick, asking Buildbucket for the build status
[12:26:56.589] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:56.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:97:0) after 1m0s
[12:27:56.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:97:0)
[12:27:56.647] Timer tick, asking Buildbucket for the build status
[12:27:56.995] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:56.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:98:0) after 1m0s
[12:28:57.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:98:0)
[12:28:57.127] Timer tick, asking Buildbucket for the build status
[12:28:57.332] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:57.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:99:0) after 1m0s
[12:29:57.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:99:0)
[12:29:57.425] Timer tick, asking Buildbucket for the build status
[12:29:57.569] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:57.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:100:0) after 1m0s
[12:30:57.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:100:0)
[12:30:57.550] Timer tick, asking Buildbucket for the build status
[12:30:57.726] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:57.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:101:0) after 1m0s
[12:31:57.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:101:0)
[12:31:57.894] Timer tick, asking Buildbucket for the build status
[12:31:58.045] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:58.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:102:0) after 1m0s
[12:32:58.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:102:0)
[12:32:58.125] Timer tick, asking Buildbucket for the build status
[12:32:58.227] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:58.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:103:0) after 1m0s
[12:33:58.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:103:0)
[12:33:58.252] Timer tick, asking Buildbucket for the build status
[12:33:58.371] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:58.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:104:0) after 1m0s
[12:34:58.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:104:0)
[12:34:58.402] Timer tick, asking Buildbucket for the build status
[12:34:58.504] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:58.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:105:0) after 1m0s
[12:35:58.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:105:0)
[12:35:58.573] Timer tick, asking Buildbucket for the build status
[12:35:59.042] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:59.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:106:0) after 1m0s
[12:36:59.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:106:0)
[12:36:59.165] Timer tick, asking Buildbucket for the build status
[12:36:59.475] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:59.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:107:0) after 1m0s
[12:37:59.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:107:0)
[12:37:59.499] Timer tick, asking Buildbucket for the build status
[12:37:59.677] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:59.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:108:0) after 1m0s
[12:38:59.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:108:0)
[12:38:59.700] Timer tick, asking Buildbucket for the build status
[12:38:59.906] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:59.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:109:0) after 1m0s
[12:40:00.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:109:0)
[12:40:00.142] Timer tick, asking Buildbucket for the build status
[12:40:00.268] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:00.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:110:0) after 1m0s
[12:41:00.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:110:0)
[12:41:00.374] Timer tick, asking Buildbucket for the build status
[12:41:00.743] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:00.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:111:0) after 1m0s
[12:42:00.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:111:0)
[12:42:00.739] Timer tick, asking Buildbucket for the build status
[12:42:00.899] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:00.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:112:0) after 1m0s
[12:43:00.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:112:0)
[12:43:00.927] Timer tick, asking Buildbucket for the build status
[12:43:01.103] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:01.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:113:0) after 1m0s
[12:44:01.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:113:0)
[12:44:01.130] Timer tick, asking Buildbucket for the build status
[12:44:01.333] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:01.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:114:0) after 1m0s
[12:45:01.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:114:0)
[12:45:01.356] Timer tick, asking Buildbucket for the build status
[12:45:01.652] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:01.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:115:0) after 1m0s
[12:46:01.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:115:0)
[12:46:01.677] Timer tick, asking Buildbucket for the build status
[12:46:03.291] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:03.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:116:0) after 1m0s
[12:47:03.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:116:0)
[12:47:03.312] Timer tick, asking Buildbucket for the build status
[12:47:03.530] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:03.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:117:0) after 1m0s
[12:48:03.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:117:0)
[12:48:03.549] Timer tick, asking Buildbucket for the build status
[12:48:03.671] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:03.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:118:0) after 1m0s
[12:49:03.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:118:0)
[12:49:03.696] Timer tick, asking Buildbucket for the build status
[12:49:03.826] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:03.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:119:0) after 1m0s
[12:50:03.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:119:0)
[12:50:03.848] Timer tick, asking Buildbucket for the build status
[12:50:04.261] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:04.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:120:0) after 1m0s
[12:51:04.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:120:0)
[12:51:04.323] Timer tick, asking Buildbucket for the build status
[12:51:04.467] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:04.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:121:0) after 1m0s
[12:52:04.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:121:0)
[12:52:04.494] Timer tick, asking Buildbucket for the build status
[12:52:04.602] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:04.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:122:0) after 1m0s
[12:53:04.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:122:0)
[12:53:04.687] Timer tick, asking Buildbucket for the build status
[12:53:04.814] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:04.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:123:0) after 1m0s
[12:54:04.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:123:0)
[12:54:04.849] Timer tick, asking Buildbucket for the build status
[12:54:07.479] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:07.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:124:0) after 1m0s
[12:55:07.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:124:0)
[12:55:07.553] Timer tick, asking Buildbucket for the build status
[12:55:07.707] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:07.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:125:0) after 1m0s
[12:56:07.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:125:0)
[12:56:07.778] Timer tick, asking Buildbucket for the build status
[12:56:08.003] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:08.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:126:0) after 1m0s
[12:57:08.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:126:0)
[12:57:08.033] Timer tick, asking Buildbucket for the build status
[12:57:08.197] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:08.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:127:0) after 1m0s
[12:58:08.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:127:0)
[12:58:08.253] Timer tick, asking Buildbucket for the build status
[12:58:08.379] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:08.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:128:0) after 1m0s
[12:59:08.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:128:0)
[12:59:08.401] Timer tick, asking Buildbucket for the build status
[12:59:08.527] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:08.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:129:0) after 1m0s
[13:00:08.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:129:0)
[13:00:08.618] Timer tick, asking Buildbucket for the build status
[13:00:08.804] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:08.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:130:0) after 1m0s
[13:01:08.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:130:0)
[13:01:08.884] Timer tick, asking Buildbucket for the build status
[13:01:09.078] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:09.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:131:0) after 1m0s
[13:02:09.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:131:0)
[13:02:09.194] Timer tick, asking Buildbucket for the build status
[13:02:09.592] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:09.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:132:0) after 1m0s
[13:03:09.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:132:0)
[13:03:09.725] Timer tick, asking Buildbucket for the build status
[13:03:09.846] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:09.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:133:0) after 1m0s
[13:04:10.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:133:0)
[13:04:10.062] Timer tick, asking Buildbucket for the build status
[13:04:10.253] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:10.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:134:0) after 1m0s
[13:05:10.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:134:0)
[13:05:10.371] Timer tick, asking Buildbucket for the build status
[13:05:10.502] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:10.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:135:0) after 1m0s
[13:06:10.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:135:0)
[13:06:10.630] Timer tick, asking Buildbucket for the build status
[13:06:10.739] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:10.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:136:0) after 1m0s
[13:07:10.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:136:0)
[13:07:10.757] Timer tick, asking Buildbucket for the build status
[13:07:10.877] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:10.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:137:0) after 1m0s
[13:08:11.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:137:0)
[13:08:11.150] Timer tick, asking Buildbucket for the build status
[13:08:11.251] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:11.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:138:0) after 1m0s
[13:09:11.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:138:0)
[13:09:11.514] Timer tick, asking Buildbucket for the build status
[13:09:11.646] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:11.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:139:0) after 1m0s
[13:10:11.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:139:0)
[13:10:11.797] Timer tick, asking Buildbucket for the build status
[13:10:11.920] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:11.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:140:0) after 1m0s
[13:11:11.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:140:0)
[13:11:11.987] Timer tick, asking Buildbucket for the build status
[13:11:12.140] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:12.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:141:0) after 1m0s
[13:12:12.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:141:0)
[13:12:12.236] Timer tick, asking Buildbucket for the build status
[13:12:12.380] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:12.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:142:0) after 1m0s
[13:13:12.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:142:0)
[13:13:12.444] Timer tick, asking Buildbucket for the build status
[13:13:12.676] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:12.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:143:0) after 1m0s
[13:14:12.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:143:0)
[13:14:12.710] Timer tick, asking Buildbucket for the build status
[13:14:12.848] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:12.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:144:0) after 1m0s
[13:15:12.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:144:0)
[13:15:12.872] Timer tick, asking Buildbucket for the build status
[13:15:13.049] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:13.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:145:0) after 1m0s
[13:16:13.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:145:0)
[13:16:13.081] Timer tick, asking Buildbucket for the build status
[13:16:13.199] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:13.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:146:0) after 1m0s
[13:17:13.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:146:0)
[13:17:13.353] Timer tick, asking Buildbucket for the build status
[13:17:13.489] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:13.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:147:0) after 1m0s
[13:18:13.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:147:0)
[13:18:13.606] Timer tick, asking Buildbucket for the build status
[13:18:13.766] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:13.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:148:0) after 1m0s
[13:19:19.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:148:0)
[13:19:19.666] Timer tick, asking Buildbucket for the build status
[13:19:19.867] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:19.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:149:0) after 1m0s
[13:20:20.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:149:0)
[13:20:20.084] Timer tick, asking Buildbucket for the build status
[13:20:20.234] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:20.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:150:0) after 1m0s
[13:21:20.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:150:0)
[13:21:20.363] Timer tick, asking Buildbucket for the build status
[13:21:20.767] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:20.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:151:0) after 1m0s
[13:22:20.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:151:0)
[13:22:20.926] Timer tick, asking Buildbucket for the build status
[13:22:21.280] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:21.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:152:0) after 1m0s
[13:23:21.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:152:0)
[13:23:21.422] Timer tick, asking Buildbucket for the build status
[13:23:21.554] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:21.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:153:0) after 1m0s
[13:24:21.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:153:0)
[13:24:21.711] Timer tick, asking Buildbucket for the build status
[13:24:21.830] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:21.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:154:0) after 1m0s
[13:25:22.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:154:0)
[13:25:22.066] Timer tick, asking Buildbucket for the build status
[13:25:22.238] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:22.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:155:0) after 1m0s
[13:26:22.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:155:0)
[13:26:22.316] Timer tick, asking Buildbucket for the build status
[13:26:22.575] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:22.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:156:0) after 1m0s
[13:27:22.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:156:0)
[13:27:22.732] Timer tick, asking Buildbucket for the build status
[13:27:22.871] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:22.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:157:0) after 1m0s
[13:28:22.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:157:0)
[13:28:22.928] Timer tick, asking Buildbucket for the build status
[13:28:23.192] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:23.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:158:0) after 1m0s
[13:29:23.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:158:0)
[13:29:23.268] Timer tick, asking Buildbucket for the build status
[13:29:23.382] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:23.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:159:0) after 1m0s
[13:30:23.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:159:0)
[13:30:23.453] Timer tick, asking Buildbucket for the build status
[13:30:23.607] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:23.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:160:0) after 1m0s
[13:31:23.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:160:0)
[13:31:23.684] Timer tick, asking Buildbucket for the build status
[13:31:24.175] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:24.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:161:0) after 1m0s
[13:32:24.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:161:0)
[13:32:24.229] Timer tick, asking Buildbucket for the build status
[13:32:24.459] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:24.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:162:0) after 1m0s
[13:33:24.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:162:0)
[13:33:24.493] Timer tick, asking Buildbucket for the build status
[13:33:24.681] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:24.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:163:0) after 1m0s
[13:34:24.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:163:0)
[13:34:24.816] Timer tick, asking Buildbucket for the build status
[13:34:24.903] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:24.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:164:0) after 1m0s
[13:35:25.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:164:0)
[13:35:25.136] Timer tick, asking Buildbucket for the build status
[13:35:25.268] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:25.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:165:0) after 1m0s
[13:36:25.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:165:0)
[13:36:25.304] Timer tick, asking Buildbucket for the build status
[13:36:25.493] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:25.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:166:0) after 1m0s
[13:37:25.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:166:0)
[13:37:25.489] Timer tick, asking Buildbucket for the build status
[13:37:25.581] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:25.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:167:0) after 1m0s
[13:38:25.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:167:0)
[13:38:25.601] Timer tick, asking Buildbucket for the build status
[13:38:25.709] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:25.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:168:0) after 1m0s
[13:39:25.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:168:0)
[13:39:25.858] Timer tick, asking Buildbucket for the build status
[13:39:26.097] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:26.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:169:0) after 1m0s
[13:40:26.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:169:0)
[13:40:26.152] Timer tick, asking Buildbucket for the build status
[13:40:26.316] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:26.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:170:0) after 1m0s
[13:41:26.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:170:0)
[13:41:26.379] Timer tick, asking Buildbucket for the build status
[13:41:26.515] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:26.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:171:0) after 1m0s
[13:42:26.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:171:0)
[13:42:26.668] Timer tick, asking Buildbucket for the build status
[13:42:26.788] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:26.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:172:0) after 1m0s
[13:43:26.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:172:0)
[13:43:26.908] Timer tick, asking Buildbucket for the build status
[13:43:27.035] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:27.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:173:0) after 1m0s
[13:44:27.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:173:0)
[13:44:27.099] Timer tick, asking Buildbucket for the build status
[13:44:27.359] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:27.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:174:0) after 1m0s
[13:45:27.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:174:0)
[13:45:27.434] Timer tick, asking Buildbucket for the build status
[13:45:27.572] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:27.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:175:0) after 1m0s
[13:46:27.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:175:0)
[13:46:27.593] Timer tick, asking Buildbucket for the build status
[13:46:27.756] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:27.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:176:0) after 1m0s
[13:47:27.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:176:0)
[13:47:27.925] Timer tick, asking Buildbucket for the build status
[13:47:28.066] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:28.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:177:0) after 1m0s
[13:48:28.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:177:0)
[13:48:28.121] Timer tick, asking Buildbucket for the build status
[13:48:28.254] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:28.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:178:0) after 1m0s
[13:49:28.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:178:0)
[13:49:28.373] Timer tick, asking Buildbucket for the build status
[13:49:28.854] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:28.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:179:0) after 1m0s
[13:50:28.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:179:0)
[13:50:28.874] Timer tick, asking Buildbucket for the build status
[13:50:29.257] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:29.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:180:0) after 1m0s
[13:51:29.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:180:0)
[13:51:29.291] Timer tick, asking Buildbucket for the build status
[13:51:29.507] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:29.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:181:0) after 1m0s
[13:52:29.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:181:0)
[13:52:29.485] Timer tick, asking Buildbucket for the build status
[13:52:29.798] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:29.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:182:0) after 1m0s
[13:53:29.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:182:0)
[13:53:29.835] Timer tick, asking Buildbucket for the build status
[13:53:30.102] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:30.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:183:0) after 1m0s
[13:54:30.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:183:0)
[13:54:30.150] Timer tick, asking Buildbucket for the build status
[13:54:30.462] Build 8886400875633670608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:30.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051830937787740640:184:0) after 1m0s
[13:54:33.818] Received PubSub notification, asking Buildbucket for the build status
[13:54:33.895] Build 8886400875633670608: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:54:33.895] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1583675672667729",
    "created_by": "project:infra",
    "created_ts": "1583664741826483",
    "id": "8886400875633670608",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4078.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5620e337-5c3e-48a1-bc9f-f0a955f0b1f5\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4078.2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4078.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5620e337-5c3e-48a1-bc9f-f0a955f0b1f5\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-6qg2\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"82.0.4078.2\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"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-6qg2\"], \"image\": [\"chrome-xenial-20030100-2bddf837f90\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1583664763430974",
    "status": "COMPLETED",
    "status_changed_ts": "1583675673095530",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9051830937787740640",
      "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/8886400875633670608/+/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:4ad297c86448f410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1583675673155442",
    "url": "https://ci.chromium.org/b/8886400875633670608",
    "utcnow_ts": "1583675673883961"
  }
}
[13:54:33.898] Invocation finished in 3h2m13.510414582s with status SUCCEEDED