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.4147.44"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[08:38:55.584] New invocation is queued and will start shortly
[08:38:55.584] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:38:56.814] Starting the invocation (attempt 1)
[08:38:56.866] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:38:56.868] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:38:56.868] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9043232619641302720",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"84.0.4147.44\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"e94b3a0c-0d27-4103-bbd4-d09ec0145db6\",\"title\":\"publish_tarball/0\"}]},\"version\":\"84.0.4147.44\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9043232619641302720",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:38:57.495] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1591864736966747",
    "id": "8877802557529901856",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4147.44\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"e94b3a0c-0d27-4103-bbd4-d09ec0145db6\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4147.44\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1591864737304456",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9043232619641302720",
      "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/8877802557529901856/+/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": "1591864737304610",
    "url": "https://ci.chromium.org/b/8877802557529901856",
    "utcnow_ts": "1591864737479965"
  }
}
[08:38:57.495] Task URL: https://ci.chromium.org/b/8877802557529901856
[08:38:57.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:2:0) after 1m0s
[08:39:25.044] Received PubSub notification, asking Buildbucket for the build status
[08:39:25.171] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:57.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:2:0)
[08:39:57.910] Timer tick, asking Buildbucket for the build status
[08:39:58.065] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:58.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:4:0) after 1m0s
[08:40:58.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:4:0)
[08:40:58.151] Timer tick, asking Buildbucket for the build status
[08:40:58.390] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:58.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:5:0) after 1m0s
[08:41:58.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:5:0)
[08:41:58.484] Timer tick, asking Buildbucket for the build status
[08:42:01.487] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:01.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:6:0) after 1m0s
[08:43:01.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:6:0)
[08:43:01.573] Timer tick, asking Buildbucket for the build status
[08:43:01.707] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:01.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:7:0) after 1m0s
[08:44:01.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:7:0)
[08:44:01.827] Timer tick, asking Buildbucket for the build status
[08:44:02.229] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:02.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:8:0) after 1m0s
[08:45:02.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:8:0)
[08:45:02.237] Timer tick, asking Buildbucket for the build status
[08:45:02.933] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:02.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:9:0) after 1m0s
[08:46:03.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:9:0)
[08:46:03.017] Timer tick, asking Buildbucket for the build status
[08:46:03.225] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:03.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:10:0) after 1m0s
[08:47:03.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:10:0)
[08:47:03.216] Timer tick, asking Buildbucket for the build status
[08:47:03.554] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:03.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:11:0) after 1m0s
[08:48:03.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:11:0)
[08:48:03.610] Timer tick, asking Buildbucket for the build status
[08:48:03.749] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:03.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:12:0) after 1m0s
[08:49:03.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:12:0)
[08:49:03.774] Timer tick, asking Buildbucket for the build status
[08:49:03.931] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:03.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:13:0) after 1m0s
[08:50:03.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:13:0)
[08:50:03.960] Timer tick, asking Buildbucket for the build status
[08:50:04.161] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:04.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:14:0) after 1m0s
[08:51:04.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:14:0)
[08:51:04.262] Timer tick, asking Buildbucket for the build status
[08:51:04.590] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:04.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:15:0) after 1m0s
[08:52:04.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:15:0)
[08:52:04.611] Timer tick, asking Buildbucket for the build status
[08:52:04.769] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:04.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:16:0) after 1m0s
[08:53:04.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:16:0)
[08:53:04.884] Timer tick, asking Buildbucket for the build status
[08:53:05.280] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:05.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:17:0) after 1m0s
[08:54:05.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:17:0)
[08:54:05.345] Timer tick, asking Buildbucket for the build status
[08:54:05.489] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:05.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:18:0) after 1m0s
[08:55:05.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:18:0)
[08:55:05.497] Timer tick, asking Buildbucket for the build status
[08:55:05.747] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:05.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:19:0) after 1m0s
[08:56:05.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:19:0)
[08:56:05.792] Timer tick, asking Buildbucket for the build status
[08:56:06.086] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:06.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:20:0) after 1m0s
[08:57:06.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:20:0)
[08:57:06.178] Timer tick, asking Buildbucket for the build status
[08:57:06.699] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:06.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:21:0) after 1m0s
[08:58:06.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:21:0)
[08:58:06.901] Timer tick, asking Buildbucket for the build status
[08:58:07.280] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:07.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:22:0) after 1m0s
[08:59:07.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:22:0)
[08:59:07.469] Timer tick, asking Buildbucket for the build status
[08:59:07.654] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:07.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:23:0) after 1m0s
[09:00:07.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:23:0)
[09:00:07.795] Timer tick, asking Buildbucket for the build status
[09:00:08.980] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:08.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:24:0) after 1m0s
[09:01:09.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:24:0)
[09:01:09.051] Timer tick, asking Buildbucket for the build status
[09:01:10.202] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:10.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:25:0) after 1m0s
[09:02:10.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:25:0)
[09:02:10.277] Timer tick, asking Buildbucket for the build status
[09:02:11.511] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:11.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:26:0) after 1m0s
[09:03:11.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:26:0)
[09:03:11.577] Timer tick, asking Buildbucket for the build status
[09:03:11.765] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:11.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:27:0) after 1m0s
[09:04:11.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:27:0)
[09:04:11.788] Timer tick, asking Buildbucket for the build status
[09:04:11.952] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:11.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:28:0) after 1m0s
[09:05:11.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:28:0)
[09:05:11.992] Timer tick, asking Buildbucket for the build status
[09:05:12.221] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:12.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:29:0) after 1m0s
[09:06:12.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:29:0)
[09:06:12.323] Timer tick, asking Buildbucket for the build status
[09:06:12.504] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:12.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:30:0) after 1m0s
[09:07:12.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:30:0)
[09:07:12.565] Timer tick, asking Buildbucket for the build status
[09:07:12.778] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:12.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:31:0) after 1m0s
[09:08:12.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:31:0)
[09:08:12.858] Timer tick, asking Buildbucket for the build status
[09:08:13.098] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:13.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:32:0) after 1m0s
[09:09:13.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:32:0)
[09:09:13.224] Timer tick, asking Buildbucket for the build status
[09:09:13.611] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:13.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:33:0) after 1m0s
[09:10:13.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:33:0)
[09:10:13.671] Timer tick, asking Buildbucket for the build status
[09:10:13.855] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:13.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:34:0) after 1m0s
[09:11:13.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:34:0)
[09:11:13.909] Timer tick, asking Buildbucket for the build status
[09:11:14.799] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:14.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:35:0) after 1m0s
[09:12:14.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:35:0)
[09:12:14.842] Timer tick, asking Buildbucket for the build status
[09:12:15.113] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:15.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:36:0) after 1m0s
[09:13:15.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:36:0)
[09:13:15.118] Timer tick, asking Buildbucket for the build status
[09:13:15.343] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:15.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:37:0) after 1m0s
[09:14:15.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:37:0)
[09:14:15.383] Timer tick, asking Buildbucket for the build status
[09:14:15.617] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:15.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:38:0) after 1m0s
[09:15:15.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:38:0)
[09:15:15.677] Timer tick, asking Buildbucket for the build status
[09:15:16.306] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:16.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:39:0) after 1m0s
[09:16:16.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:39:0)
[09:16:16.491] Timer tick, asking Buildbucket for the build status
[09:16:16.679] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:16.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:40:0) after 1m0s
[09:17:16.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:40:0)
[09:17:16.734] Timer tick, asking Buildbucket for the build status
[09:17:16.901] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:16.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:41:0) after 1m0s
[09:18:16.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:41:0)
[09:18:16.995] Timer tick, asking Buildbucket for the build status
[09:18:17.123] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:17.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:42:0) after 1m0s
[09:19:17.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:42:0)
[09:19:17.126] Timer tick, asking Buildbucket for the build status
[09:19:17.555] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:17.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:43:0) after 1m0s
[09:20:17.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:43:0)
[09:20:17.572] Timer tick, asking Buildbucket for the build status
[09:20:17.715] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:17.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:44:0) after 1m0s
[09:21:17.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:44:0)
[09:21:17.752] Timer tick, asking Buildbucket for the build status
[09:21:17.998] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:17.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:45:0) after 1m0s
[09:22:18.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:45:0)
[09:22:18.025] Timer tick, asking Buildbucket for the build status
[09:22:18.482] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:18.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:46:0) after 1m0s
[09:23:18.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:46:0)
[09:23:18.624] Timer tick, asking Buildbucket for the build status
[09:23:18.788] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:18.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:47:0) after 1m0s
[09:24:18.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:47:0)
[09:24:18.780] Timer tick, asking Buildbucket for the build status
[09:24:18.938] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:18.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:48:0) after 1m0s
[09:25:18.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:48:0)
[09:25:18.985] Timer tick, asking Buildbucket for the build status
[09:25:19.210] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:19.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:49:0) after 1m0s
[09:26:19.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:49:0)
[09:26:19.238] Timer tick, asking Buildbucket for the build status
[09:26:20.201] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:20.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:50:0) after 1m0s
[09:27:20.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:50:0)
[09:27:20.271] Timer tick, asking Buildbucket for the build status
[09:27:20.506] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:20.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:51:0) after 1m0s
[09:28:20.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:51:0)
[09:28:20.541] Timer tick, asking Buildbucket for the build status
[09:28:20.781] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:20.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:52:0) after 1m0s
[09:29:20.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:52:0)
[09:29:20.805] Timer tick, asking Buildbucket for the build status
[09:29:20.959] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:20.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:53:0) after 1m0s
[09:30:21.010] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:53:0)
[09:30:21.010] Timer tick, asking Buildbucket for the build status
[09:30:21.206] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:21.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:54:0) after 1m0s
[09:31:21.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:54:0)
[09:31:21.291] Timer tick, asking Buildbucket for the build status
[09:31:21.745] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:21.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:55:0) after 1m0s
[09:32:21.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:55:0)
[09:32:21.776] Timer tick, asking Buildbucket for the build status
[09:32:21.936] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:21.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:56:0) after 1m0s
[09:33:21.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:56:0)
[09:33:21.946] Timer tick, asking Buildbucket for the build status
[09:33:22.229] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:22.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:57:0) after 1m0s
[09:34:22.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:57:0)
[09:34:22.292] Timer tick, asking Buildbucket for the build status
[09:34:22.409] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:22.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:58:0) after 1m0s
[09:35:22.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:58:0)
[09:35:22.465] Timer tick, asking Buildbucket for the build status
[09:35:22.641] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:22.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:59:0) after 1m0s
[09:36:22.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:59:0)
[09:36:22.679] Timer tick, asking Buildbucket for the build status
[09:36:22.814] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:22.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:60:0) after 1m0s
[09:37:22.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:60:0)
[09:37:22.860] Timer tick, asking Buildbucket for the build status
[09:37:23.040] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:23.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:61:0) after 1m0s
[09:38:23.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:61:0)
[09:38:23.052] Timer tick, asking Buildbucket for the build status
[09:38:23.278] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:23.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:62:0) after 1m0s
[09:39:23.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:62:0)
[09:39:23.267] Timer tick, asking Buildbucket for the build status
[09:39:23.415] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:23.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:63:0) after 1m0s
[09:40:23.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:63:0)
[09:40:23.492] Timer tick, asking Buildbucket for the build status
[09:40:23.615] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:23.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:64:0) after 1m0s
[09:41:23.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:64:0)
[09:41:23.711] Timer tick, asking Buildbucket for the build status
[09:41:23.991] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:23.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:65:0) after 1m0s
[09:42:24.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:65:0)
[09:42:24.024] Timer tick, asking Buildbucket for the build status
[09:42:24.189] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:24.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:66:0) after 1m0s
[09:43:24.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:66:0)
[09:43:24.294] Timer tick, asking Buildbucket for the build status
[09:43:24.353] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:24.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:67:0) after 1m0s
[09:44:24.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:67:0)
[09:44:24.377] Timer tick, asking Buildbucket for the build status
[09:44:24.541] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:24.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:68:0) after 1m0s
[09:45:24.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:68:0)
[09:45:24.596] Timer tick, asking Buildbucket for the build status
[09:45:25.081] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:25.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:69:0) after 1m0s
[09:46:25.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:69:0)
[09:46:25.141] Timer tick, asking Buildbucket for the build status
[09:46:25.751] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:25.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:70:0) after 1m0s
[09:47:25.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:70:0)
[09:47:25.902] Timer tick, asking Buildbucket for the build status
[09:47:26.574] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:26.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:71:0) after 1m0s
[09:48:26.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:71:0)
[09:48:26.604] Timer tick, asking Buildbucket for the build status
[09:48:27.082] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:27.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:72:0) after 1m0s
[09:49:27.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:72:0)
[09:49:27.109] Timer tick, asking Buildbucket for the build status
[09:49:27.464] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:27.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:73:0) after 1m0s
[09:50:27.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:73:0)
[09:50:27.497] Timer tick, asking Buildbucket for the build status
[09:50:27.809] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:27.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:74:0) after 1m0s
[09:51:27.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:74:0)
[09:51:27.833] Timer tick, asking Buildbucket for the build status
[09:51:28.600] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:28.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:75:0) after 1m0s
[09:52:28.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:75:0)
[09:52:28.682] Timer tick, asking Buildbucket for the build status
[09:52:28.954] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:28.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:76:0) after 1m0s
[09:53:29.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:76:0)
[09:53:29.016] Timer tick, asking Buildbucket for the build status
[09:53:29.192] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:29.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:77:0) after 1m0s
[09:54:29.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:77:0)
[09:54:29.223] Timer tick, asking Buildbucket for the build status
[09:54:29.461] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:29.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:78:0) after 1m0s
[09:55:29.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:78:0)
[09:55:29.535] Timer tick, asking Buildbucket for the build status
[09:55:29.767] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:29.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:79:0) after 1m0s
[09:56:29.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:79:0)
[09:56:29.790] Timer tick, asking Buildbucket for the build status
[09:56:30.482] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:30.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:80:0) after 1m0s
[09:57:30.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:80:0)
[09:57:30.503] Timer tick, asking Buildbucket for the build status
[09:57:30.651] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:30.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:81:0) after 1m0s
[09:58:30.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:81:0)
[09:58:30.688] Timer tick, asking Buildbucket for the build status
[09:58:31.005] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:31.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:82:0) after 1m0s
[09:59:31.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:82:0)
[09:59:31.042] Timer tick, asking Buildbucket for the build status
[09:59:31.206] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:31.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:83:0) after 1m0s
[10:00:31.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:83:0)
[10:00:31.232] Timer tick, asking Buildbucket for the build status
[10:00:31.836] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:31.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:84:0) after 1m0s
[10:01:31.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:84:0)
[10:01:31.860] Timer tick, asking Buildbucket for the build status
[10:01:32.009] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:32.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:85:0) after 1m0s
[10:02:32.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:85:0)
[10:02:32.072] Timer tick, asking Buildbucket for the build status
[10:02:32.245] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:32.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:86:0) after 1m0s
[10:03:32.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:86:0)
[10:03:32.365] Timer tick, asking Buildbucket for the build status
[10:03:32.587] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:32.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:87:0) after 1m0s
[10:04:32.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:87:0)
[10:04:32.614] Timer tick, asking Buildbucket for the build status
[10:04:32.740] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:32.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:88:0) after 1m0s
[10:05:32.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:88:0)
[10:05:32.816] Timer tick, asking Buildbucket for the build status
[10:05:33.223] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:33.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:89:0) after 1m0s
[10:06:33.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:89:0)
[10:06:33.248] Timer tick, asking Buildbucket for the build status
[10:06:33.732] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:33.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:90:0) after 1m0s
[10:07:33.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:90:0)
[10:07:33.769] Timer tick, asking Buildbucket for the build status
[10:07:33.993] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:33.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:91:0) after 1m0s
[10:08:34.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:91:0)
[10:08:34.015] Timer tick, asking Buildbucket for the build status
[10:08:34.182] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:34.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:92:0) after 1m0s
[10:09:34.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:92:0)
[10:09:34.206] Timer tick, asking Buildbucket for the build status
[10:09:34.384] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:34.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:93:0) after 1m0s
[10:10:34.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:93:0)
[10:10:34.461] Timer tick, asking Buildbucket for the build status
[10:10:34.660] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:34.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:94:0) after 1m0s
[10:11:34.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:94:0)
[10:11:34.682] Timer tick, asking Buildbucket for the build status
[10:11:36.160] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:36.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:95:0) after 1m0s
[10:12:36.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:95:0)
[10:12:36.195] Timer tick, asking Buildbucket for the build status
[10:12:37.128] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:37.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:96:0) after 1m0s
[10:13:37.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:96:0)
[10:13:37.228] Timer tick, asking Buildbucket for the build status
[10:13:37.349] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:37.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:97:0) after 1m0s
[10:14:37.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:97:0)
[10:14:37.376] Timer tick, asking Buildbucket for the build status
[10:14:37.509] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:37.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:98:0) after 1m0s
[10:15:37.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:98:0)
[10:15:37.638] Timer tick, asking Buildbucket for the build status
[10:15:37.744] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:37.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:99:0) after 1m0s
[10:16:37.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:99:0)
[10:16:37.834] Timer tick, asking Buildbucket for the build status
[10:16:38.256] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:38.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:100:0) after 1m0s
[10:17:38.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:100:0)
[10:17:38.286] Timer tick, asking Buildbucket for the build status
[10:17:38.401] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:38.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:101:0) after 1m0s
[10:18:38.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:101:0)
[10:18:38.443] Timer tick, asking Buildbucket for the build status
[10:18:38.607] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:38.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:102:0) after 1m0s
[10:19:38.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:102:0)
[10:19:38.729] Timer tick, asking Buildbucket for the build status
[10:19:39.110] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:39.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:103:0) after 1m0s
[10:20:39.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:103:0)
[10:20:39.136] Timer tick, asking Buildbucket for the build status
[10:20:39.287] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:39.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:104:0) after 1m0s
[10:21:39.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:104:0)
[10:21:39.362] Timer tick, asking Buildbucket for the build status
[10:21:41.528] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:41.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:105:0) after 1m0s
[10:22:41.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:105:0)
[10:22:41.625] Timer tick, asking Buildbucket for the build status
[10:22:41.786] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:41.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:106:0) after 1m0s
[10:23:41.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:106:0)
[10:23:41.828] Timer tick, asking Buildbucket for the build status
[10:23:42.127] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:42.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:107:0) after 1m0s
[10:24:42.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:107:0)
[10:24:42.182] Timer tick, asking Buildbucket for the build status
[10:24:42.524] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:42.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:108:0) after 1m0s
[10:25:42.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:108:0)
[10:25:42.574] Timer tick, asking Buildbucket for the build status
[10:25:42.985] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:42.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:109:0) after 1m0s
[10:26:43.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:109:0)
[10:26:43.041] Timer tick, asking Buildbucket for the build status
[10:26:44.256] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:44.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:110:0) after 1m0s
[10:27:44.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:110:0)
[10:27:44.463] Timer tick, asking Buildbucket for the build status
[10:27:44.751] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:44.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:111:0) after 1m0s
[10:28:44.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:111:0)
[10:28:44.834] Timer tick, asking Buildbucket for the build status
[10:28:44.990] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:44.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:112:0) after 1m0s
[10:29:45.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:112:0)
[10:29:45.047] Timer tick, asking Buildbucket for the build status
[10:29:45.392] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:45.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:113:0) after 1m0s
[10:30:45.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:113:0)
[10:30:45.405] Timer tick, asking Buildbucket for the build status
[10:30:45.856] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:45.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:114:0) after 1m0s
[10:31:45.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:114:0)
[10:31:45.973] Timer tick, asking Buildbucket for the build status
[10:31:47.458] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:47.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:115:0) after 1m0s
[10:32:47.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:115:0)
[10:32:47.730] Timer tick, asking Buildbucket for the build status
[10:32:47.906] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:47.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:116:0) after 1m0s
[10:33:47.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:116:0)
[10:33:47.988] Timer tick, asking Buildbucket for the build status
[10:33:48.170] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:48.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:117:0) after 1m0s
[10:34:48.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:117:0)
[10:34:48.162] Timer tick, asking Buildbucket for the build status
[10:34:48.295] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:48.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:118:0) after 1m0s
[10:35:48.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:118:0)
[10:35:48.363] Timer tick, asking Buildbucket for the build status
[10:35:48.562] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:48.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:119:0) after 1m0s
[10:36:48.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:119:0)
[10:36:48.640] Timer tick, asking Buildbucket for the build status
[10:36:48.866] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:48.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:120:0) after 1m0s
[10:37:48.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:120:0)
[10:37:48.978] Timer tick, asking Buildbucket for the build status
[10:37:49.113] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:49.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:121:0) after 1m0s
[10:38:49.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:121:0)
[10:38:49.107] Timer tick, asking Buildbucket for the build status
[10:38:49.475] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:49.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:122:0) after 1m0s
[10:39:49.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:122:0)
[10:39:49.506] Timer tick, asking Buildbucket for the build status
[10:39:49.741] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:49.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:123:0) after 1m0s
[10:40:50.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:123:0)
[10:40:50.255] Timer tick, asking Buildbucket for the build status
[10:40:50.643] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:50.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:124:0) after 1m0s
[10:41:50.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:124:0)
[10:41:50.687] Timer tick, asking Buildbucket for the build status
[10:41:50.883] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:50.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:125:0) after 1m0s
[10:42:51.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:125:0)
[10:42:51.400] Timer tick, asking Buildbucket for the build status
[10:42:51.551] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:51.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:126:0) after 1m0s
[10:43:51.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:126:0)
[10:43:51.728] Timer tick, asking Buildbucket for the build status
[10:43:51.876] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:51.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:127:0) after 1m0s
[10:44:52.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:127:0)
[10:44:52.046] Timer tick, asking Buildbucket for the build status
[10:44:52.384] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:52.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:128:0) after 1m0s
[10:45:52.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:128:0)
[10:45:52.494] Timer tick, asking Buildbucket for the build status
[10:45:52.718] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:52.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:129:0) after 1m0s
[10:46:52.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:129:0)
[10:46:52.845] Timer tick, asking Buildbucket for the build status
[10:46:53.113] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:53.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:130:0) after 1m0s
[10:47:53.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:130:0)
[10:47:53.140] Timer tick, asking Buildbucket for the build status
[10:47:53.277] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:53.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:131:0) after 1m0s
[10:48:53.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:131:0)
[10:48:53.343] Timer tick, asking Buildbucket for the build status
[10:48:53.725] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:53.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:132:0) after 1m0s
[10:49:53.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:132:0)
[10:49:53.754] Timer tick, asking Buildbucket for the build status
[10:49:53.881] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:53.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:133:0) after 1m0s
[10:50:54.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:133:0)
[10:50:54.105] Timer tick, asking Buildbucket for the build status
[10:50:54.264] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:54.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:134:0) after 1m0s
[10:51:54.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:134:0)
[10:51:54.291] Timer tick, asking Buildbucket for the build status
[10:51:54.698] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:54.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:135:0) after 1m0s
[10:52:54.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:135:0)
[10:52:54.730] Timer tick, asking Buildbucket for the build status
[10:52:54.858] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:54.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:136:0) after 1m0s
[10:53:54.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:136:0)
[10:53:54.878] Timer tick, asking Buildbucket for the build status
[10:53:55.062] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:55.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:137:0) after 1m0s
[10:54:55.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:137:0)
[10:54:55.119] Timer tick, asking Buildbucket for the build status
[10:54:55.290] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:55.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:138:0) after 1m0s
[10:55:55.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:138:0)
[10:55:55.321] Timer tick, asking Buildbucket for the build status
[10:55:55.451] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:55.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:139:0) after 1m0s
[10:56:55.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:139:0)
[10:56:55.520] Timer tick, asking Buildbucket for the build status
[10:56:55.758] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:55.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:140:0) after 1m0s
[10:57:55.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:140:0)
[10:57:55.763] Timer tick, asking Buildbucket for the build status
[10:57:56.030] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:56.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:141:0) after 1m0s
[10:58:56.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:141:0)
[10:58:56.589] Timer tick, asking Buildbucket for the build status
[10:58:56.697] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:56.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:142:0) after 1m0s
[10:59:56.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:142:0)
[10:59:56.793] Timer tick, asking Buildbucket for the build status
[10:59:56.925] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:56.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:143:0) after 1m0s
[11:00:57.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:143:0)
[11:00:57.104] Timer tick, asking Buildbucket for the build status
[11:00:57.315] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:57.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:144:0) after 1m0s
[11:01:57.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:144:0)
[11:01:57.488] Timer tick, asking Buildbucket for the build status
[11:01:57.766] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:57.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:145:0) after 1m0s
[11:02:57.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:145:0)
[11:02:57.991] Timer tick, asking Buildbucket for the build status
[11:02:58.332] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:58.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:146:0) after 1m0s
[11:03:58.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:146:0)
[11:03:58.365] Timer tick, asking Buildbucket for the build status
[11:03:58.666] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:58.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:147:0) after 1m0s
[11:04:58.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:147:0)
[11:04:58.976] Timer tick, asking Buildbucket for the build status
[11:04:59.094] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:59.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:148:0) after 1m0s
[11:05:59.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:148:0)
[11:05:59.306] Timer tick, asking Buildbucket for the build status
[11:05:59.584] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:59.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:149:0) after 1m0s
[11:06:59.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:149:0)
[11:06:59.728] Timer tick, asking Buildbucket for the build status
[11:07:00.021] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:00.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:150:0) after 1m0s
[11:08:00.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:150:0)
[11:08:00.048] Timer tick, asking Buildbucket for the build status
[11:08:00.579] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:00.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:151:0) after 1m0s
[11:09:00.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:151:0)
[11:09:00.592] Timer tick, asking Buildbucket for the build status
[11:09:00.736] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:00.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:152:0) after 1m0s
[11:10:00.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:152:0)
[11:10:00.847] Timer tick, asking Buildbucket for the build status
[11:10:01.026] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:01.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:153:0) after 1m0s
[11:11:01.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:153:0)
[11:11:01.065] Timer tick, asking Buildbucket for the build status
[11:11:01.321] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:01.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:154:0) after 1m0s
[11:12:02.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:154:0)
[11:12:02.168] Timer tick, asking Buildbucket for the build status
[11:12:02.502] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:02.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:155:0) after 1m0s
[11:13:02.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:155:0)
[11:13:02.663] Timer tick, asking Buildbucket for the build status
[11:13:02.797] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:02.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:156:0) after 1m0s
[11:14:03.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:156:0)
[11:14:03.022] Timer tick, asking Buildbucket for the build status
[11:14:03.194] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:03.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:157:0) after 1m0s
[11:15:03.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:157:0)
[11:15:03.284] Timer tick, asking Buildbucket for the build status
[11:15:03.744] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:03.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:158:0) after 1m0s
[11:16:03.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:158:0)
[11:16:03.801] Timer tick, asking Buildbucket for the build status
[11:16:04.456] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:04.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:159:0) after 1m0s
[11:17:04.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:159:0)
[11:17:04.543] Timer tick, asking Buildbucket for the build status
[11:17:04.662] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:04.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:160:0) after 1m0s
[11:18:04.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:160:0)
[11:18:04.727] Timer tick, asking Buildbucket for the build status
[11:18:04.929] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:04.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:161:0) after 1m0s
[11:19:05.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:161:0)
[11:19:05.218] Timer tick, asking Buildbucket for the build status
[11:19:05.657] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:05.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:162:0) after 1m0s
[11:20:05.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:162:0)
[11:20:05.812] Timer tick, asking Buildbucket for the build status
[11:20:05.985] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:05.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:163:0) after 1m0s
[11:21:06.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:163:0)
[11:21:06.053] Timer tick, asking Buildbucket for the build status
[11:21:06.350] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:06.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:164:0) after 1m0s
[11:22:06.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:164:0)
[11:22:06.444] Timer tick, asking Buildbucket for the build status
[11:22:06.718] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:06.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:165:0) after 1m0s
[11:23:06.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:165:0)
[11:23:06.836] Timer tick, asking Buildbucket for the build status
[11:23:07.010] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:07.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:166:0) after 1m0s
[11:24:07.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:166:0)
[11:24:07.082] Timer tick, asking Buildbucket for the build status
[11:24:07.235] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:07.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:167:0) after 1m0s
[11:25:07.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:167:0)
[11:25:07.378] Timer tick, asking Buildbucket for the build status
[11:25:07.741] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:07.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:168:0) after 1m0s
[11:26:07.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:168:0)
[11:26:07.796] Timer tick, asking Buildbucket for the build status
[11:26:07.966] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:07.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:169:0) after 1m0s
[11:27:08.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:169:0)
[11:27:08.005] Timer tick, asking Buildbucket for the build status
[11:27:08.175] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:08.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:170:0) after 1m0s
[11:28:08.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:170:0)
[11:28:08.509] Timer tick, asking Buildbucket for the build status
[11:28:08.642] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:08.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:171:0) after 1m0s
[11:29:08.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:171:0)
[11:29:08.755] Timer tick, asking Buildbucket for the build status
[11:29:08.907] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:08.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:172:0) after 1m0s
[11:30:09.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:172:0)
[11:30:09.005] Timer tick, asking Buildbucket for the build status
[11:30:09.224] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:09.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:173:0) after 1m0s
[11:31:09.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:173:0)
[11:31:09.307] Timer tick, asking Buildbucket for the build status
[11:31:09.534] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:09.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:174:0) after 1m0s
[11:32:09.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:174:0)
[11:32:09.658] Timer tick, asking Buildbucket for the build status
[11:32:09.807] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:09.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:175:0) after 1m0s
[11:33:09.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:175:0)
[11:33:09.932] Timer tick, asking Buildbucket for the build status
[11:33:10.044] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:10.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:176:0) after 1m0s
[11:34:10.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:176:0)
[11:34:10.074] Timer tick, asking Buildbucket for the build status
[11:34:10.175] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:10.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:177:0) after 1m0s
[11:35:10.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:177:0)
[11:35:10.230] Timer tick, asking Buildbucket for the build status
[11:35:10.342] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:10.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:178:0) after 1m0s
[11:36:10.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:178:0)
[11:36:10.401] Timer tick, asking Buildbucket for the build status
[11:36:10.654] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:10.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:179:0) after 1m0s
[11:37:10.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:179:0)
[11:37:10.706] Timer tick, asking Buildbucket for the build status
[11:37:10.909] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:10.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:180:0) after 1m0s
[11:38:10.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:180:0)
[11:38:10.972] Timer tick, asking Buildbucket for the build status
[11:38:11.127] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:11.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:181:0) after 1m0s
[11:39:11.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:181:0)
[11:39:11.175] Timer tick, asking Buildbucket for the build status
[11:39:11.746] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:11.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:182:0) after 1m0s
[11:40:11.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:182:0)
[11:40:11.831] Timer tick, asking Buildbucket for the build status
[11:40:11.953] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:11.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:183:0) after 1m0s
[11:41:12.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:183:0)
[11:41:12.004] Timer tick, asking Buildbucket for the build status
[11:41:12.703] Build 8877802557529901856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:12.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043232619641302720:184:0) after 1m0s
[11:41:35.646] Received PubSub notification, asking Buildbucket for the build status
[11:41:36.068] Build 8877802557529901856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:41:36.068] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1591875694949102",
    "created_by": "project:infra",
    "created_ts": "1591864736966747",
    "id": "8877802557529901856",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4147.44\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"e94b3a0c-0d27-4103-bbd4-d09ec0145db6\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4147.44\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-g4pg\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"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\": [\"5199-07c11c0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1591864764100964",
    "status": "COMPLETED",
    "status_changed_ts": "1591875695311578",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9043232619641302720",
      "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/8877802557529901856/+/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:4cbb59c7a6841d10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1591875695326738",
    "url": "https://ci.chromium.org/b/8877802557529901856",
    "utcnow_ts": "1591875696058668"
  }
}
[11:41:36.068] Invocation finished in 3h2m40.500669601s with status SUCCEEDED