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

• parent_buildername:publish_tarball
• user_agent:recipe

[11:17:38.909] New invocation is queued and will start shortly
[11:17:38.910] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:17:40.011] Starting the invocation (attempt 1)
[11:17:40.089] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:17:40.089] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:17:40.089] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9031807415946724064",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4293.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"8168459e-1aa5-47e6-82c5-41390b9181b1\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4293.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:9031807415946724064",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[11:17:40.529] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1602760660180858",
    "id": "8866377353948961376",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4293.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"8168459e-1aa5-47e6-82c5-41390b9181b1\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4293.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1602760660371802",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9031807415946724064",
      "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/8866377353948961376/+/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": "1602760660371951",
    "url": "https://ci.chromium.org/b/8866377353948961376",
    "utcnow_ts": "1602760660518794"
  }
}
[11:17:40.529] Task URL: https://ci.chromium.org/b/8866377353948961376
[11:17:40.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:2:0) after 1m0s
[11:18:04.246] Received PubSub notification, asking Buildbucket for the build status
[11:18:04.336] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:40.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:2:0)
[11:18:40.616] Timer tick, asking Buildbucket for the build status
[11:18:40.820] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:40.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:4:0) after 1m0s
[11:19:40.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:4:0)
[11:19:40.928] Timer tick, asking Buildbucket for the build status
[11:19:41.549] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:41.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:5:0) after 1m0s
[11:20:41.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:5:0)
[11:20:41.619] Timer tick, asking Buildbucket for the build status
[11:20:42.007] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:42.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:6:0) after 1m0s
[11:21:42.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:6:0)
[11:21:42.414] Timer tick, asking Buildbucket for the build status
[11:21:42.478] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:42.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:7:0) after 1m0s
[11:22:42.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:7:0)
[11:22:42.591] Timer tick, asking Buildbucket for the build status
[11:22:43.875] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:43.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:8:0) after 1m0s
[11:23:43.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:8:0)
[11:23:43.972] Timer tick, asking Buildbucket for the build status
[11:23:44.213] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:44.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:9:0) after 1m0s
[11:24:44.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:9:0)
[11:24:44.235] Timer tick, asking Buildbucket for the build status
[11:24:44.583] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:44.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:10:0) after 1m0s
[11:25:44.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:10:0)
[11:25:44.699] Timer tick, asking Buildbucket for the build status
[11:25:44.808] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:44.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:11:0) after 1m0s
[11:26:44.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:11:0)
[11:26:44.933] Timer tick, asking Buildbucket for the build status
[11:26:44.990] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:44.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:12:0) after 1m0s
[11:27:45.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:12:0)
[11:27:45.099] Timer tick, asking Buildbucket for the build status
[11:27:45.276] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:45.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:13:0) after 1m0s
[11:28:45.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:13:0)
[11:28:45.303] Timer tick, asking Buildbucket for the build status
[11:28:45.372] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:45.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:14:0) after 1m0s
[11:29:45.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:14:0)
[11:29:45.486] Timer tick, asking Buildbucket for the build status
[11:29:45.601] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:45.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:15:0) after 1m0s
[11:30:45.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:15:0)
[11:30:45.766] Timer tick, asking Buildbucket for the build status
[11:30:45.874] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:45.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:16:0) after 1m0s
[11:31:45.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:16:0)
[11:31:45.933] Timer tick, asking Buildbucket for the build status
[11:31:46.029] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:46.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:17:0) after 1m0s
[11:32:46.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:17:0)
[11:32:46.079] Timer tick, asking Buildbucket for the build status
[11:32:46.271] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:46.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:18:0) after 1m0s
[11:33:46.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:18:0)
[11:33:46.274] Timer tick, asking Buildbucket for the build status
[11:33:46.333] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:46.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:19:0) after 1m0s
[11:34:46.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:19:0)
[11:34:46.371] Timer tick, asking Buildbucket for the build status
[11:34:46.437] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:46.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:20:0) after 1m0s
[11:35:46.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:20:0)
[11:35:46.577] Timer tick, asking Buildbucket for the build status
[11:35:46.693] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:46.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:21:0) after 1m0s
[11:36:46.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:21:0)
[11:36:46.825] Timer tick, asking Buildbucket for the build status
[11:36:46.910] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:46.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:22:0) after 1m0s
[11:37:46.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:22:0)
[11:37:46.974] Timer tick, asking Buildbucket for the build status
[11:37:47.153] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:47.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:23:0) after 1m0s
[11:38:47.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:23:0)
[11:38:47.222] Timer tick, asking Buildbucket for the build status
[11:38:47.302] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:47.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:24:0) after 1m0s
[11:39:47.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:24:0)
[11:39:47.349] Timer tick, asking Buildbucket for the build status
[11:39:47.505] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:47.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:25:0) after 1m0s
[11:40:47.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:25:0)
[11:40:47.526] Timer tick, asking Buildbucket for the build status
[11:40:47.629] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:47.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:26:0) after 1m0s
[11:41:47.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:26:0)
[11:41:47.657] Timer tick, asking Buildbucket for the build status
[11:41:47.801] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:47.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:27:0) after 1m0s
[11:42:47.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:27:0)
[11:42:47.929] Timer tick, asking Buildbucket for the build status
[11:42:48.074] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:48.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:28:0) after 1m0s
[11:43:48.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:28:0)
[11:43:48.108] Timer tick, asking Buildbucket for the build status
[11:43:48.168] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:48.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:29:0) after 1m0s
[11:44:48.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:29:0)
[11:44:48.255] Timer tick, asking Buildbucket for the build status
[11:44:48.352] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:48.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:30:0) after 1m0s
[11:45:48.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:30:0)
[11:45:48.447] Timer tick, asking Buildbucket for the build status
[11:45:49.329] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:49.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:31:0) after 1m0s
[11:46:49.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:31:0)
[11:46:49.334] Timer tick, asking Buildbucket for the build status
[11:46:49.581] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:49.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:32:0) after 1m0s
[11:47:50.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:32:0)
[11:47:50.589] Timer tick, asking Buildbucket for the build status
[11:47:50.696] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:50.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:33:0) after 1m0s
[11:48:50.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:33:0)
[11:48:50.733] Timer tick, asking Buildbucket for the build status
[11:48:50.791] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:50.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:34:0) after 1m0s
[11:49:50.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:34:0)
[11:49:50.813] Timer tick, asking Buildbucket for the build status
[11:49:50.906] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:50.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:35:0) after 1m0s
[11:50:50.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:35:0)
[11:50:50.929] Timer tick, asking Buildbucket for the build status
[11:50:51.033] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:51.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:36:0) after 1m0s
[11:51:51.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:36:0)
[11:51:51.103] Timer tick, asking Buildbucket for the build status
[11:51:51.158] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:51.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:37:0) after 1m0s
[11:52:51.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:37:0)
[11:52:51.141] Timer tick, asking Buildbucket for the build status
[11:52:51.316] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:51.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:38:0) after 1m0s
[11:53:51.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:38:0)
[11:53:51.348] Timer tick, asking Buildbucket for the build status
[11:53:51.413] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:51.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:39:0) after 1m0s
[11:54:51.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:39:0)
[11:54:51.486] Timer tick, asking Buildbucket for the build status
[11:54:52.096] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:52.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:40:0) after 1m0s
[11:55:52.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:40:0)
[11:55:52.141] Timer tick, asking Buildbucket for the build status
[11:55:52.208] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:52.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:41:0) after 1m0s
[11:56:52.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:41:0)
[11:56:52.276] Timer tick, asking Buildbucket for the build status
[11:56:52.428] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:52.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:42:0) after 1m0s
[11:57:52.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:42:0)
[11:57:52.469] Timer tick, asking Buildbucket for the build status
[11:57:52.571] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:52.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:43:0) after 1m0s
[11:58:52.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:43:0)
[11:58:52.634] Timer tick, asking Buildbucket for the build status
[11:58:52.746] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:52.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:44:0) after 1m0s
[11:59:52.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:44:0)
[11:59:52.819] Timer tick, asking Buildbucket for the build status
[11:59:53.040] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:53.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:45:0) after 1m0s
[12:00:53.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:45:0)
[12:00:53.191] Timer tick, asking Buildbucket for the build status
[12:00:53.484] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:53.484] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:46:0) after 1m0s
[12:01:53.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:46:0)
[12:01:53.583] Timer tick, asking Buildbucket for the build status
[12:01:53.816] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:53.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:47:0) after 1m0s
[12:02:53.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:47:0)
[12:02:53.864] Timer tick, asking Buildbucket for the build status
[12:02:53.996] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:53.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:48:0) after 1m0s
[12:03:54.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:48:0)
[12:03:54.082] Timer tick, asking Buildbucket for the build status
[12:03:54.314] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:54.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:49:0) after 1m0s
[12:04:54.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:49:0)
[12:04:54.363] Timer tick, asking Buildbucket for the build status
[12:04:54.485] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:54.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:50:0) after 1m0s
[12:05:54.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:50:0)
[12:05:54.545] Timer tick, asking Buildbucket for the build status
[12:05:54.800] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:54.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:51:0) after 1m0s
[12:06:54.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:51:0)
[12:06:54.837] Timer tick, asking Buildbucket for the build status
[12:06:54.981] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:54.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:52:0) after 1m0s
[12:07:55.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:52:0)
[12:07:55.036] Timer tick, asking Buildbucket for the build status
[12:07:55.180] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:55.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:53:0) after 1m0s
[12:08:55.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:53:0)
[12:08:55.242] Timer tick, asking Buildbucket for the build status
[12:08:55.315] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:55.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:54:0) after 1m0s
[12:09:55.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:54:0)
[12:09:55.313] Timer tick, asking Buildbucket for the build status
[12:09:55.989] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:55.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:55:0) after 1m0s
[12:10:56.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:55:0)
[12:10:56.092] Timer tick, asking Buildbucket for the build status
[12:10:56.309] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:56.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:56:0) after 1m0s
[12:11:56.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:56:0)
[12:11:56.374] Timer tick, asking Buildbucket for the build status
[12:11:56.434] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:56.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:57:0) after 1m0s
[12:12:56.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:57:0)
[12:12:56.459] Timer tick, asking Buildbucket for the build status
[12:12:56.668] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:56.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:58:0) after 1m0s
[12:13:56.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:58:0)
[12:13:56.681] Timer tick, asking Buildbucket for the build status
[12:13:56.839] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:56.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:59:0) after 1m0s
[12:14:56.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:59:0)
[12:14:56.858] Timer tick, asking Buildbucket for the build status
[12:14:57.119] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:57.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:60:0) after 1m0s
[12:15:57.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:60:0)
[12:15:57.179] Timer tick, asking Buildbucket for the build status
[12:15:57.269] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:57.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:61:0) after 1m0s
[12:16:57.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:61:0)
[12:16:57.452] Timer tick, asking Buildbucket for the build status
[12:16:57.524] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:57.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:62:0) after 1m0s
[12:17:57.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:62:0)
[12:17:57.543] Timer tick, asking Buildbucket for the build status
[12:17:58.001] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:58.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:63:0) after 1m0s
[12:18:58.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:63:0)
[12:18:58.027] Timer tick, asking Buildbucket for the build status
[12:18:58.108] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:58.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:64:0) after 1m0s
[12:19:58.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:64:0)
[12:19:58.147] Timer tick, asking Buildbucket for the build status
[12:19:58.224] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:58.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:65:0) after 1m0s
[12:20:58.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:65:0)
[12:20:58.285] Timer tick, asking Buildbucket for the build status
[12:20:58.373] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:58.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:66:0) after 1m0s
[12:21:58.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:66:0)
[12:21:58.397] Timer tick, asking Buildbucket for the build status
[12:21:58.526] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:58.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:67:0) after 1m0s
[12:22:58.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:67:0)
[12:22:58.554] Timer tick, asking Buildbucket for the build status
[12:22:58.660] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:58.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:68:0) after 1m0s
[12:23:58.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:68:0)
[12:23:58.822] Timer tick, asking Buildbucket for the build status
[12:23:58.879] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:58.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:69:0) after 1m0s
[12:24:59.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:69:0)
[12:24:59.072] Timer tick, asking Buildbucket for the build status
[12:24:59.148] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:59.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:70:0) after 1m0s
[12:25:59.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:70:0)
[12:25:59.199] Timer tick, asking Buildbucket for the build status
[12:25:59.251] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:59.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:71:0) after 1m0s
[12:26:59.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:71:0)
[12:26:59.292] Timer tick, asking Buildbucket for the build status
[12:26:59.357] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:59.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:72:0) after 1m0s
[12:27:59.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:72:0)
[12:27:59.417] Timer tick, asking Buildbucket for the build status
[12:27:59.511] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:59.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:73:0) after 1m0s
[12:28:59.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:73:0)
[12:28:59.581] Timer tick, asking Buildbucket for the build status
[12:28:59.655] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:59.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:74:0) after 1m0s
[12:29:59.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:74:0)
[12:29:59.735] Timer tick, asking Buildbucket for the build status
[12:29:59.799] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:59.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:75:0) after 1m0s
[12:30:59.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:75:0)
[12:30:59.942] Timer tick, asking Buildbucket for the build status
[12:30:59.996] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:59.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:76:0) after 1m0s
[12:32:00.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:76:0)
[12:32:00.094] Timer tick, asking Buildbucket for the build status
[12:32:00.282] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:00.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:77:0) after 1m0s
[12:33:00.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:77:0)
[12:33:00.393] Timer tick, asking Buildbucket for the build status
[12:33:00.770] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:00.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:78:0) after 1m0s
[12:34:01.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:78:0)
[12:34:01.181] Timer tick, asking Buildbucket for the build status
[12:34:01.406] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:01.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:79:0) after 1m0s
[12:35:01.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:79:0)
[12:35:01.578] Timer tick, asking Buildbucket for the build status
[12:35:01.806] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:01.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:80:0) after 1m0s
[12:36:02.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:80:0)
[12:36:02.717] Timer tick, asking Buildbucket for the build status
[12:36:02.896] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:02.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:81:0) after 1m0s
[12:37:02.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:81:0)
[12:37:02.937] Timer tick, asking Buildbucket for the build status
[12:37:03.139] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:03.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:82:0) after 1m0s
[12:38:04.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:82:0)
[12:38:04.011] Timer tick, asking Buildbucket for the build status
[12:38:04.405] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:04.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:83:0) after 1m0s
[12:39:04.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:83:0)
[12:39:04.543] Timer tick, asking Buildbucket for the build status
[12:39:04.619] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:04.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:84:0) after 1m0s
[12:40:04.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:84:0)
[12:40:04.800] Timer tick, asking Buildbucket for the build status
[12:40:04.915] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:04.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:85:0) after 1m0s
[12:41:04.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:85:0)
[12:41:04.909] Timer tick, asking Buildbucket for the build status
[12:41:05.072] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:05.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:86:0) after 1m0s
[12:42:05.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:86:0)
[12:42:05.195] Timer tick, asking Buildbucket for the build status
[12:42:05.256] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:05.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:87:0) after 1m0s
[12:43:05.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:87:0)
[12:43:05.293] Timer tick, asking Buildbucket for the build status
[12:43:05.405] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:05.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:88:0) after 1m0s
[12:44:05.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:88:0)
[12:44:05.475] Timer tick, asking Buildbucket for the build status
[12:44:06.700] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:06.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:89:0) after 1m0s
[12:45:06.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:89:0)
[12:45:06.742] Timer tick, asking Buildbucket for the build status
[12:45:06.877] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:06.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:90:0) after 1m0s
[12:46:06.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:90:0)
[12:46:06.978] Timer tick, asking Buildbucket for the build status
[12:46:07.566] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:07.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:91:0) after 1m0s
[12:47:07.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:91:0)
[12:47:07.593] Timer tick, asking Buildbucket for the build status
[12:47:07.655] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:07.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:92:0) after 1m0s
[12:48:07.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:92:0)
[12:48:07.670] Timer tick, asking Buildbucket for the build status
[12:48:07.774] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:07.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:93:0) after 1m0s
[12:49:07.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:93:0)
[12:49:07.802] Timer tick, asking Buildbucket for the build status
[12:49:08.011] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:08.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:94:0) after 1m0s
[12:50:08.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:94:0)
[12:50:08.044] Timer tick, asking Buildbucket for the build status
[12:50:08.146] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:08.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:95:0) after 1m0s
[12:51:08.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:95:0)
[12:51:08.247] Timer tick, asking Buildbucket for the build status
[12:51:08.311] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:08.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:96:0) after 1m0s
[12:52:08.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:96:0)
[12:52:08.370] Timer tick, asking Buildbucket for the build status
[12:52:08.571] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:08.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:97:0) after 1m0s
[12:53:08.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:97:0)
[12:53:08.595] Timer tick, asking Buildbucket for the build status
[12:53:08.706] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:08.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:98:0) after 1m0s
[12:54:08.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:98:0)
[12:54:08.775] Timer tick, asking Buildbucket for the build status
[12:54:08.857] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:08.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:99:0) after 1m0s
[12:55:08.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:99:0)
[12:55:08.913] Timer tick, asking Buildbucket for the build status
[12:55:08.998] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:08.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:100:0) after 1m0s
[12:56:09.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:100:0)
[12:56:09.029] Timer tick, asking Buildbucket for the build status
[12:56:09.128] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:09.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:101:0) after 1m0s
[12:57:09.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:101:0)
[12:57:09.229] Timer tick, asking Buildbucket for the build status
[12:57:09.415] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:09.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:102:0) after 1m0s
[12:58:09.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:102:0)
[12:58:09.446] Timer tick, asking Buildbucket for the build status
[12:58:09.550] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:09.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:103:0) after 1m0s
[12:59:09.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:103:0)
[12:59:09.736] Timer tick, asking Buildbucket for the build status
[12:59:09.802] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:09.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:104:0) after 1m0s
[13:00:09.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:104:0)
[13:00:09.899] Timer tick, asking Buildbucket for the build status
[13:00:09.967] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:09.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:105:0) after 1m0s
[13:01:10.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:105:0)
[13:01:10.092] Timer tick, asking Buildbucket for the build status
[13:01:10.284] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:10.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:106:0) after 1m0s
[13:02:10.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:106:0)
[13:02:10.359] Timer tick, asking Buildbucket for the build status
[13:02:10.463] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:10.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:107:0) after 1m0s
[13:03:10.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:107:0)
[13:03:10.636] Timer tick, asking Buildbucket for the build status
[13:03:10.925] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:10.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:108:0) after 1m0s
[13:04:11.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:108:0)
[13:04:11.036] Timer tick, asking Buildbucket for the build status
[13:04:11.237] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:11.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:109:0) after 1m0s
[13:05:11.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:109:0)
[13:05:11.272] Timer tick, asking Buildbucket for the build status
[13:05:11.333] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:11.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:110:0) after 1m0s
[13:06:11.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:110:0)
[13:06:11.410] Timer tick, asking Buildbucket for the build status
[13:06:11.594] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:11.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:111:0) after 1m0s
[13:07:11.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:111:0)
[13:07:11.680] Timer tick, asking Buildbucket for the build status
[13:07:11.866] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:11.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:112:0) after 1m0s
[13:08:11.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:112:0)
[13:08:11.891] Timer tick, asking Buildbucket for the build status
[13:08:12.021] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:12.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:113:0) after 1m0s
[13:09:12.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:113:0)
[13:09:12.114] Timer tick, asking Buildbucket for the build status
[13:09:12.167] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:12.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:114:0) after 1m0s
[13:10:12.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:114:0)
[13:10:12.202] Timer tick, asking Buildbucket for the build status
[13:10:12.589] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:12.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:115:0) after 1m0s
[13:11:12.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:115:0)
[13:11:12.695] Timer tick, asking Buildbucket for the build status
[13:11:12.751] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:12.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:116:0) after 1m0s
[13:12:12.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:116:0)
[13:12:12.903] Timer tick, asking Buildbucket for the build status
[13:12:13.284] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:13.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:117:0) after 1m0s
[13:13:13.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:117:0)
[13:13:13.325] Timer tick, asking Buildbucket for the build status
[13:13:14.830] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:14.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:118:0) after 1m0s
[13:14:14.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:118:0)
[13:14:14.900] Timer tick, asking Buildbucket for the build status
[13:14:14.969] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:14.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:119:0) after 1m0s
[13:15:14.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:119:0)
[13:15:14.974] Timer tick, asking Buildbucket for the build status
[13:15:15.044] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:15.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:120:0) after 1m0s
[13:16:15.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:120:0)
[13:16:15.173] Timer tick, asking Buildbucket for the build status
[13:16:15.583] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:15.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:121:0) after 1m0s
[13:17:15.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:121:0)
[13:17:15.764] Timer tick, asking Buildbucket for the build status
[13:17:15.820] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:15.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:122:0) after 1m0s
[13:18:15.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:122:0)
[13:18:15.875] Timer tick, asking Buildbucket for the build status
[13:18:15.969] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:15.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:123:0) after 1m0s
[13:19:15.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:123:0)
[13:19:15.995] Timer tick, asking Buildbucket for the build status
[13:19:16.056] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:16.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:124:0) after 1m0s
[13:20:16.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:124:0)
[13:20:16.109] Timer tick, asking Buildbucket for the build status
[13:20:16.172] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:16.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:125:0) after 1m0s
[13:21:16.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:125:0)
[13:21:16.202] Timer tick, asking Buildbucket for the build status
[13:21:16.257] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:16.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:126:0) after 1m0s
[13:22:16.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:126:0)
[13:22:16.416] Timer tick, asking Buildbucket for the build status
[13:22:17.670] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:17.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:127:0) after 1m0s
[13:23:17.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:127:0)
[13:23:17.789] Timer tick, asking Buildbucket for the build status
[13:23:17.884] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:17.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:128:0) after 1m0s
[13:24:17.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:128:0)
[13:24:17.896] Timer tick, asking Buildbucket for the build status
[13:24:17.959] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:17.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:129:0) after 1m0s
[13:25:18.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:129:0)
[13:25:18.005] Timer tick, asking Buildbucket for the build status
[13:25:18.334] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:18.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:130:0) after 1m0s
[13:26:18.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:130:0)
[13:26:18.361] Timer tick, asking Buildbucket for the build status
[13:26:18.451] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:18.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:131:0) after 1m0s
[13:27:18.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:131:0)
[13:27:18.502] Timer tick, asking Buildbucket for the build status
[13:27:18.689] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:18.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:132:0) after 1m0s
[13:28:18.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:132:0)
[13:28:18.780] Timer tick, asking Buildbucket for the build status
[13:28:18.932] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:18.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:133:0) after 1m0s
[13:29:18.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:133:0)
[13:29:18.945] Timer tick, asking Buildbucket for the build status
[13:29:19.116] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:19.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:134:0) after 1m0s
[13:30:19.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:134:0)
[13:30:19.148] Timer tick, asking Buildbucket for the build status
[13:30:19.210] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:19.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:135:0) after 1m0s
[13:31:19.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:135:0)
[13:31:19.273] Timer tick, asking Buildbucket for the build status
[13:31:19.351] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:19.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:136:0) after 1m0s
[13:32:19.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:136:0)
[13:32:19.389] Timer tick, asking Buildbucket for the build status
[13:32:19.621] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:19.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:137:0) after 1m0s
[13:33:19.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:137:0)
[13:33:19.654] Timer tick, asking Buildbucket for the build status
[13:33:20.009] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:20.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:138:0) after 1m0s
[13:34:20.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:138:0)
[13:34:20.129] Timer tick, asking Buildbucket for the build status
[13:34:20.180] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:20.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:139:0) after 1m0s
[13:35:20.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:139:0)
[13:35:20.436] Timer tick, asking Buildbucket for the build status
[13:35:20.506] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:20.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:140:0) after 1m0s
[13:36:20.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:140:0)
[13:36:20.606] Timer tick, asking Buildbucket for the build status
[13:36:20.666] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:20.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:141:0) after 1m0s
[13:37:20.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:141:0)
[13:37:20.742] Timer tick, asking Buildbucket for the build status
[13:37:20.815] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:20.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:142:0) after 1m0s
[13:38:20.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:142:0)
[13:38:20.916] Timer tick, asking Buildbucket for the build status
[13:38:21.214] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:21.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:143:0) after 1m0s
[13:39:21.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:143:0)
[13:39:21.362] Timer tick, asking Buildbucket for the build status
[13:39:21.480] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:21.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:144:0) after 1m0s
[13:40:21.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:144:0)
[13:40:21.541] Timer tick, asking Buildbucket for the build status
[13:40:21.813] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:21.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:145:0) after 1m0s
[13:41:21.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:145:0)
[13:41:21.890] Timer tick, asking Buildbucket for the build status
[13:41:22.304] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:22.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:146:0) after 1m0s
[13:42:22.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:146:0)
[13:42:22.311] Timer tick, asking Buildbucket for the build status
[13:42:22.371] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:22.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:147:0) after 1m0s
[13:43:22.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:147:0)
[13:43:22.393] Timer tick, asking Buildbucket for the build status
[13:43:22.882] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:22.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:148:0) after 1m0s
[13:44:22.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:148:0)
[13:44:22.916] Timer tick, asking Buildbucket for the build status
[13:44:23.006] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:23.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:149:0) after 1m0s
[13:45:23.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:149:0)
[13:45:23.125] Timer tick, asking Buildbucket for the build status
[13:45:23.249] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:23.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:150:0) after 1m0s
[13:46:23.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:150:0)
[13:46:23.277] Timer tick, asking Buildbucket for the build status
[13:46:23.400] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:23.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:151:0) after 1m0s
[13:47:23.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:151:0)
[13:47:23.475] Timer tick, asking Buildbucket for the build status
[13:47:23.568] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:23.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:152:0) after 1m0s
[13:48:23.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:152:0)
[13:48:23.648] Timer tick, asking Buildbucket for the build status
[13:48:23.748] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:23.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:153:0) after 1m0s
[13:49:23.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:153:0)
[13:49:23.775] Timer tick, asking Buildbucket for the build status
[13:49:23.840] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:23.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:154:0) after 1m0s
[13:50:23.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:154:0)
[13:50:23.891] Timer tick, asking Buildbucket for the build status
[13:50:24.114] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:24.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:155:0) after 1m0s
[13:51:24.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:155:0)
[13:51:24.143] Timer tick, asking Buildbucket for the build status
[13:51:24.202] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:24.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:156:0) after 1m0s
[13:52:24.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:156:0)
[13:52:24.212] Timer tick, asking Buildbucket for the build status
[13:52:24.312] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:24.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:157:0) after 1m0s
[13:53:24.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:157:0)
[13:53:24.397] Timer tick, asking Buildbucket for the build status
[13:53:24.526] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:24.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:158:0) after 1m0s
[13:54:24.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:158:0)
[13:54:24.621] Timer tick, asking Buildbucket for the build status
[13:54:24.896] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:24.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:159:0) after 1m0s
[13:55:25.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:159:0)
[13:55:25.223] Timer tick, asking Buildbucket for the build status
[13:55:25.509] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:25.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:160:0) after 1m0s
[13:56:25.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:160:0)
[13:56:25.635] Timer tick, asking Buildbucket for the build status
[13:56:25.691] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:25.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:161:0) after 1m0s
[13:57:25.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:161:0)
[13:57:25.914] Timer tick, asking Buildbucket for the build status
[13:57:25.968] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:25.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:162:0) after 1m0s
[13:58:26.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:162:0)
[13:58:26.176] Timer tick, asking Buildbucket for the build status
[13:58:26.349] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:26.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:163:0) after 1m0s
[13:59:26.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:163:0)
[13:59:26.509] Timer tick, asking Buildbucket for the build status
[13:59:26.558] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:26.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:164:0) after 1m0s
[14:00:26.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:164:0)
[14:00:26.664] Timer tick, asking Buildbucket for the build status
[14:00:26.936] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:26.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:165:0) after 1m0s
[14:01:27.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:165:0)
[14:01:27.021] Timer tick, asking Buildbucket for the build status
[14:01:27.122] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:27.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:166:0) after 1m0s
[14:02:27.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:166:0)
[14:02:27.197] Timer tick, asking Buildbucket for the build status
[14:02:27.253] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:27.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:167:0) after 1m0s
[14:03:27.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:167:0)
[14:03:27.352] Timer tick, asking Buildbucket for the build status
[14:03:27.591] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:27.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:168:0) after 1m0s
[14:04:27.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:168:0)
[14:04:27.688] Timer tick, asking Buildbucket for the build status
[14:04:27.754] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:27.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:169:0) after 1m0s
[14:05:27.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:169:0)
[14:05:27.826] Timer tick, asking Buildbucket for the build status
[14:05:27.907] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:27.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:170:0) after 1m0s
[14:06:27.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:170:0)
[14:06:27.956] Timer tick, asking Buildbucket for the build status
[14:06:28.035] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:28.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:171:0) after 1m0s
[14:07:28.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:171:0)
[14:07:28.134] Timer tick, asking Buildbucket for the build status
[14:07:28.200] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:28.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:172:0) after 1m0s
[14:08:28.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:172:0)
[14:08:28.258] Timer tick, asking Buildbucket for the build status
[14:08:28.386] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:28.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:173:0) after 1m0s
[14:09:28.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:173:0)
[14:09:28.436] Timer tick, asking Buildbucket for the build status
[14:09:28.706] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:28.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:174:0) after 1m0s
[14:10:28.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:174:0)
[14:10:28.750] Timer tick, asking Buildbucket for the build status
[14:10:28.816] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:28.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:175:0) after 1m0s
[14:11:28.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:175:0)
[14:11:28.890] Timer tick, asking Buildbucket for the build status
[14:11:28.961] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:28.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:176:0) after 1m0s
[14:12:29.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:176:0)
[14:12:29.002] Timer tick, asking Buildbucket for the build status
[14:12:29.065] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:29.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:177:0) after 1m0s
[14:13:29.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:177:0)
[14:13:29.167] Timer tick, asking Buildbucket for the build status
[14:13:29.278] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:29.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:178:0) after 1m0s
[14:14:29.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:178:0)
[14:14:29.393] Timer tick, asking Buildbucket for the build status
[14:14:29.468] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:29.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:179:0) after 1m0s
[14:15:29.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:179:0)
[14:15:29.615] Timer tick, asking Buildbucket for the build status
[14:15:29.689] Build 8866377353948961376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:29.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031807415946724064:180:0) after 1m0s
[14:16:14.678] Received PubSub notification, asking Buildbucket for the build status
[14:16:14.907] Build 8866377353948961376: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:16:14.907] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1602771373854288",
    "created_by": "project:infra",
    "created_ts": "1602760660180858",
    "id": "8866377353948961376",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4293.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"8168459e-1aa5-47e6-82c5-41390b9181b1\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4293.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_292036ca8cd0711903e829a3c5417e821d204af08fdef2e4b6d3affbee40eeb2_v2\", \"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-zdly\"], \"image\": [\"chrome-xenial-20101301-f14f52fdd71\"], \"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\": [\"5595-820ff29\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1602760682877288",
    "status": "COMPLETED",
    "status_changed_ts": "1602771374268077",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9031807415946724064",
      "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/8866377353948961376/+/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:4f44cc5f2006de10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1602771374281446",
    "url": "https://ci.chromium.org/b/8866377353948961376",
    "utcnow_ts": "1602771374892524"
  }
}
[14:16:14.907] Invocation finished in 2h58m36.015388952s with status SUCCEEDED