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

• parent_buildername:publish_tarball
• user_agent:recipe

[03:27:16.325] New invocation is queued and will start shortly
[03:27:16.325] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[03:27:17.614] Starting the invocation (attempt 1)
[03:27:17.659] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:27:17.659] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:27:17.659] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9048416254324407888",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"84.0.4114.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"b53a2ad1-2a48-40e9-a1b0-1eed82a4de68\",\"title\":\"publish_tarball/0\"}]},\"version\":\"84.0.4114.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:9048416254324407888",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[03:27:18.502] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1586921237883884",
    "id": "8882986192023477536",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4114.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b53a2ad1-2a48-40e9-a1b0-1eed82a4de68\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4114.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1586921238270635",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9048416254324407888",
      "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/8882986192023477536/+/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": "1586921238270803",
    "url": "https://ci.chromium.org/b/8882986192023477536",
    "utcnow_ts": "1586921238474994"
  }
}
[03:27:18.502] Task URL: https://ci.chromium.org/b/8882986192023477536
[03:27:18.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:2:0) after 1m0s
[03:28:18.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:2:0)
[03:28:18.589] Timer tick, asking Buildbucket for the build status
[03:28:18.722] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:18.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:3:0) after 1m0s
[03:29:18.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:3:0)
[03:29:18.764] Timer tick, asking Buildbucket for the build status
[03:29:19.265] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:19.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:4:0) after 1m0s
[03:30:19.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:4:0)
[03:30:19.297] Timer tick, asking Buildbucket for the build status
[03:30:19.830] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:19.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:5:0) after 1m0s
[03:31:19.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:5:0)
[03:31:19.899] Timer tick, asking Buildbucket for the build status
[03:31:20.063] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:20.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:6:0) after 1m0s
[03:32:20.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:6:0)
[03:32:20.105] Timer tick, asking Buildbucket for the build status
[03:32:20.241] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:20.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:7:0) after 1m0s
[03:33:20.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:7:0)
[03:33:20.283] Timer tick, asking Buildbucket for the build status
[03:33:20.908] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:20.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:8:0) after 1m0s
[03:34:21.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:8:0)
[03:34:21.013] Timer tick, asking Buildbucket for the build status
[03:34:21.159] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:21.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:9:0) after 1m0s
[03:35:21.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:9:0)
[03:35:21.194] Timer tick, asking Buildbucket for the build status
[03:35:22.011] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:22.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:10:0) after 1m0s
[03:36:22.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:10:0)
[03:36:22.033] Timer tick, asking Buildbucket for the build status
[03:36:22.388] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:22.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:11:0) after 1m0s
[03:37:22.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:11:0)
[03:37:22.401] Timer tick, asking Buildbucket for the build status
[03:37:22.594] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:22.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:12:0) after 1m0s
[03:38:22.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:12:0)
[03:38:22.643] Timer tick, asking Buildbucket for the build status
[03:38:22.995] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:22.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:13:0) after 1m0s
[03:39:22.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:13:0)
[03:39:22.979] Timer tick, asking Buildbucket for the build status
[03:39:23.201] Build 8882986192023477536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:23.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:14:0) after 1m0s
[03:40:02.284] Received PubSub notification, asking Buildbucket for the build status
[03:40:02.415] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:23.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:14:0)
[03:40:23.234] Timer tick, asking Buildbucket for the build status
[03:40:24.688] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:24.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:16:0) after 1m0s
[03:41:24.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:16:0)
[03:41:24.742] Timer tick, asking Buildbucket for the build status
[03:41:26.810] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:26.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:17:0) after 1m0s
[03:42:26.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:17:0)
[03:42:26.900] Timer tick, asking Buildbucket for the build status
[03:42:27.225] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:27.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:18:0) after 1m0s
[03:43:27.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:18:0)
[03:43:27.278] Timer tick, asking Buildbucket for the build status
[03:43:27.415] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:27.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:19:0) after 1m0s
[03:44:27.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:19:0)
[03:44:27.460] Timer tick, asking Buildbucket for the build status
[03:44:27.836] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:27.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:20:0) after 1m0s
[03:45:27.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:20:0)
[03:45:27.864] Timer tick, asking Buildbucket for the build status
[03:45:28.267] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:28.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:21:0) after 1m0s
[03:46:28.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:21:0)
[03:46:28.261] Timer tick, asking Buildbucket for the build status
[03:46:28.392] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:28.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:22:0) after 1m0s
[03:47:28.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:22:0)
[03:47:28.436] Timer tick, asking Buildbucket for the build status
[03:47:28.882] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:28.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:23:0) after 1m0s
[03:48:28.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:23:0)
[03:48:28.941] Timer tick, asking Buildbucket for the build status
[03:48:29.124] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:29.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:24:0) after 1m0s
[03:49:29.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:24:0)
[03:49:29.156] Timer tick, asking Buildbucket for the build status
[03:49:29.467] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:29.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:25:0) after 1m0s
[03:50:29.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:25:0)
[03:50:29.536] Timer tick, asking Buildbucket for the build status
[03:50:29.733] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:29.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:26:0) after 1m0s
[03:51:29.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:26:0)
[03:51:29.762] Timer tick, asking Buildbucket for the build status
[03:51:29.957] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:29.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:27:0) after 1m0s
[03:52:30.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:27:0)
[03:52:30.011] Timer tick, asking Buildbucket for the build status
[03:52:30.140] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:30.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:28:0) after 1m0s
[03:53:30.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:28:0)
[03:53:30.260] Timer tick, asking Buildbucket for the build status
[03:53:30.403] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:30.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:29:0) after 1m0s
[03:54:30.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:29:0)
[03:54:30.417] Timer tick, asking Buildbucket for the build status
[03:54:30.562] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:30.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:30:0) after 1m0s
[03:55:30.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:30:0)
[03:55:30.608] Timer tick, asking Buildbucket for the build status
[03:55:31.564] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:31.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:31:0) after 1m0s
[03:56:31.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:31:0)
[03:56:31.552] Timer tick, asking Buildbucket for the build status
[03:56:31.924] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:31.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:32:0) after 1m0s
[03:57:31.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:32:0)
[03:57:31.954] Timer tick, asking Buildbucket for the build status
[03:57:32.138] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:32.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:33:0) after 1m0s
[03:58:32.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:33:0)
[03:58:32.154] Timer tick, asking Buildbucket for the build status
[03:58:32.389] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:32.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:34:0) after 1m0s
[03:59:32.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:34:0)
[03:59:32.416] Timer tick, asking Buildbucket for the build status
[03:59:32.862] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:32.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:35:0) after 1m0s
[04:00:32.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:35:0)
[04:00:32.946] Timer tick, asking Buildbucket for the build status
[04:00:33.616] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:33.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:36:0) after 1m0s
[04:01:33.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:36:0)
[04:01:33.639] Timer tick, asking Buildbucket for the build status
[04:01:34.288] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:34.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:37:0) after 1m0s
[04:02:34.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:37:0)
[04:02:34.420] Timer tick, asking Buildbucket for the build status
[04:02:34.657] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:34.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:38:0) after 1m0s
[04:03:34.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:38:0)
[04:03:34.688] Timer tick, asking Buildbucket for the build status
[04:03:37.357] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:37.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:39:0) after 1m0s
[04:04:37.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:39:0)
[04:04:37.409] Timer tick, asking Buildbucket for the build status
[04:04:37.577] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:37.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:40:0) after 1m0s
[04:05:37.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:40:0)
[04:05:37.602] Timer tick, asking Buildbucket for the build status
[04:05:37.861] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:37.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:41:0) after 1m0s
[04:06:37.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:41:0)
[04:06:37.953] Timer tick, asking Buildbucket for the build status
[04:06:38.171] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:38.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:42:0) after 1m0s
[04:07:38.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:42:0)
[04:07:38.318] Timer tick, asking Buildbucket for the build status
[04:07:38.499] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:38.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:43:0) after 1m0s
[04:08:38.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:43:0)
[04:08:38.559] Timer tick, asking Buildbucket for the build status
[04:08:38.704] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:38.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:44:0) after 1m0s
[04:09:38.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:44:0)
[04:09:38.830] Timer tick, asking Buildbucket for the build status
[04:09:38.955] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:38.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:45:0) after 1m0s
[04:10:39.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:45:0)
[04:10:39.056] Timer tick, asking Buildbucket for the build status
[04:10:39.868] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:39.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:46:0) after 1m0s
[04:11:39.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:46:0)
[04:11:39.963] Timer tick, asking Buildbucket for the build status
[04:11:40.120] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:40.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:47:0) after 1m0s
[04:12:40.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:47:0)
[04:12:40.146] Timer tick, asking Buildbucket for the build status
[04:12:40.382] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:40.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:48:0) after 1m0s
[04:13:40.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:48:0)
[04:13:40.429] Timer tick, asking Buildbucket for the build status
[04:13:40.729] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:40.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:49:0) after 1m0s
[04:14:40.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:49:0)
[04:14:40.775] Timer tick, asking Buildbucket for the build status
[04:14:40.901] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:40.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:50:0) after 1m0s
[04:15:40.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:50:0)
[04:15:40.926] Timer tick, asking Buildbucket for the build status
[04:15:41.329] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:41.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:51:0) after 1m0s
[04:16:41.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:51:0)
[04:16:41.446] Timer tick, asking Buildbucket for the build status
[04:16:41.902] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:41.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:52:0) after 1m0s
[04:17:41.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:52:0)
[04:17:41.969] Timer tick, asking Buildbucket for the build status
[04:17:42.274] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:42.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:53:0) after 1m0s
[04:18:42.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:53:0)
[04:18:42.426] Timer tick, asking Buildbucket for the build status
[04:18:43.137] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:43.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:54:0) after 1m0s
[04:19:43.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:54:0)
[04:19:43.158] Timer tick, asking Buildbucket for the build status
[04:19:43.344] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:43.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:55:0) after 1m0s
[04:20:43.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:55:0)
[04:20:43.515] Timer tick, asking Buildbucket for the build status
[04:20:44.068] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:44.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:56:0) after 1m0s
[04:21:44.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:56:0)
[04:21:44.147] Timer tick, asking Buildbucket for the build status
[04:21:44.322] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:44.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:57:0) after 1m0s
[04:22:44.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:57:0)
[04:22:44.406] Timer tick, asking Buildbucket for the build status
[04:22:44.602] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:44.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:58:0) after 1m0s
[04:23:44.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:58:0)
[04:23:44.778] Timer tick, asking Buildbucket for the build status
[04:23:44.899] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:44.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:59:0) after 1m0s
[04:24:44.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:59:0)
[04:24:44.960] Timer tick, asking Buildbucket for the build status
[04:24:45.138] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:45.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:60:0) after 1m0s
[04:25:45.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:60:0)
[04:25:45.176] Timer tick, asking Buildbucket for the build status
[04:25:45.464] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:45.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:61:0) after 1m0s
[04:26:45.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:61:0)
[04:26:45.492] Timer tick, asking Buildbucket for the build status
[04:26:45.743] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:45.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:62:0) after 1m0s
[04:27:45.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:62:0)
[04:27:45.842] Timer tick, asking Buildbucket for the build status
[04:27:46.097] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:46.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:63:0) after 1m0s
[04:28:46.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:63:0)
[04:28:46.127] Timer tick, asking Buildbucket for the build status
[04:28:46.285] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:46.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:64:0) after 1m0s
[04:29:46.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:64:0)
[04:29:46.312] Timer tick, asking Buildbucket for the build status
[04:29:46.893] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:46.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:65:0) after 1m0s
[04:30:46.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:65:0)
[04:30:46.936] Timer tick, asking Buildbucket for the build status
[04:30:48.892] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:48.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:66:0) after 1m0s
[04:31:48.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:66:0)
[04:31:48.949] Timer tick, asking Buildbucket for the build status
[04:31:49.172] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:49.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:67:0) after 1m0s
[04:32:49.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:67:0)
[04:32:49.166] Timer tick, asking Buildbucket for the build status
[04:32:49.424] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:49.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:68:0) after 1m0s
[04:33:49.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:68:0)
[04:33:49.496] Timer tick, asking Buildbucket for the build status
[04:33:49.638] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:49.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:69:0) after 1m0s
[04:34:49.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:69:0)
[04:34:49.741] Timer tick, asking Buildbucket for the build status
[04:34:49.923] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:49.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:70:0) after 1m0s
[04:35:50.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:70:0)
[04:35:50.015] Timer tick, asking Buildbucket for the build status
[04:35:50.523] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:50.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:71:0) after 1m0s
[04:36:50.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:71:0)
[04:36:50.664] Timer tick, asking Buildbucket for the build status
[04:36:50.885] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:50.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:72:0) after 1m0s
[04:37:50.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:72:0)
[04:37:50.992] Timer tick, asking Buildbucket for the build status
[04:37:51.177] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:51.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:73:0) after 1m0s
[04:38:51.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:73:0)
[04:38:51.292] Timer tick, asking Buildbucket for the build status
[04:38:51.437] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:51.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:74:0) after 1m0s
[04:39:51.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:74:0)
[04:39:51.494] Timer tick, asking Buildbucket for the build status
[04:39:51.677] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:51.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:75:0) after 1m0s
[04:40:51.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:75:0)
[04:40:51.709] Timer tick, asking Buildbucket for the build status
[04:40:51.864] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:51.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:76:0) after 1m0s
[04:41:51.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:76:0)
[04:41:51.903] Timer tick, asking Buildbucket for the build status
[04:41:52.040] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:52.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:77:0) after 1m0s
[04:42:52.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:77:0)
[04:42:52.169] Timer tick, asking Buildbucket for the build status
[04:42:52.335] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:52.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:78:0) after 1m0s
[04:43:52.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:78:0)
[04:43:52.466] Timer tick, asking Buildbucket for the build status
[04:43:52.591] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:52.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:79:0) after 1m0s
[04:44:52.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:79:0)
[04:44:52.648] Timer tick, asking Buildbucket for the build status
[04:44:52.815] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:52.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:80:0) after 1m0s
[04:45:52.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:80:0)
[04:45:52.857] Timer tick, asking Buildbucket for the build status
[04:45:53.163] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:53.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:81:0) after 1m0s
[04:46:53.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:81:0)
[04:46:53.386] Timer tick, asking Buildbucket for the build status
[04:46:53.549] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:53.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:82:0) after 1m0s
[04:47:53.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:82:0)
[04:47:53.630] Timer tick, asking Buildbucket for the build status
[04:47:53.781] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:53.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:83:0) after 1m0s
[04:48:53.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:83:0)
[04:48:53.956] Timer tick, asking Buildbucket for the build status
[04:48:54.336] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:54.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:84:0) after 1m0s
[04:49:54.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:84:0)
[04:49:54.374] Timer tick, asking Buildbucket for the build status
[04:49:54.646] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:54.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:85:0) after 1m0s
[04:50:54.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:85:0)
[04:50:54.797] Timer tick, asking Buildbucket for the build status
[04:50:55.051] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:55.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:86:0) after 1m0s
[04:51:55.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:86:0)
[04:51:55.133] Timer tick, asking Buildbucket for the build status
[04:51:55.248] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:55.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:87:0) after 1m0s
[04:52:55.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:87:0)
[04:52:55.291] Timer tick, asking Buildbucket for the build status
[04:52:55.481] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:55.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:88:0) after 1m0s
[04:53:55.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:88:0)
[04:53:55.533] Timer tick, asking Buildbucket for the build status
[04:53:55.696] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:55.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:89:0) after 1m0s
[04:54:55.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:89:0)
[04:54:55.765] Timer tick, asking Buildbucket for the build status
[04:54:55.915] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:55.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:90:0) after 1m0s
[04:55:55.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:90:0)
[04:55:55.963] Timer tick, asking Buildbucket for the build status
[04:55:56.960] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:56.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:91:0) after 1m0s
[04:56:57.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:91:0)
[04:56:57.020] Timer tick, asking Buildbucket for the build status
[04:56:57.155] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:57.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:92:0) after 1m0s
[04:57:57.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:92:0)
[04:57:57.205] Timer tick, asking Buildbucket for the build status
[04:57:57.330] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:57.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:93:0) after 1m0s
[04:58:57.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:93:0)
[04:58:57.353] Timer tick, asking Buildbucket for the build status
[04:58:57.682] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:57.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:94:0) after 1m0s
[04:59:57.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:94:0)
[04:59:57.753] Timer tick, asking Buildbucket for the build status
[04:59:58.155] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:58.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:95:0) after 1m0s
[05:00:58.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:95:0)
[05:00:58.280] Timer tick, asking Buildbucket for the build status
[05:00:59.803] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:59.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:96:0) after 1m0s
[05:01:59.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:96:0)
[05:01:59.792] Timer tick, asking Buildbucket for the build status
[05:01:59.944] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:59.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:97:0) after 1m0s
[05:02:59.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:97:0)
[05:02:59.973] Timer tick, asking Buildbucket for the build status
[05:03:00.132] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:00.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:98:0) after 1m0s
[05:04:00.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:98:0)
[05:04:00.188] Timer tick, asking Buildbucket for the build status
[05:04:00.396] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:00.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:99:0) after 1m0s
[05:05:00.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:99:0)
[05:05:00.765] Timer tick, asking Buildbucket for the build status
[05:05:01.075] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:01.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:100:0) after 1m0s
[05:06:01.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:100:0)
[05:06:01.089] Timer tick, asking Buildbucket for the build status
[05:06:01.362] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:01.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:101:0) after 1m0s
[05:07:01.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:101:0)
[05:07:01.423] Timer tick, asking Buildbucket for the build status
[05:07:01.703] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:01.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:102:0) after 1m0s
[05:08:01.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:102:0)
[05:08:01.686] Timer tick, asking Buildbucket for the build status
[05:08:01.811] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:01.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:103:0) after 1m0s
[05:09:01.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:103:0)
[05:09:01.878] Timer tick, asking Buildbucket for the build status
[05:09:02.073] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:02.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:104:0) after 1m0s
[05:10:02.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:104:0)
[05:10:02.105] Timer tick, asking Buildbucket for the build status
[05:10:02.326] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:02.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:105:0) after 1m0s
[05:11:02.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:105:0)
[05:11:02.387] Timer tick, asking Buildbucket for the build status
[05:11:02.618] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:02.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:106:0) after 1m0s
[05:12:02.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:106:0)
[05:12:02.651] Timer tick, asking Buildbucket for the build status
[05:12:02.794] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:02.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:107:0) after 1m0s
[05:13:02.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:107:0)
[05:13:02.859] Timer tick, asking Buildbucket for the build status
[05:13:03.294] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:03.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:108:0) after 1m0s
[05:14:03.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:108:0)
[05:14:03.383] Timer tick, asking Buildbucket for the build status
[05:14:03.502] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:03.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:109:0) after 1m0s
[05:15:03.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:109:0)
[05:15:03.532] Timer tick, asking Buildbucket for the build status
[05:15:03.684] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:03.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:110:0) after 1m0s
[05:16:03.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:110:0)
[05:16:03.832] Timer tick, asking Buildbucket for the build status
[05:16:04.269] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:04.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:111:0) after 1m0s
[05:17:04.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:111:0)
[05:17:04.427] Timer tick, asking Buildbucket for the build status
[05:17:04.662] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:04.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:112:0) after 1m0s
[05:18:04.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:112:0)
[05:18:04.700] Timer tick, asking Buildbucket for the build status
[05:18:04.839] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:04.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:113:0) after 1m0s
[05:19:04.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:113:0)
[05:19:04.883] Timer tick, asking Buildbucket for the build status
[05:19:04.998] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:04.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:114:0) after 1m0s
[05:20:05.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:114:0)
[05:20:05.024] Timer tick, asking Buildbucket for the build status
[05:20:05.152] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:05.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:115:0) after 1m0s
[05:21:05.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:115:0)
[05:21:05.345] Timer tick, asking Buildbucket for the build status
[05:21:05.472] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:05.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:116:0) after 1m0s
[05:22:05.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:116:0)
[05:22:05.755] Timer tick, asking Buildbucket for the build status
[05:22:05.956] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:05.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:117:0) after 1m0s
[05:23:06.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:117:0)
[05:23:06.101] Timer tick, asking Buildbucket for the build status
[05:23:06.220] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:06.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:118:0) after 1m0s
[05:24:06.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:118:0)
[05:24:06.322] Timer tick, asking Buildbucket for the build status
[05:24:06.458] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:06.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:119:0) after 1m0s
[05:25:06.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:119:0)
[05:25:06.607] Timer tick, asking Buildbucket for the build status
[05:25:06.915] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:06.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:120:0) after 1m0s
[05:26:07.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:120:0)
[05:26:07.008] Timer tick, asking Buildbucket for the build status
[05:26:07.505] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:07.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:121:0) after 1m0s
[05:27:07.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:121:0)
[05:27:07.527] Timer tick, asking Buildbucket for the build status
[05:27:07.724] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:07.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:122:0) after 1m0s
[05:28:07.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:122:0)
[05:28:07.753] Timer tick, asking Buildbucket for the build status
[05:28:08.371] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:08.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:123:0) after 1m0s
[05:29:08.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:123:0)
[05:29:08.440] Timer tick, asking Buildbucket for the build status
[05:29:08.748] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:08.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:124:0) after 1m0s
[05:30:08.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:124:0)
[05:30:08.807] Timer tick, asking Buildbucket for the build status
[05:30:09.153] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:09.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:125:0) after 1m0s
[05:31:09.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:125:0)
[05:31:09.176] Timer tick, asking Buildbucket for the build status
[05:31:09.323] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:09.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:126:0) after 1m0s
[05:32:09.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:126:0)
[05:32:09.388] Timer tick, asking Buildbucket for the build status
[05:32:09.609] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:09.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:127:0) after 1m0s
[05:33:09.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:127:0)
[05:33:09.683] Timer tick, asking Buildbucket for the build status
[05:33:09.974] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:09.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:128:0) after 1m0s
[05:34:10.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:128:0)
[05:34:10.005] Timer tick, asking Buildbucket for the build status
[05:34:10.172] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:10.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:129:0) after 1m0s
[05:35:10.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:129:0)
[05:35:10.197] Timer tick, asking Buildbucket for the build status
[05:35:10.509] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:10.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:130:0) after 1m0s
[05:36:10.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:130:0)
[05:36:10.611] Timer tick, asking Buildbucket for the build status
[05:36:10.885] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:10.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:131:0) after 1m0s
[05:37:10.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:131:0)
[05:37:10.933] Timer tick, asking Buildbucket for the build status
[05:37:11.067] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:11.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:132:0) after 1m0s
[05:38:11.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:132:0)
[05:38:11.112] Timer tick, asking Buildbucket for the build status
[05:38:11.914] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:11.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:133:0) after 1m0s
[05:39:11.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:133:0)
[05:39:11.930] Timer tick, asking Buildbucket for the build status
[05:39:12.178] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:12.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:134:0) after 1m0s
[05:40:12.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:134:0)
[05:40:12.343] Timer tick, asking Buildbucket for the build status
[05:40:12.787] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:12.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:135:0) after 1m0s
[05:41:12.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:135:0)
[05:41:12.988] Timer tick, asking Buildbucket for the build status
[05:41:13.145] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:13.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:136:0) after 1m0s
[05:42:13.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:136:0)
[05:42:13.215] Timer tick, asking Buildbucket for the build status
[05:42:13.752] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:13.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:137:0) after 1m0s
[05:43:13.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:137:0)
[05:43:13.803] Timer tick, asking Buildbucket for the build status
[05:43:14.127] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:14.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:138:0) after 1m0s
[05:44:14.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:138:0)
[05:44:14.227] Timer tick, asking Buildbucket for the build status
[05:44:14.493] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:14.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:139:0) after 1m0s
[05:45:14.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:139:0)
[05:45:14.595] Timer tick, asking Buildbucket for the build status
[05:45:14.914] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:14.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:140:0) after 1m0s
[05:46:14.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:140:0)
[05:46:14.949] Timer tick, asking Buildbucket for the build status
[05:46:15.188] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:15.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:141:0) after 1m0s
[05:47:15.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:141:0)
[05:47:15.355] Timer tick, asking Buildbucket for the build status
[05:47:15.875] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:15.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:142:0) after 1m0s
[05:48:16.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:142:0)
[05:48:16.294] Timer tick, asking Buildbucket for the build status
[05:48:16.465] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:16.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:143:0) after 1m0s
[05:49:16.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:143:0)
[05:49:16.698] Timer tick, asking Buildbucket for the build status
[05:49:17.106] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:17.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:144:0) after 1m0s
[05:50:17.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:144:0)
[05:50:17.280] Timer tick, asking Buildbucket for the build status
[05:50:17.446] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:17.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:145:0) after 1m0s
[05:51:17.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:145:0)
[05:51:17.454] Timer tick, asking Buildbucket for the build status
[05:51:17.641] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:17.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:146:0) after 1m0s
[05:52:17.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:146:0)
[05:52:17.717] Timer tick, asking Buildbucket for the build status
[05:52:17.927] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:17.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:147:0) after 1m0s
[05:53:18.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:147:0)
[05:53:18.030] Timer tick, asking Buildbucket for the build status
[05:53:18.352] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:18.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:148:0) after 1m0s
[05:54:18.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:148:0)
[05:54:18.594] Timer tick, asking Buildbucket for the build status
[05:54:18.742] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:18.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:149:0) after 1m0s
[05:55:18.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:149:0)
[05:55:18.953] Timer tick, asking Buildbucket for the build status
[05:55:19.186] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:19.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:150:0) after 1m0s
[05:56:19.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:150:0)
[05:56:19.332] Timer tick, asking Buildbucket for the build status
[05:56:19.577] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:19.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:151:0) after 1m0s
[05:57:19.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:151:0)
[05:57:19.691] Timer tick, asking Buildbucket for the build status
[05:57:20.361] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:20.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:152:0) after 1m0s
[05:58:20.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:152:0)
[05:58:20.491] Timer tick, asking Buildbucket for the build status
[05:58:21.126] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:21.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:153:0) after 1m0s
[05:59:21.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:153:0)
[05:59:21.157] Timer tick, asking Buildbucket for the build status
[05:59:21.367] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:21.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:154:0) after 1m0s
[06:00:21.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:154:0)
[06:00:21.449] Timer tick, asking Buildbucket for the build status
[06:00:23.109] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:23.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:155:0) after 1m0s
[06:01:23.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:155:0)
[06:01:23.136] Timer tick, asking Buildbucket for the build status
[06:01:24.342] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:24.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:156:0) after 1m0s
[06:02:24.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:156:0)
[06:02:24.499] Timer tick, asking Buildbucket for the build status
[06:02:24.771] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:24.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:157:0) after 1m0s
[06:03:25.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:157:0)
[06:03:25.921] Timer tick, asking Buildbucket for the build status
[06:03:26.253] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:26.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:158:0) after 1m0s
[06:04:26.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:158:0)
[06:04:26.846] Timer tick, asking Buildbucket for the build status
[06:04:27.020] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:27.020] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:159:0) after 1m0s
[06:05:27.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:159:0)
[06:05:27.422] Timer tick, asking Buildbucket for the build status
[06:05:28.573] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:28.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:160:0) after 1m0s
[06:06:28.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:160:0)
[06:06:28.603] Timer tick, asking Buildbucket for the build status
[06:06:28.965] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:28.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:161:0) after 1m0s
[06:07:29.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:161:0)
[06:07:29.072] Timer tick, asking Buildbucket for the build status
[06:07:29.322] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:29.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:162:0) after 1m0s
[06:08:29.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:162:0)
[06:08:29.333] Timer tick, asking Buildbucket for the build status
[06:08:30.277] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:30.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:163:0) after 1m0s
[06:09:30.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:163:0)
[06:09:30.310] Timer tick, asking Buildbucket for the build status
[06:09:30.675] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:30.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:164:0) after 1m0s
[06:10:30.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:164:0)
[06:10:30.767] Timer tick, asking Buildbucket for the build status
[06:10:30.921] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:30.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:165:0) after 1m0s
[06:11:30.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:165:0)
[06:11:30.966] Timer tick, asking Buildbucket for the build status
[06:11:31.270] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:31.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:166:0) after 1m0s
[06:12:31.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:166:0)
[06:12:31.377] Timer tick, asking Buildbucket for the build status
[06:12:33.704] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:33.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:167:0) after 1m0s
[06:13:33.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:167:0)
[06:13:33.751] Timer tick, asking Buildbucket for the build status
[06:13:34.095] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:34.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:168:0) after 1m0s
[06:14:34.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:168:0)
[06:14:34.121] Timer tick, asking Buildbucket for the build status
[06:14:35.195] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:35.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:169:0) after 1m0s
[06:15:35.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:169:0)
[06:15:35.298] Timer tick, asking Buildbucket for the build status
[06:15:36.297] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:36.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:170:0) after 1m0s
[06:16:36.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:170:0)
[06:16:36.500] Timer tick, asking Buildbucket for the build status
[06:16:36.791] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:36.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:171:0) after 1m0s
[06:17:36.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:171:0)
[06:17:36.891] Timer tick, asking Buildbucket for the build status
[06:17:38.482] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:38.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:172:0) after 1m0s
[06:18:38.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:172:0)
[06:18:38.492] Timer tick, asking Buildbucket for the build status
[06:18:38.874] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:38.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:173:0) after 1m0s
[06:19:38.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:173:0)
[06:19:38.936] Timer tick, asking Buildbucket for the build status
[06:19:39.067] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:39.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:174:0) after 1m0s
[06:20:39.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:174:0)
[06:20:39.217] Timer tick, asking Buildbucket for the build status
[06:20:39.805] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:39.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:175:0) after 1m0s
[06:21:39.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:175:0)
[06:21:39.866] Timer tick, asking Buildbucket for the build status
[06:21:41.159] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:41.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:176:0) after 1m0s
[06:22:41.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:176:0)
[06:22:41.230] Timer tick, asking Buildbucket for the build status
[06:22:42.803] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:42.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:177:0) after 1m0s
[06:23:42.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:177:0)
[06:23:42.837] Timer tick, asking Buildbucket for the build status
[06:23:42.944] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:42.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:178:0) after 1m0s
[06:24:43.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:178:0)
[06:24:43.066] Timer tick, asking Buildbucket for the build status
[06:24:43.349] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:43.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:179:0) after 1m0s
[06:25:43.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:179:0)
[06:25:43.392] Timer tick, asking Buildbucket for the build status
[06:25:43.560] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:43.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:180:0) after 1m0s
[06:26:43.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:180:0)
[06:26:43.591] Timer tick, asking Buildbucket for the build status
[06:26:44.492] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:44.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:181:0) after 1m0s
[06:27:44.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:181:0)
[06:27:44.571] Timer tick, asking Buildbucket for the build status
[06:27:44.960] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:44.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:182:0) after 1m0s
[06:28:45.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:182:0)
[06:28:45.004] Timer tick, asking Buildbucket for the build status
[06:28:46.082] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:46.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:183:0) after 1m0s
[06:29:46.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:183:0)
[06:29:46.132] Timer tick, asking Buildbucket for the build status
[06:29:46.511] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:46.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:184:0) after 1m0s
[06:30:46.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:184:0)
[06:30:46.556] Timer tick, asking Buildbucket for the build status
[06:30:47.189] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:47.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:185:0) after 1m0s
[06:31:47.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:185:0)
[06:31:47.272] Timer tick, asking Buildbucket for the build status
[06:31:47.900] Build 8882986192023477536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:47.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9048416254324407888:186:0) after 1m0s
[06:32:21.067] Received PubSub notification, asking Buildbucket for the build status
[06:32:22.126] Build 8882986192023477536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:32:22.126] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1586932340359478",
    "created_by": "project:infra",
    "created_ts": "1586921237883884",
    "id": "8882986192023477536",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4114.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b53a2ad1-2a48-40e9-a1b0-1eed82a4de68\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4114.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\": \"84.0.4114.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b53a2ad1-2a48-40e9-a1b0-1eed82a4de68\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-h90s\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"84.0.4114.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-h90s\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"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\": [\"4967-6d1ba8b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1586922001142423",
    "status": "COMPLETED",
    "status_changed_ts": "1586932340676851",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9048416254324407888",
      "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/8882986192023477536/+/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:4b94b1fa198dd910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1586932340693471",
    "url": "https://ci.chromium.org/b/8882986192023477536",
    "utcnow_ts": "1586932342034194"
  }
}
[06:32:22.126] Invocation finished in 3h5m5.821145023s with status SUCCEEDED