buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.infra.cron"
  builder: "Build From Tarball"
>

• publish_tarball/0 (4 years ago by chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com)

{
  "version": "91.0.4445.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[12:50:15.454] New invocation is queued and will start shortly
[12:50:15.454] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:50:16.613] Starting the invocation (attempt 1)
[12:50:16.642] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:50:16.642] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:50:16.643] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9018302641489361856",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"91.0.4445.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"af7b352c-766d-4e62-8893-ac9c50545a15\",\"title\":\"publish_tarball/0\"}]},\"version\":\"91.0.4445.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:9018302641489361856",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:50:17.976] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1615639816750698",
    "id": "8852872579469545056",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4445.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"af7b352c-766d-4e62-8893-ac9c50545a15\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4445.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1615639817025229",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9018302641489361856",
      "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/8852872579469545056/+/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": "1615639817025382",
    "url": "https://ci.chromium.org/b/8852872579469545056",
    "utcnow_ts": "1615639817258366"
  }
}
[12:50:17.976] Task URL: https://ci.chromium.org/b/8852872579469545056
[12:50:17.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:2:0) after 1m0s
[12:51:15.648] Received PubSub notification, asking Buildbucket for the build status
[12:51:15.709] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:18.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:2:0)
[12:51:18.037] Timer tick, asking Buildbucket for the build status
[12:51:18.190] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:18.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:4:0) after 1m0s
[12:52:18.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:4:0)
[12:52:18.657] Timer tick, asking Buildbucket for the build status
[12:52:18.751] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:18.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:5:0) after 1m0s
[12:53:19.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:5:0)
[12:53:19.016] Timer tick, asking Buildbucket for the build status
[12:53:19.077] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:19.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:6:0) after 1m0s
[12:54:19.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:6:0)
[12:54:19.092] Timer tick, asking Buildbucket for the build status
[12:54:19.462] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:19.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:7:0) after 1m0s
[12:55:19.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:7:0)
[12:55:19.487] Timer tick, asking Buildbucket for the build status
[12:55:19.895] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:19.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:8:0) after 1m0s
[12:56:19.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:8:0)
[12:56:19.920] Timer tick, asking Buildbucket for the build status
[12:56:19.991] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:19.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:9:0) after 1m0s
[12:57:20.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:9:0)
[12:57:20.009] Timer tick, asking Buildbucket for the build status
[12:57:20.087] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:20.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:10:0) after 1m0s
[12:58:20.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:10:0)
[12:58:20.111] Timer tick, asking Buildbucket for the build status
[12:58:20.179] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:20.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:11:0) after 1m0s
[12:59:20.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:11:0)
[12:59:20.194] Timer tick, asking Buildbucket for the build status
[12:59:20.274] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:20.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:12:0) after 1m0s
[13:00:20.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:12:0)
[13:00:20.407] Timer tick, asking Buildbucket for the build status
[13:00:20.689] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:20.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:13:0) after 1m0s
[13:01:22.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:13:0)
[13:01:22.529] Timer tick, asking Buildbucket for the build status
[13:01:22.596] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:22.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:14:0) after 1m0s
[13:02:22.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:14:0)
[13:02:22.612] Timer tick, asking Buildbucket for the build status
[13:02:22.711] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:22.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:15:0) after 1m0s
[13:03:22.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:15:0)
[13:03:22.806] Timer tick, asking Buildbucket for the build status
[13:03:22.920] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:22.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:16:0) after 1m0s
[13:04:23.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:16:0)
[13:04:23.011] Timer tick, asking Buildbucket for the build status
[13:04:23.080] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:23.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:17:0) after 1m0s
[13:05:23.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:17:0)
[13:05:23.123] Timer tick, asking Buildbucket for the build status
[13:05:23.211] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:23.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:18:0) after 1m0s
[13:06:23.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:18:0)
[13:06:23.228] Timer tick, asking Buildbucket for the build status
[13:06:23.401] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:23.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:19:0) after 1m0s
[13:07:23.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:19:0)
[13:07:23.418] Timer tick, asking Buildbucket for the build status
[13:07:23.533] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:23.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:20:0) after 1m0s
[13:08:23.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:20:0)
[13:08:23.552] Timer tick, asking Buildbucket for the build status
[13:08:23.654] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:23.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:21:0) after 1m0s
[13:09:23.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:21:0)
[13:09:23.685] Timer tick, asking Buildbucket for the build status
[13:09:23.756] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:23.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:22:0) after 1m0s
[13:10:23.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:22:0)
[13:10:23.821] Timer tick, asking Buildbucket for the build status
[13:10:23.905] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:23.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:23:0) after 1m0s
[13:11:23.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:23:0)
[13:11:23.932] Timer tick, asking Buildbucket for the build status
[13:11:24.007] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:24.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:24:0) after 1m0s
[13:12:24.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:24:0)
[13:12:24.746] Timer tick, asking Buildbucket for the build status
[13:12:24.814] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:24.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:25:0) after 1m0s
[13:13:25.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:25:0)
[13:13:25.801] Timer tick, asking Buildbucket for the build status
[13:13:25.872] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:25.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:26:0) after 1m0s
[13:14:25.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:26:0)
[13:14:25.890] Timer tick, asking Buildbucket for the build status
[13:14:26.109] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:26.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:27:0) after 1m0s
[13:15:26.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:27:0)
[13:15:26.133] Timer tick, asking Buildbucket for the build status
[13:15:26.208] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:26.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:28:0) after 1m0s
[13:16:26.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:28:0)
[13:16:26.314] Timer tick, asking Buildbucket for the build status
[13:16:26.383] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:26.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:29:0) after 1m0s
[13:17:26.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:29:0)
[13:17:26.563] Timer tick, asking Buildbucket for the build status
[13:17:26.699] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:26.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:30:0) after 1m0s
[13:18:26.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:30:0)
[13:18:26.765] Timer tick, asking Buildbucket for the build status
[13:18:27.062] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:27.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:31:0) after 1m0s
[13:19:27.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:31:0)
[13:19:27.200] Timer tick, asking Buildbucket for the build status
[13:19:27.306] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:27.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:32:0) after 1m0s
[13:20:27.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:32:0)
[13:20:27.714] Timer tick, asking Buildbucket for the build status
[13:20:27.806] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:27.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:33:0) after 1m0s
[13:21:27.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:33:0)
[13:21:27.899] Timer tick, asking Buildbucket for the build status
[13:21:28.161] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:28.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:34:0) after 1m0s
[13:22:28.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:34:0)
[13:22:28.285] Timer tick, asking Buildbucket for the build status
[13:22:28.364] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:28.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:35:0) after 1m0s
[13:23:28.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:35:0)
[13:23:28.546] Timer tick, asking Buildbucket for the build status
[13:23:28.794] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:28.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:36:0) after 1m0s
[13:24:28.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:36:0)
[13:24:28.881] Timer tick, asking Buildbucket for the build status
[13:24:28.955] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:28.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:37:0) after 1m0s
[13:25:29.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:37:0)
[13:25:29.272] Timer tick, asking Buildbucket for the build status
[13:25:29.345] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:29.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:38:0) after 1m0s
[13:26:29.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:38:0)
[13:26:29.779] Timer tick, asking Buildbucket for the build status
[13:26:29.844] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:29.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:39:0) after 1m0s
[13:27:29.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:39:0)
[13:27:29.863] Timer tick, asking Buildbucket for the build status
[13:27:29.932] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:29.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:40:0) after 1m0s
[13:28:30.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:40:0)
[13:28:30.093] Timer tick, asking Buildbucket for the build status
[13:28:30.191] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:30.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:41:0) after 1m0s
[13:29:30.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:41:0)
[13:29:30.423] Timer tick, asking Buildbucket for the build status
[13:29:30.601] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:30.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:42:0) after 1m0s
[13:30:30.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:42:0)
[13:30:30.619] Timer tick, asking Buildbucket for the build status
[13:30:30.703] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:30.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:43:0) after 1m0s
[13:31:30.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:43:0)
[13:31:30.912] Timer tick, asking Buildbucket for the build status
[13:31:31.071] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:31.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:44:0) after 1m0s
[13:32:31.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:44:0)
[13:32:31.152] Timer tick, asking Buildbucket for the build status
[13:32:31.294] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:31.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:45:0) after 1m0s
[13:33:31.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:45:0)
[13:33:31.384] Timer tick, asking Buildbucket for the build status
[13:33:31.454] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:31.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:46:0) after 1m0s
[13:34:31.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:46:0)
[13:34:31.888] Timer tick, asking Buildbucket for the build status
[13:34:32.046] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:32.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:47:0) after 1m0s
[13:35:32.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:47:0)
[13:35:32.062] Timer tick, asking Buildbucket for the build status
[13:35:32.143] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:32.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:48:0) after 1m0s
[13:36:32.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:48:0)
[13:36:32.302] Timer tick, asking Buildbucket for the build status
[13:36:32.454] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:32.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:49:0) after 1m0s
[13:37:32.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:49:0)
[13:37:32.479] Timer tick, asking Buildbucket for the build status
[13:37:32.549] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:32.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:50:0) after 1m0s
[13:38:32.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:50:0)
[13:38:32.609] Timer tick, asking Buildbucket for the build status
[13:38:32.690] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:32.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:51:0) after 1m0s
[13:39:32.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:51:0)
[13:39:32.893] Timer tick, asking Buildbucket for the build status
[13:39:32.953] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:32.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:52:0) after 1m0s
[13:40:33.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:52:0)
[13:40:33.060] Timer tick, asking Buildbucket for the build status
[13:40:33.147] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:33.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:53:0) after 1m0s
[13:41:33.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:53:0)
[13:41:33.884] Timer tick, asking Buildbucket for the build status
[13:41:33.947] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:33.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:54:0) after 1m0s
[13:42:34.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:54:0)
[13:42:34.201] Timer tick, asking Buildbucket for the build status
[13:42:34.500] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:34.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:55:0) after 1m0s
[13:43:34.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:55:0)
[13:43:34.653] Timer tick, asking Buildbucket for the build status
[13:43:34.947] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:34.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:56:0) after 1m0s
[13:44:35.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:56:0)
[13:44:35.049] Timer tick, asking Buildbucket for the build status
[13:44:35.374] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:35.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:57:0) after 1m0s
[13:45:35.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:57:0)
[13:45:35.578] Timer tick, asking Buildbucket for the build status
[13:45:35.662] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:35.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:58:0) after 1m0s
[13:46:35.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:58:0)
[13:46:35.681] Timer tick, asking Buildbucket for the build status
[13:46:35.742] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:35.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:59:0) after 1m0s
[13:47:35.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:59:0)
[13:47:35.759] Timer tick, asking Buildbucket for the build status
[13:47:36.093] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:36.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:60:0) after 1m0s
[13:48:36.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:60:0)
[13:48:36.118] Timer tick, asking Buildbucket for the build status
[13:48:36.203] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:36.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:61:0) after 1m0s
[13:49:36.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:61:0)
[13:49:36.405] Timer tick, asking Buildbucket for the build status
[13:49:36.652] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:36.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:62:0) after 1m0s
[13:50:36.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:62:0)
[13:50:36.670] Timer tick, asking Buildbucket for the build status
[13:50:36.789] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:36.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:63:0) after 1m0s
[13:51:36.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:63:0)
[13:51:36.982] Timer tick, asking Buildbucket for the build status
[13:51:37.265] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:37.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:64:0) after 1m0s
[13:52:37.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:64:0)
[13:52:37.469] Timer tick, asking Buildbucket for the build status
[13:52:37.597] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:37.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:65:0) after 1m0s
[13:53:37.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:65:0)
[13:53:37.772] Timer tick, asking Buildbucket for the build status
[13:53:37.898] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:37.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:66:0) after 1m0s
[13:54:37.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:66:0)
[13:54:37.961] Timer tick, asking Buildbucket for the build status
[13:54:38.043] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:38.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:67:0) after 1m0s
[13:55:38.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:67:0)
[13:55:38.064] Timer tick, asking Buildbucket for the build status
[13:55:38.148] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:38.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:68:0) after 1m0s
[13:56:38.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:68:0)
[13:56:38.464] Timer tick, asking Buildbucket for the build status
[13:56:38.559] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:38.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:69:0) after 1m0s
[13:57:38.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:69:0)
[13:57:38.602] Timer tick, asking Buildbucket for the build status
[13:57:38.761] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:38.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:70:0) after 1m0s
[13:58:38.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:70:0)
[13:58:38.784] Timer tick, asking Buildbucket for the build status
[13:58:38.858] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:38.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:71:0) after 1m0s
[13:59:39.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:71:0)
[13:59:39.082] Timer tick, asking Buildbucket for the build status
[13:59:39.146] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:39.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:72:0) after 1m0s
[14:00:39.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:72:0)
[14:00:39.251] Timer tick, asking Buildbucket for the build status
[14:00:39.328] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:39.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:73:0) after 1m0s
[14:01:39.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:73:0)
[14:01:39.349] Timer tick, asking Buildbucket for the build status
[14:01:39.503] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:39.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:74:0) after 1m0s
[14:02:39.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:74:0)
[14:02:39.524] Timer tick, asking Buildbucket for the build status
[14:02:39.598] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:39.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:75:0) after 1m0s
[14:03:39.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:75:0)
[14:03:39.639] Timer tick, asking Buildbucket for the build status
[14:03:39.719] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:39.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:76:0) after 1m0s
[14:04:39.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:76:0)
[14:04:39.747] Timer tick, asking Buildbucket for the build status
[14:04:39.817] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:39.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:77:0) after 1m0s
[14:05:39.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:77:0)
[14:05:39.912] Timer tick, asking Buildbucket for the build status
[14:05:40.174] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:40.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:78:0) after 1m0s
[14:06:40.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:78:0)
[14:06:40.193] Timer tick, asking Buildbucket for the build status
[14:06:40.275] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:40.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:79:0) after 1m0s
[14:07:40.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:79:0)
[14:07:40.434] Timer tick, asking Buildbucket for the build status
[14:07:40.589] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:40.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:80:0) after 1m0s
[14:08:40.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:80:0)
[14:08:40.714] Timer tick, asking Buildbucket for the build status
[14:08:40.786] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:40.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:81:0) after 1m0s
[14:09:40.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:81:0)
[14:09:40.806] Timer tick, asking Buildbucket for the build status
[14:09:40.875] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:40.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:82:0) after 1m0s
[14:10:40.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:82:0)
[14:10:40.907] Timer tick, asking Buildbucket for the build status
[14:10:41.000] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:41.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:83:0) after 1m0s
[14:11:41.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:83:0)
[14:11:41.191] Timer tick, asking Buildbucket for the build status
[14:11:41.251] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:41.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:84:0) after 1m0s
[14:12:41.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:84:0)
[14:12:41.704] Timer tick, asking Buildbucket for the build status
[14:12:41.780] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:41.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:85:0) after 1m0s
[14:13:42.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:85:0)
[14:13:42.055] Timer tick, asking Buildbucket for the build status
[14:13:43.740] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:43.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:86:0) after 1m0s
[14:14:43.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:86:0)
[14:14:43.768] Timer tick, asking Buildbucket for the build status
[14:14:43.852] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:43.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:87:0) after 1m0s
[14:15:43.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:87:0)
[14:15:43.880] Timer tick, asking Buildbucket for the build status
[14:15:44.004] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:44.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:88:0) after 1m0s
[14:16:44.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:88:0)
[14:16:44.095] Timer tick, asking Buildbucket for the build status
[14:16:44.191] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:44.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:89:0) after 1m0s
[14:17:44.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:89:0)
[14:17:44.400] Timer tick, asking Buildbucket for the build status
[14:17:44.463] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:44.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:90:0) after 1m0s
[14:18:44.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:90:0)
[14:18:44.519] Timer tick, asking Buildbucket for the build status
[14:18:44.892] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:44.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:91:0) after 1m0s
[14:19:44.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:91:0)
[14:19:44.917] Timer tick, asking Buildbucket for the build status
[14:19:45.093] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:45.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:92:0) after 1m0s
[14:20:45.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:92:0)
[14:20:45.223] Timer tick, asking Buildbucket for the build status
[14:20:45.666] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:45.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:93:0) after 1m0s
[14:21:45.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:93:0)
[14:21:45.682] Timer tick, asking Buildbucket for the build status
[14:21:46.089] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:46.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:94:0) after 1m0s
[14:22:46.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:94:0)
[14:22:46.192] Timer tick, asking Buildbucket for the build status
[14:22:46.387] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:46.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:95:0) after 1m0s
[14:23:46.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:95:0)
[14:23:46.407] Timer tick, asking Buildbucket for the build status
[14:23:47.493] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:47.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:96:0) after 1m0s
[14:24:47.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:96:0)
[14:24:47.516] Timer tick, asking Buildbucket for the build status
[14:24:47.619] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:47.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:97:0) after 1m0s
[14:25:47.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:97:0)
[14:25:47.751] Timer tick, asking Buildbucket for the build status
[14:25:47.839] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:47.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:98:0) after 1m0s
[14:26:48.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:98:0)
[14:26:48.047] Timer tick, asking Buildbucket for the build status
[14:26:48.113] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:48.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:99:0) after 1m0s
[14:27:48.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:99:0)
[14:27:48.173] Timer tick, asking Buildbucket for the build status
[14:27:48.237] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:48.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:100:0) after 1m0s
[14:28:48.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:100:0)
[14:28:48.308] Timer tick, asking Buildbucket for the build status
[14:28:49.382] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:49.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:101:0) after 1m0s
[14:29:49.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:101:0)
[14:29:49.518] Timer tick, asking Buildbucket for the build status
[14:29:49.585] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:49.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:102:0) after 1m0s
[14:30:49.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:102:0)
[14:30:49.644] Timer tick, asking Buildbucket for the build status
[14:30:49.914] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:49.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:103:0) after 1m0s
[14:31:49.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:103:0)
[14:31:49.950] Timer tick, asking Buildbucket for the build status
[14:31:50.294] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:50.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:104:0) after 1m0s
[14:32:50.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:104:0)
[14:32:50.696] Timer tick, asking Buildbucket for the build status
[14:32:50.916] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:50.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:105:0) after 1m0s
[14:33:50.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:105:0)
[14:33:50.972] Timer tick, asking Buildbucket for the build status
[14:33:51.191] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:51.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:106:0) after 1m0s
[14:34:51.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:106:0)
[14:34:51.211] Timer tick, asking Buildbucket for the build status
[14:34:51.392] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:51.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:107:0) after 1m0s
[14:35:51.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:107:0)
[14:35:51.412] Timer tick, asking Buildbucket for the build status
[14:35:51.511] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:51.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:108:0) after 1m0s
[14:36:51.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:108:0)
[14:36:51.533] Timer tick, asking Buildbucket for the build status
[14:36:51.606] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:51.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:109:0) after 1m0s
[14:37:51.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:109:0)
[14:37:51.627] Timer tick, asking Buildbucket for the build status
[14:37:51.891] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:51.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:110:0) after 1m0s
[14:38:51.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:110:0)
[14:38:51.909] Timer tick, asking Buildbucket for the build status
[14:38:52.005] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:52.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:111:0) after 1m0s
[14:39:52.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:111:0)
[14:39:52.122] Timer tick, asking Buildbucket for the build status
[14:39:52.196] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:52.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:112:0) after 1m0s
[14:40:52.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:112:0)
[14:40:52.221] Timer tick, asking Buildbucket for the build status
[14:40:52.321] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:52.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:113:0) after 1m0s
[14:41:52.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:113:0)
[14:41:52.412] Timer tick, asking Buildbucket for the build status
[14:41:52.477] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:52.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:114:0) after 1m0s
[14:42:52.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:114:0)
[14:42:52.495] Timer tick, asking Buildbucket for the build status
[14:42:52.561] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:52.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:115:0) after 1m0s
[14:43:52.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:115:0)
[14:43:52.866] Timer tick, asking Buildbucket for the build status
[14:43:52.926] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:52.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:116:0) after 1m0s
[14:44:52.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:116:0)
[14:44:52.945] Timer tick, asking Buildbucket for the build status
[14:44:53.008] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:53.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:117:0) after 1m0s
[14:45:53.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:117:0)
[14:45:53.241] Timer tick, asking Buildbucket for the build status
[14:45:53.317] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:53.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:118:0) after 1m0s
[14:46:53.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:118:0)
[14:46:53.860] Timer tick, asking Buildbucket for the build status
[14:46:53.961] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:53.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:119:0) after 1m0s
[14:47:54.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:119:0)
[14:47:54.015] Timer tick, asking Buildbucket for the build status
[14:47:54.142] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:54.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:120:0) after 1m0s
[14:48:54.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:120:0)
[14:48:54.374] Timer tick, asking Buildbucket for the build status
[14:48:54.437] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:54.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:121:0) after 1m0s
[14:49:54.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:121:0)
[14:49:54.768] Timer tick, asking Buildbucket for the build status
[14:49:54.842] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:54.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:122:0) after 1m0s
[14:50:54.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:122:0)
[14:50:54.932] Timer tick, asking Buildbucket for the build status
[14:50:55.147] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:55.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:123:0) after 1m0s
[14:51:55.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:123:0)
[14:51:55.169] Timer tick, asking Buildbucket for the build status
[14:51:55.407] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:55.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:124:0) after 1m0s
[14:52:55.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:124:0)
[14:52:55.433] Timer tick, asking Buildbucket for the build status
[14:52:55.500] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:55.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:125:0) after 1m0s
[14:53:55.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:125:0)
[14:53:55.746] Timer tick, asking Buildbucket for the build status
[14:53:55.818] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:55.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:126:0) after 1m0s
[14:54:55.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:126:0)
[14:54:55.850] Timer tick, asking Buildbucket for the build status
[14:54:55.934] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:55.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:127:0) after 1m0s
[14:55:55.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:127:0)
[14:55:55.956] Timer tick, asking Buildbucket for the build status
[14:55:56.036] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:56.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:128:0) after 1m0s
[14:56:56.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:128:0)
[14:56:56.057] Timer tick, asking Buildbucket for the build status
[14:56:56.241] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:56.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:129:0) after 1m0s
[14:57:56.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:129:0)
[14:57:56.388] Timer tick, asking Buildbucket for the build status
[14:57:56.445] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:56.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:130:0) after 1m0s
[14:58:56.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:130:0)
[14:58:56.464] Timer tick, asking Buildbucket for the build status
[14:58:56.551] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:56.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:131:0) after 1m0s
[14:59:56.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:131:0)
[14:59:56.578] Timer tick, asking Buildbucket for the build status
[14:59:56.649] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:56.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:132:0) after 1m0s
[15:00:56.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:132:0)
[15:00:56.673] Timer tick, asking Buildbucket for the build status
[15:00:56.739] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:56.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:133:0) after 1m0s
[15:01:56.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:133:0)
[15:01:56.762] Timer tick, asking Buildbucket for the build status
[15:01:56.827] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:56.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:134:0) after 1m0s
[15:02:57.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:134:0)
[15:02:57.080] Timer tick, asking Buildbucket for the build status
[15:02:57.370] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:57.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:135:0) after 1m0s
[15:03:57.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:135:0)
[15:03:57.409] Timer tick, asking Buildbucket for the build status
[15:03:57.471] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:57.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:136:0) after 1m0s
[15:04:57.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:136:0)
[15:04:57.497] Timer tick, asking Buildbucket for the build status
[15:04:57.564] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:57.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:137:0) after 1m0s
[15:05:57.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:137:0)
[15:05:57.611] Timer tick, asking Buildbucket for the build status
[15:05:57.757] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:57.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:138:0) after 1m0s
[15:06:57.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:138:0)
[15:06:57.791] Timer tick, asking Buildbucket for the build status
[15:06:58.105] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:58.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:139:0) after 1m0s
[15:07:58.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:139:0)
[15:07:58.125] Timer tick, asking Buildbucket for the build status
[15:07:58.190] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:58.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:140:0) after 1m0s
[15:08:58.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:140:0)
[15:08:58.244] Timer tick, asking Buildbucket for the build status
[15:08:59.997] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:59.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:141:0) after 1m0s
[15:10:00.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:141:0)
[15:10:00.018] Timer tick, asking Buildbucket for the build status
[15:10:00.374] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:00.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:142:0) after 1m0s
[15:11:00.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:142:0)
[15:11:00.693] Timer tick, asking Buildbucket for the build status
[15:11:00.779] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:00.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:143:0) after 1m0s
[15:12:01.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:143:0)
[15:12:01.037] Timer tick, asking Buildbucket for the build status
[15:12:01.118] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:01.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:144:0) after 1m0s
[15:13:01.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:144:0)
[15:13:01.141] Timer tick, asking Buildbucket for the build status
[15:13:01.204] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:01.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:145:0) after 1m0s
[15:14:01.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:145:0)
[15:14:01.344] Timer tick, asking Buildbucket for the build status
[15:14:01.710] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:01.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:146:0) after 1m0s
[15:15:01.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:146:0)
[15:15:01.730] Timer tick, asking Buildbucket for the build status
[15:15:01.804] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:01.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:147:0) after 1m0s
[15:16:01.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:147:0)
[15:16:01.857] Timer tick, asking Buildbucket for the build status
[15:16:01.954] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:01.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:148:0) after 1m0s
[15:17:01.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:148:0)
[15:17:01.975] Timer tick, asking Buildbucket for the build status
[15:17:02.205] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:02.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:149:0) after 1m0s
[15:18:02.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:149:0)
[15:18:02.370] Timer tick, asking Buildbucket for the build status
[15:18:02.437] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:02.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:150:0) after 1m0s
[15:19:02.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:150:0)
[15:19:02.642] Timer tick, asking Buildbucket for the build status
[15:19:02.705] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:02.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:151:0) after 1m0s
[15:20:02.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:151:0)
[15:20:02.786] Timer tick, asking Buildbucket for the build status
[15:20:02.915] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:02.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:152:0) after 1m0s
[15:21:03.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:152:0)
[15:21:03.048] Timer tick, asking Buildbucket for the build status
[15:21:03.134] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:03.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:153:0) after 1m0s
[15:22:03.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:153:0)
[15:22:03.176] Timer tick, asking Buildbucket for the build status
[15:22:03.296] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:03.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:154:0) after 1m0s
[15:23:03.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:154:0)
[15:23:03.437] Timer tick, asking Buildbucket for the build status
[15:23:03.505] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:03.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:155:0) after 1m0s
[15:24:03.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:155:0)
[15:24:03.528] Timer tick, asking Buildbucket for the build status
[15:24:03.597] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:03.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:156:0) after 1m0s
[15:25:03.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:156:0)
[15:25:03.616] Timer tick, asking Buildbucket for the build status
[15:25:03.682] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:03.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:157:0) after 1m0s
[15:26:03.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:157:0)
[15:26:03.949] Timer tick, asking Buildbucket for the build status
[15:26:04.035] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:04.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:158:0) after 1m0s
[15:27:04.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:158:0)
[15:27:04.165] Timer tick, asking Buildbucket for the build status
[15:27:04.231] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:04.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:159:0) after 1m0s
[15:28:04.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:159:0)
[15:28:04.601] Timer tick, asking Buildbucket for the build status
[15:28:04.669] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:04.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:160:0) after 1m0s
[15:29:05.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:160:0)
[15:29:05.304] Timer tick, asking Buildbucket for the build status
[15:29:05.392] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:05.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:161:0) after 1m0s
[15:30:05.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:161:0)
[15:30:05.570] Timer tick, asking Buildbucket for the build status
[15:30:05.761] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:05.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:162:0) after 1m0s
[15:31:09.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:162:0)
[15:31:09.929] Timer tick, asking Buildbucket for the build status
[15:31:10.101] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:10.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:163:0) after 1m0s
[15:32:10.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:163:0)
[15:32:10.183] Timer tick, asking Buildbucket for the build status
[15:32:10.258] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:10.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:164:0) after 1m0s
[15:33:10.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:164:0)
[15:33:10.362] Timer tick, asking Buildbucket for the build status
[15:33:10.442] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:10.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:165:0) after 1m0s
[15:34:10.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:165:0)
[15:34:10.900] Timer tick, asking Buildbucket for the build status
[15:34:11.025] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:11.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:166:0) after 1m0s
[15:35:11.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:166:0)
[15:35:11.197] Timer tick, asking Buildbucket for the build status
[15:35:11.266] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:11.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:167:0) after 1m0s
[15:36:11.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:167:0)
[15:36:11.863] Timer tick, asking Buildbucket for the build status
[15:36:12.022] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:12.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:168:0) after 1m0s
[15:37:12.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:168:0)
[15:37:12.489] Timer tick, asking Buildbucket for the build status
[15:37:12.564] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:12.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:169:0) after 1m0s
[15:38:12.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:169:0)
[15:38:12.756] Timer tick, asking Buildbucket for the build status
[15:38:12.886] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:12.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:170:0) after 1m0s
[15:39:12.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:170:0)
[15:39:12.951] Timer tick, asking Buildbucket for the build status
[15:39:13.024] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:13.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:171:0) after 1m0s
[15:40:13.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:171:0)
[15:40:13.341] Timer tick, asking Buildbucket for the build status
[15:40:13.447] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:13.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:172:0) after 1m0s
[15:41:13.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:172:0)
[15:41:13.642] Timer tick, asking Buildbucket for the build status
[15:41:13.771] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:13.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:173:0) after 1m0s
[15:42:13.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:173:0)
[15:42:13.984] Timer tick, asking Buildbucket for the build status
[15:42:14.101] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:14.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:174:0) after 1m0s
[15:43:14.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:174:0)
[15:43:14.261] Timer tick, asking Buildbucket for the build status
[15:43:14.334] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:14.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:175:0) after 1m0s
[15:44:14.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:175:0)
[15:44:14.415] Timer tick, asking Buildbucket for the build status
[15:44:14.482] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:14.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:176:0) after 1m0s
[15:45:14.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:176:0)
[15:45:14.701] Timer tick, asking Buildbucket for the build status
[15:45:14.877] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:14.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:177:0) after 1m0s
[15:46:14.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:177:0)
[15:46:14.900] Timer tick, asking Buildbucket for the build status
[15:46:15.057] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:15.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:178:0) after 1m0s
[15:47:15.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:178:0)
[15:47:15.226] Timer tick, asking Buildbucket for the build status
[15:47:15.514] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:15.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:179:0) after 1m0s
[15:48:15.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:179:0)
[15:48:15.540] Timer tick, asking Buildbucket for the build status
[15:48:15.601] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:15.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:180:0) after 1m0s
[15:49:15.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:180:0)
[15:49:15.624] Timer tick, asking Buildbucket for the build status
[15:49:15.788] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:15.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:181:0) after 1m0s
[15:50:15.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:181:0)
[15:50:15.775] Timer tick, asking Buildbucket for the build status
[15:50:15.989] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:15.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:182:0) after 1m0s
[15:51:16.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:182:0)
[15:51:16.016] Timer tick, asking Buildbucket for the build status
[15:51:16.097] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:16.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:183:0) after 1m0s
[15:52:16.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:183:0)
[15:52:16.141] Timer tick, asking Buildbucket for the build status
[15:52:16.258] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:16.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:184:0) after 1m0s
[15:53:16.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:184:0)
[15:53:16.278] Timer tick, asking Buildbucket for the build status
[15:53:16.341] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:16.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:185:0) after 1m0s
[15:54:16.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:185:0)
[15:54:16.391] Timer tick, asking Buildbucket for the build status
[15:54:16.655] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:16.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:186:0) after 1m0s
[15:55:16.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:186:0)
[15:55:16.717] Timer tick, asking Buildbucket for the build status
[15:55:16.789] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:16.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:187:0) after 1m0s
[15:56:16.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:187:0)
[15:56:16.812] Timer tick, asking Buildbucket for the build status
[15:56:16.987] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:16.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:188:0) after 1m0s
[15:57:17.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:188:0)
[15:57:17.011] Timer tick, asking Buildbucket for the build status
[15:57:17.100] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:17.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:189:0) after 1m0s
[15:58:17.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:189:0)
[15:58:17.122] Timer tick, asking Buildbucket for the build status
[15:58:17.258] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:17.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:190:0) after 1m0s
[15:59:17.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:190:0)
[15:59:17.284] Timer tick, asking Buildbucket for the build status
[15:59:17.352] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:17.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:191:0) after 1m0s
[16:00:17.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:191:0)
[16:00:17.523] Timer tick, asking Buildbucket for the build status
[16:00:17.591] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:17.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:192:0) after 1m0s
[16:01:20.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:192:0)
[16:01:20.052] Timer tick, asking Buildbucket for the build status
[16:01:20.143] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:20.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:193:0) after 1m0s
[16:02:20.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:193:0)
[16:02:20.263] Timer tick, asking Buildbucket for the build status
[16:02:20.355] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:20.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:194:0) after 1m0s
[16:03:20.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:194:0)
[16:03:20.438] Timer tick, asking Buildbucket for the build status
[16:03:20.595] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:20.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:195:0) after 1m0s
[16:04:20.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:195:0)
[16:04:20.670] Timer tick, asking Buildbucket for the build status
[16:04:20.795] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:20.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:196:0) after 1m0s
[16:05:20.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:196:0)
[16:05:20.819] Timer tick, asking Buildbucket for the build status
[16:05:21.373] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:21.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:197:0) after 1m0s
[16:06:21.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:197:0)
[16:06:21.500] Timer tick, asking Buildbucket for the build status
[16:06:21.784] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:21.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:198:0) after 1m0s
[16:07:21.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:198:0)
[16:07:21.809] Timer tick, asking Buildbucket for the build status
[16:07:21.872] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:21.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:199:0) after 1m0s
[16:08:21.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:199:0)
[16:08:21.897] Timer tick, asking Buildbucket for the build status
[16:08:22.030] Build 8852872579469545056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:22.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018302641489361856:200:0) after 1m0s
[16:09:05.406] Received PubSub notification, asking Buildbucket for the build status
[16:09:05.577] Build 8852872579469545056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:09:05.577] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1615651745103892",
    "created_by": "project:infra",
    "created_ts": "1615639816750698",
    "id": "8852872579469545056",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4445.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"af7b352c-766d-4e62-8893-ac9c50545a15\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4445.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-x0d1\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1615639874521159",
    "status": "COMPLETED",
    "status_changed_ts": "1615639874998395",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9018302641489361856",
      "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/8852872579469545056/+/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:524474b4ba60a910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1615651745104595",
    "url": "https://ci.chromium.org/b/8852872579469545056",
    "utcnow_ts": "1615651745566007"
  }
}
[16:09:05.578] Invocation finished in 3h18m50.137706268s with status SUCCEEDED