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

• parent_buildername:publish_tarball
• user_agent:recipe

[10:47:46.223] New invocation is queued and will start shortly
[10:47:46.223] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:47:47.304] Starting the invocation (attempt 1)
[10:47:47.349] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:47:47.349] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:47:47.349] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9044402274047073584",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4159.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"d1076c51-37c1-47c2-84c6-eb2ce4cb4228\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4159.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:9044402274047073584",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[10:47:48.805] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1590749267628688",
    "id": "8878972211905696272",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4159.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d1076c51-37c1-47c2-84c6-eb2ce4cb4228\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4159.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1590749268400230",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9044402274047073584",
      "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/8878972211905696272/+/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": "1590749268401501",
    "url": "https://ci.chromium.org/b/8878972211905696272",
    "utcnow_ts": "1590749268756463"
  }
}
[10:47:48.805] Task URL: https://ci.chromium.org/b/8878972211905696272
[10:47:48.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:2:0) after 1m0s
[10:48:48.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:2:0)
[10:48:48.933] Timer tick, asking Buildbucket for the build status
[10:48:49.539] Build 8878972211905696272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:49.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:3:0) after 1m0s
[10:49:49.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:3:0)
[10:49:49.621] Timer tick, asking Buildbucket for the build status
[10:49:50.169] Build 8878972211905696272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:50.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:4:0) after 1m0s
[10:50:50.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:4:0)
[10:50:50.270] Timer tick, asking Buildbucket for the build status
[10:50:50.394] Build 8878972211905696272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:50.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:5:0) after 1m0s
[10:51:50.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:5:0)
[10:51:50.408] Timer tick, asking Buildbucket for the build status
[10:51:50.733] Build 8878972211905696272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:50.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:6:0) after 1m0s
[10:52:50.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:6:0)
[10:52:50.816] Timer tick, asking Buildbucket for the build status
[10:52:51.054] Build 8878972211905696272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:51.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:7:0) after 1m0s
[10:53:51.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:7:0)
[10:53:51.113] Timer tick, asking Buildbucket for the build status
[10:53:51.256] Build 8878972211905696272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:51.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:8:0) after 1m0s
[10:54:51.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:8:0)
[10:54:51.309] Timer tick, asking Buildbucket for the build status
[10:54:51.480] Build 8878972211905696272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:51.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:9:0) after 1m0s
[10:54:56.333] Received PubSub notification, asking Buildbucket for the build status
[10:54:56.490] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:51.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:9:0)
[10:55:51.535] Timer tick, asking Buildbucket for the build status
[10:55:51.941] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:51.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:11:0) after 1m0s
[10:56:51.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:11:0)
[10:56:51.930] Timer tick, asking Buildbucket for the build status
[10:56:52.195] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:52.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:12:0) after 1m0s
[10:57:52.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:12:0)
[10:57:52.212] Timer tick, asking Buildbucket for the build status
[10:57:52.805] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:52.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:13:0) after 1m0s
[10:58:52.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:13:0)
[10:58:52.870] Timer tick, asking Buildbucket for the build status
[10:58:52.996] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:52.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:14:0) after 1m0s
[10:59:53.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:14:0)
[10:59:53.078] Timer tick, asking Buildbucket for the build status
[10:59:53.259] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:53.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:15:0) after 1m0s
[11:00:53.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:15:0)
[11:00:53.290] Timer tick, asking Buildbucket for the build status
[11:00:53.714] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:53.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:16:0) after 1m0s
[11:01:53.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:16:0)
[11:01:53.733] Timer tick, asking Buildbucket for the build status
[11:01:54.468] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:54.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:17:0) after 1m0s
[11:02:54.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:17:0)
[11:02:54.566] Timer tick, asking Buildbucket for the build status
[11:02:54.708] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:54.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:18:0) after 1m0s
[11:03:54.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:18:0)
[11:03:54.799] Timer tick, asking Buildbucket for the build status
[11:03:54.861] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:54.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:19:0) after 1m0s
[11:04:54.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:19:0)
[11:04:54.898] Timer tick, asking Buildbucket for the build status
[11:04:55.114] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:55.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:20:0) after 1m0s
[11:05:55.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:20:0)
[11:05:55.175] Timer tick, asking Buildbucket for the build status
[11:05:55.404] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:55.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:21:0) after 1m0s
[11:06:55.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:21:0)
[11:06:55.435] Timer tick, asking Buildbucket for the build status
[11:06:55.576] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:55.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:22:0) after 1m0s
[11:07:55.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:22:0)
[11:07:55.614] Timer tick, asking Buildbucket for the build status
[11:07:55.757] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:55.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:23:0) after 1m0s
[11:08:55.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:23:0)
[11:08:55.791] Timer tick, asking Buildbucket for the build status
[11:08:56.676] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:56.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:24:0) after 1m0s
[11:09:56.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:24:0)
[11:09:56.699] Timer tick, asking Buildbucket for the build status
[11:09:56.804] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:56.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:25:0) after 1m0s
[11:10:56.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:25:0)
[11:10:56.877] Timer tick, asking Buildbucket for the build status
[11:10:57.012] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:57.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:26:0) after 1m0s
[11:11:57.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:26:0)
[11:11:57.107] Timer tick, asking Buildbucket for the build status
[11:11:57.263] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:57.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:27:0) after 1m0s
[11:12:57.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:27:0)
[11:12:57.287] Timer tick, asking Buildbucket for the build status
[11:12:57.472] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:57.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:28:0) after 1m0s
[11:13:57.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:28:0)
[11:13:57.501] Timer tick, asking Buildbucket for the build status
[11:13:57.656] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:57.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:29:0) after 1m0s
[11:14:57.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:29:0)
[11:14:57.782] Timer tick, asking Buildbucket for the build status
[11:14:58.277] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:58.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:30:0) after 1m0s
[11:15:58.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:30:0)
[11:15:58.344] Timer tick, asking Buildbucket for the build status
[11:15:58.631] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:58.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:31:0) after 1m0s
[11:16:58.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:31:0)
[11:16:58.805] Timer tick, asking Buildbucket for the build status
[11:16:59.042] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:59.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:32:0) after 1m0s
[11:17:59.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:32:0)
[11:17:59.096] Timer tick, asking Buildbucket for the build status
[11:17:59.348] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:59.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:33:0) after 1m0s
[11:18:59.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:33:0)
[11:18:59.432] Timer tick, asking Buildbucket for the build status
[11:18:59.608] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:59.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:34:0) after 1m0s
[11:19:59.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:34:0)
[11:19:59.664] Timer tick, asking Buildbucket for the build status
[11:19:59.957] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:59.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:35:0) after 1m0s
[11:21:00.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:35:0)
[11:21:00.002] Timer tick, asking Buildbucket for the build status
[11:21:00.698] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:00.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:36:0) after 1m0s
[11:22:01.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:36:0)
[11:22:01.459] Timer tick, asking Buildbucket for the build status
[11:22:01.777] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:01.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:37:0) after 1m0s
[11:23:02.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:37:0)
[11:23:02.917] Timer tick, asking Buildbucket for the build status
[11:23:03.189] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:03.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:38:0) after 1m0s
[11:24:03.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:38:0)
[11:24:03.193] Timer tick, asking Buildbucket for the build status
[11:24:03.513] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:03.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:39:0) after 1m0s
[11:25:03.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:39:0)
[11:25:03.531] Timer tick, asking Buildbucket for the build status
[11:25:03.707] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:03.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:40:0) after 1m0s
[11:26:03.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:40:0)
[11:26:03.765] Timer tick, asking Buildbucket for the build status
[11:26:04.007] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:04.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:41:0) after 1m0s
[11:27:04.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:41:0)
[11:27:04.105] Timer tick, asking Buildbucket for the build status
[11:27:04.883] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:04.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:42:0) after 1m0s
[11:28:04.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:42:0)
[11:28:04.878] Timer tick, asking Buildbucket for the build status
[11:28:05.304] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:05.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:43:0) after 1m0s
[11:29:05.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:43:0)
[11:29:05.419] Timer tick, asking Buildbucket for the build status
[11:29:05.547] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:05.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:44:0) after 1m0s
[11:30:05.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:44:0)
[11:30:05.625] Timer tick, asking Buildbucket for the build status
[11:30:06.193] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:06.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:45:0) after 1m0s
[11:31:06.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:45:0)
[11:31:06.257] Timer tick, asking Buildbucket for the build status
[11:31:06.908] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:06.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:46:0) after 1m0s
[11:32:07.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:46:0)
[11:32:07.029] Timer tick, asking Buildbucket for the build status
[11:32:07.202] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:07.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:47:0) after 1m0s
[11:33:07.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:47:0)
[11:33:07.222] Timer tick, asking Buildbucket for the build status
[11:33:07.338] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:07.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:48:0) after 1m0s
[11:34:07.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:48:0)
[11:34:07.361] Timer tick, asking Buildbucket for the build status
[11:34:07.655] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:07.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:49:0) after 1m0s
[11:35:07.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:49:0)
[11:35:07.687] Timer tick, asking Buildbucket for the build status
[11:35:07.807] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:07.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:50:0) after 1m0s
[11:36:07.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:50:0)
[11:36:07.865] Timer tick, asking Buildbucket for the build status
[11:36:08.081] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:08.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:51:0) after 1m0s
[11:37:08.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:51:0)
[11:37:08.139] Timer tick, asking Buildbucket for the build status
[11:37:08.441] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:08.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:52:0) after 1m0s
[11:38:08.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:52:0)
[11:38:08.685] Timer tick, asking Buildbucket for the build status
[11:38:08.856] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:08.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:53:0) after 1m0s
[11:39:08.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:53:0)
[11:39:08.904] Timer tick, asking Buildbucket for the build status
[11:39:09.131] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:09.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:54:0) after 1m0s
[11:40:09.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:54:0)
[11:40:09.172] Timer tick, asking Buildbucket for the build status
[11:40:09.620] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:09.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:55:0) after 1m0s
[11:41:09.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:55:0)
[11:41:09.643] Timer tick, asking Buildbucket for the build status
[11:41:09.895] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:09.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:56:0) after 1m0s
[11:42:09.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:56:0)
[11:42:09.971] Timer tick, asking Buildbucket for the build status
[11:42:10.213] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:10.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:57:0) after 1m0s
[11:43:10.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:57:0)
[11:43:10.301] Timer tick, asking Buildbucket for the build status
[11:43:10.988] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:10.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:58:0) after 1m0s
[11:44:10.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:58:0)
[11:44:10.997] Timer tick, asking Buildbucket for the build status
[11:44:11.155] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:11.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:59:0) after 1m0s
[11:45:11.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:59:0)
[11:45:11.243] Timer tick, asking Buildbucket for the build status
[11:45:11.554] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:11.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:60:0) after 1m0s
[11:46:11.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:60:0)
[11:46:11.575] Timer tick, asking Buildbucket for the build status
[11:46:11.813] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:11.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:61:0) after 1m0s
[11:47:11.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:61:0)
[11:47:11.835] Timer tick, asking Buildbucket for the build status
[11:47:12.053] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:12.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:62:0) after 1m0s
[11:48:12.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:62:0)
[11:48:12.093] Timer tick, asking Buildbucket for the build status
[11:48:12.477] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:12.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:63:0) after 1m0s
[11:49:12.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:63:0)
[11:49:12.530] Timer tick, asking Buildbucket for the build status
[11:49:13.064] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:13.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:64:0) after 1m0s
[11:50:13.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:64:0)
[11:50:13.098] Timer tick, asking Buildbucket for the build status
[11:50:13.265] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:13.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:65:0) after 1m0s
[11:51:13.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:65:0)
[11:51:13.407] Timer tick, asking Buildbucket for the build status
[11:51:13.669] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:13.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:66:0) after 1m0s
[11:52:13.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:66:0)
[11:52:13.769] Timer tick, asking Buildbucket for the build status
[11:52:13.976] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:13.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:67:0) after 1m0s
[11:53:14.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:67:0)
[11:53:14.069] Timer tick, asking Buildbucket for the build status
[11:53:14.189] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:14.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:68:0) after 1m0s
[11:54:14.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:68:0)
[11:54:14.255] Timer tick, asking Buildbucket for the build status
[11:54:14.629] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:14.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:69:0) after 1m0s
[11:55:14.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:69:0)
[11:55:14.613] Timer tick, asking Buildbucket for the build status
[11:55:14.792] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:14.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:70:0) after 1m0s
[11:56:14.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:70:0)
[11:56:14.812] Timer tick, asking Buildbucket for the build status
[11:56:15.359] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:15.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:71:0) after 1m0s
[11:57:15.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:71:0)
[11:57:15.383] Timer tick, asking Buildbucket for the build status
[11:57:15.586] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:15.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:72:0) after 1m0s
[11:58:15.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:72:0)
[11:58:15.632] Timer tick, asking Buildbucket for the build status
[11:58:17.065] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:17.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:73:0) after 1m0s
[11:59:17.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:73:0)
[11:59:17.092] Timer tick, asking Buildbucket for the build status
[11:59:17.343] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:17.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:74:0) after 1m0s
[12:00:17.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:74:0)
[12:00:17.371] Timer tick, asking Buildbucket for the build status
[12:00:18.185] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:18.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:75:0) after 1m0s
[12:01:18.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:75:0)
[12:01:18.224] Timer tick, asking Buildbucket for the build status
[12:01:18.450] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:18.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:76:0) after 1m0s
[12:02:18.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:76:0)
[12:02:18.478] Timer tick, asking Buildbucket for the build status
[12:02:18.692] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:18.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:77:0) after 1m0s
[12:03:18.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:77:0)
[12:03:18.768] Timer tick, asking Buildbucket for the build status
[12:03:18.965] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:18.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:78:0) after 1m0s
[12:04:19.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:78:0)
[12:04:19.025] Timer tick, asking Buildbucket for the build status
[12:04:19.250] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:19.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:79:0) after 1m0s
[12:05:19.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:79:0)
[12:05:19.276] Timer tick, asking Buildbucket for the build status
[12:05:19.913] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:19.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:80:0) after 1m0s
[12:06:19.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:80:0)
[12:06:19.938] Timer tick, asking Buildbucket for the build status
[12:06:20.073] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:20.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:81:0) after 1m0s
[12:07:20.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:81:0)
[12:07:20.147] Timer tick, asking Buildbucket for the build status
[12:07:20.350] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:20.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:82:0) after 1m0s
[12:08:20.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:82:0)
[12:08:20.412] Timer tick, asking Buildbucket for the build status
[12:08:20.718] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:20.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:83:0) after 1m0s
[12:09:20.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:83:0)
[12:09:20.785] Timer tick, asking Buildbucket for the build status
[12:09:20.951] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:20.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:84:0) after 1m0s
[12:10:21.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:84:0)
[12:10:21.036] Timer tick, asking Buildbucket for the build status
[12:10:21.272] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:21.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:85:0) after 1m0s
[12:11:21.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:85:0)
[12:11:21.361] Timer tick, asking Buildbucket for the build status
[12:11:21.663] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:21.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:86:0) after 1m0s
[12:12:21.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:86:0)
[12:12:21.684] Timer tick, asking Buildbucket for the build status
[12:12:21.838] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:21.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:87:0) after 1m0s
[12:13:21.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:87:0)
[12:13:21.925] Timer tick, asking Buildbucket for the build status
[12:13:22.295] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:22.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:88:0) after 1m0s
[12:14:22.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:88:0)
[12:14:22.385] Timer tick, asking Buildbucket for the build status
[12:14:22.580] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:22.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:89:0) after 1m0s
[12:15:22.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:89:0)
[12:15:22.670] Timer tick, asking Buildbucket for the build status
[12:15:22.873] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:22.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:90:0) after 1m0s
[12:16:22.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:90:0)
[12:16:22.895] Timer tick, asking Buildbucket for the build status
[12:16:23.577] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:23.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:91:0) after 1m0s
[12:17:23.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:91:0)
[12:17:23.602] Timer tick, asking Buildbucket for the build status
[12:17:23.931] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:23.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:92:0) after 1m0s
[12:18:23.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:92:0)
[12:18:23.952] Timer tick, asking Buildbucket for the build status
[12:18:24.114] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:24.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:93:0) after 1m0s
[12:19:24.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:93:0)
[12:19:24.170] Timer tick, asking Buildbucket for the build status
[12:19:24.303] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:24.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:94:0) after 1m0s
[12:20:24.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:94:0)
[12:20:24.463] Timer tick, asking Buildbucket for the build status
[12:20:24.805] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:24.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:95:0) after 1m0s
[12:21:24.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:95:0)
[12:21:24.798] Timer tick, asking Buildbucket for the build status
[12:21:25.317] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:25.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:96:0) after 1m0s
[12:22:25.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:96:0)
[12:22:25.327] Timer tick, asking Buildbucket for the build status
[12:22:25.468] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:25.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:97:0) after 1m0s
[12:23:25.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:97:0)
[12:23:25.555] Timer tick, asking Buildbucket for the build status
[12:23:25.724] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:25.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:98:0) after 1m0s
[12:24:25.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:98:0)
[12:24:25.781] Timer tick, asking Buildbucket for the build status
[12:24:26.133] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:26.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:99:0) after 1m0s
[12:25:26.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:99:0)
[12:25:26.199] Timer tick, asking Buildbucket for the build status
[12:25:26.530] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:26.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:100:0) after 1m0s
[12:26:26.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:100:0)
[12:26:26.564] Timer tick, asking Buildbucket for the build status
[12:26:26.724] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:26.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:101:0) after 1m0s
[12:27:26.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:101:0)
[12:27:26.993] Timer tick, asking Buildbucket for the build status
[12:27:27.579] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:27.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:102:0) after 1m0s
[12:28:27.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:102:0)
[12:28:27.694] Timer tick, asking Buildbucket for the build status
[12:28:27.831] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:27.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:103:0) after 1m0s
[12:29:27.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:103:0)
[12:29:27.856] Timer tick, asking Buildbucket for the build status
[12:29:28.091] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:28.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:104:0) after 1m0s
[12:30:28.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:104:0)
[12:30:28.200] Timer tick, asking Buildbucket for the build status
[12:30:28.443] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:28.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:105:0) after 1m0s
[12:31:28.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:105:0)
[12:31:28.483] Timer tick, asking Buildbucket for the build status
[12:31:28.875] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:28.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:106:0) after 1m0s
[12:32:28.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:106:0)
[12:32:28.988] Timer tick, asking Buildbucket for the build status
[12:32:29.187] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:29.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:107:0) after 1m0s
[12:33:29.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:107:0)
[12:33:29.276] Timer tick, asking Buildbucket for the build status
[12:33:29.422] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:29.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:108:0) after 1m0s
[12:34:29.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:108:0)
[12:34:29.468] Timer tick, asking Buildbucket for the build status
[12:34:29.604] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:29.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:109:0) after 1m0s
[12:35:29.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:109:0)
[12:35:29.629] Timer tick, asking Buildbucket for the build status
[12:35:29.753] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:29.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:110:0) after 1m0s
[12:36:29.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:110:0)
[12:36:29.795] Timer tick, asking Buildbucket for the build status
[12:36:30.766] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:30.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:111:0) after 1m0s
[12:37:30.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:111:0)
[12:37:30.882] Timer tick, asking Buildbucket for the build status
[12:37:31.031] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:31.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:112:0) after 1m0s
[12:38:31.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:112:0)
[12:38:31.093] Timer tick, asking Buildbucket for the build status
[12:38:31.176] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:31.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:113:0) after 1m0s
[12:39:31.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:113:0)
[12:39:31.213] Timer tick, asking Buildbucket for the build status
[12:39:31.394] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:31.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:114:0) after 1m0s
[12:40:31.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:114:0)
[12:40:31.441] Timer tick, asking Buildbucket for the build status
[12:40:31.542] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:31.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:115:0) after 1m0s
[12:41:31.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:115:0)
[12:41:31.572] Timer tick, asking Buildbucket for the build status
[12:41:31.780] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:31.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:116:0) after 1m0s
[12:42:31.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:116:0)
[12:42:31.812] Timer tick, asking Buildbucket for the build status
[12:42:32.408] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:32.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:117:0) after 1m0s
[12:43:32.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:117:0)
[12:43:32.461] Timer tick, asking Buildbucket for the build status
[12:43:32.563] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:32.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:118:0) after 1m0s
[12:44:32.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:118:0)
[12:44:32.709] Timer tick, asking Buildbucket for the build status
[12:44:32.992] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:32.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:119:0) after 1m0s
[12:45:33.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:119:0)
[12:45:33.121] Timer tick, asking Buildbucket for the build status
[12:45:33.251] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:33.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:120:0) after 1m0s
[12:46:33.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:120:0)
[12:46:33.349] Timer tick, asking Buildbucket for the build status
[12:46:33.579] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:33.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:121:0) after 1m0s
[12:47:33.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:121:0)
[12:47:33.692] Timer tick, asking Buildbucket for the build status
[12:47:34.706] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:34.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:122:0) after 1m0s
[12:48:34.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:122:0)
[12:48:34.823] Timer tick, asking Buildbucket for the build status
[12:48:34.952] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:34.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:123:0) after 1m0s
[12:49:35.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:123:0)
[12:49:35.072] Timer tick, asking Buildbucket for the build status
[12:49:35.216] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:35.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:124:0) after 1m0s
[12:50:35.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:124:0)
[12:50:35.421] Timer tick, asking Buildbucket for the build status
[12:50:35.866] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:35.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:125:0) after 1m0s
[12:51:35.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:125:0)
[12:51:35.967] Timer tick, asking Buildbucket for the build status
[12:51:36.696] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:36.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:126:0) after 1m0s
[12:52:36.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:126:0)
[12:52:36.701] Timer tick, asking Buildbucket for the build status
[12:52:37.356] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:37.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:127:0) after 1m0s
[12:53:37.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:127:0)
[12:53:37.480] Timer tick, asking Buildbucket for the build status
[12:53:37.566] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:37.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:128:0) after 1m0s
[12:54:37.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:128:0)
[12:54:37.603] Timer tick, asking Buildbucket for the build status
[12:54:38.300] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:38.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:129:0) after 1m0s
[12:55:38.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:129:0)
[12:55:38.461] Timer tick, asking Buildbucket for the build status
[12:55:38.692] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:38.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:130:0) after 1m0s
[12:56:38.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:130:0)
[12:56:38.764] Timer tick, asking Buildbucket for the build status
[12:56:38.915] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:38.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:131:0) after 1m0s
[12:57:39.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:131:0)
[12:57:39.003] Timer tick, asking Buildbucket for the build status
[12:57:39.266] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:39.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:132:0) after 1m0s
[12:58:39.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:132:0)
[12:58:39.279] Timer tick, asking Buildbucket for the build status
[12:58:39.440] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:39.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:133:0) after 1m0s
[12:59:39.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:133:0)
[12:59:39.549] Timer tick, asking Buildbucket for the build status
[12:59:39.771] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:39.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:134:0) after 1m0s
[13:00:39.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:134:0)
[13:00:39.814] Timer tick, asking Buildbucket for the build status
[13:00:40.347] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:40.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:135:0) after 1m0s
[13:01:40.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:135:0)
[13:01:40.387] Timer tick, asking Buildbucket for the build status
[13:01:40.727] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:40.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:136:0) after 1m0s
[13:02:40.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:136:0)
[13:02:40.825] Timer tick, asking Buildbucket for the build status
[13:02:41.475] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:41.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:137:0) after 1m0s
[13:03:41.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:137:0)
[13:03:41.510] Timer tick, asking Buildbucket for the build status
[13:03:41.708] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:41.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:138:0) after 1m0s
[13:04:41.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:138:0)
[13:04:41.787] Timer tick, asking Buildbucket for the build status
[13:04:41.913] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:41.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:139:0) after 1m0s
[13:05:41.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:139:0)
[13:05:41.939] Timer tick, asking Buildbucket for the build status
[13:05:42.115] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:42.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:140:0) after 1m0s
[13:06:42.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:140:0)
[13:06:42.176] Timer tick, asking Buildbucket for the build status
[13:06:42.297] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:42.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:141:0) after 1m0s
[13:07:42.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:141:0)
[13:07:42.308] Timer tick, asking Buildbucket for the build status
[13:07:42.969] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:42.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:142:0) after 1m0s
[13:08:43.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:142:0)
[13:08:43.062] Timer tick, asking Buildbucket for the build status
[13:08:43.253] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:43.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:143:0) after 1m0s
[13:09:43.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:143:0)
[13:09:43.265] Timer tick, asking Buildbucket for the build status
[13:09:43.563] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:43.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:144:0) after 1m0s
[13:10:43.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:144:0)
[13:10:43.549] Timer tick, asking Buildbucket for the build status
[13:10:43.658] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:43.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:145:0) after 1m0s
[13:11:43.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:145:0)
[13:11:43.781] Timer tick, asking Buildbucket for the build status
[13:11:44.161] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:44.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:146:0) after 1m0s
[13:12:44.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:146:0)
[13:12:44.225] Timer tick, asking Buildbucket for the build status
[13:12:44.575] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:44.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:147:0) after 1m0s
[13:13:44.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:147:0)
[13:13:44.627] Timer tick, asking Buildbucket for the build status
[13:13:45.101] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:45.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:148:0) after 1m0s
[13:14:45.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:148:0)
[13:14:45.107] Timer tick, asking Buildbucket for the build status
[13:14:45.279] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:45.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:149:0) after 1m0s
[13:15:45.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:149:0)
[13:15:45.356] Timer tick, asking Buildbucket for the build status
[13:15:45.758] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:45.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:150:0) after 1m0s
[13:16:45.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:150:0)
[13:16:45.797] Timer tick, asking Buildbucket for the build status
[13:16:45.963] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:45.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:151:0) after 1m0s
[13:17:46.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:151:0)
[13:17:46.083] Timer tick, asking Buildbucket for the build status
[13:17:46.246] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:46.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:152:0) after 1m0s
[13:18:46.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:152:0)
[13:18:46.275] Timer tick, asking Buildbucket for the build status
[13:18:46.466] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:46.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:153:0) after 1m0s
[13:19:46.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:153:0)
[13:19:46.502] Timer tick, asking Buildbucket for the build status
[13:19:46.676] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:46.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:154:0) after 1m0s
[13:20:46.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:154:0)
[13:20:46.734] Timer tick, asking Buildbucket for the build status
[13:20:47.054] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:47.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:155:0) after 1m0s
[13:21:47.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:155:0)
[13:21:47.133] Timer tick, asking Buildbucket for the build status
[13:21:47.294] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:47.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:156:0) after 1m0s
[13:22:47.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:156:0)
[13:22:47.354] Timer tick, asking Buildbucket for the build status
[13:22:48.611] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:48.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:157:0) after 1m0s
[13:23:48.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:157:0)
[13:23:48.698] Timer tick, asking Buildbucket for the build status
[13:23:48.883] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:48.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:158:0) after 1m0s
[13:24:48.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:158:0)
[13:24:48.963] Timer tick, asking Buildbucket for the build status
[13:24:49.093] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:49.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:159:0) after 1m0s
[13:25:49.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:159:0)
[13:25:49.153] Timer tick, asking Buildbucket for the build status
[13:25:50.213] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:50.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:160:0) after 1m0s
[13:26:50.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:160:0)
[13:26:50.251] Timer tick, asking Buildbucket for the build status
[13:26:50.471] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:50.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:161:0) after 1m0s
[13:27:50.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:161:0)
[13:27:50.655] Timer tick, asking Buildbucket for the build status
[13:27:50.810] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:50.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:162:0) after 1m0s
[13:28:50.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:162:0)
[13:28:50.881] Timer tick, asking Buildbucket for the build status
[13:28:51.048] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:51.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:163:0) after 1m0s
[13:29:51.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:163:0)
[13:29:51.247] Timer tick, asking Buildbucket for the build status
[13:29:51.579] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:51.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:164:0) after 1m0s
[13:30:51.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:164:0)
[13:30:51.743] Timer tick, asking Buildbucket for the build status
[13:30:52.321] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:52.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:165:0) after 1m0s
[13:31:52.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:165:0)
[13:31:52.392] Timer tick, asking Buildbucket for the build status
[13:31:52.511] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:52.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:166:0) after 1m0s
[13:32:52.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:166:0)
[13:32:52.581] Timer tick, asking Buildbucket for the build status
[13:32:52.831] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:52.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:167:0) after 1m0s
[13:33:52.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:167:0)
[13:33:52.992] Timer tick, asking Buildbucket for the build status
[13:33:53.164] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:53.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:168:0) after 1m0s
[13:34:53.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:168:0)
[13:34:53.375] Timer tick, asking Buildbucket for the build status
[13:34:53.662] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:53.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:169:0) after 1m0s
[13:35:53.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:169:0)
[13:35:53.770] Timer tick, asking Buildbucket for the build status
[13:35:54.135] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:54.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:170:0) after 1m0s
[13:36:54.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:170:0)
[13:36:54.308] Timer tick, asking Buildbucket for the build status
[13:36:54.444] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:54.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:171:0) after 1m0s
[13:37:54.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:171:0)
[13:37:54.506] Timer tick, asking Buildbucket for the build status
[13:37:54.681] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:54.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:172:0) after 1m0s
[13:38:54.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:172:0)
[13:38:54.667] Timer tick, asking Buildbucket for the build status
[13:38:54.773] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:54.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:173:0) after 1m0s
[13:39:55.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:173:0)
[13:39:55.022] Timer tick, asking Buildbucket for the build status
[13:39:55.181] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:55.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:174:0) after 1m0s
[13:40:55.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:174:0)
[13:40:55.246] Timer tick, asking Buildbucket for the build status
[13:40:55.402] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:55.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:175:0) after 1m0s
[13:41:55.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:175:0)
[13:41:55.450] Timer tick, asking Buildbucket for the build status
[13:41:55.617] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:55.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:176:0) after 1m0s
[13:42:55.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:176:0)
[13:42:55.720] Timer tick, asking Buildbucket for the build status
[13:42:55.883] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:55.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:177:0) after 1m0s
[13:43:56.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:177:0)
[13:43:56.034] Timer tick, asking Buildbucket for the build status
[13:43:56.228] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:56.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:178:0) after 1m0s
[13:44:56.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:178:0)
[13:44:56.214] Timer tick, asking Buildbucket for the build status
[13:44:56.431] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:56.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:179:0) after 1m0s
[13:45:56.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:179:0)
[13:45:56.450] Timer tick, asking Buildbucket for the build status
[13:45:56.885] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:56.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:180:0) after 1m0s
[13:46:57.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:180:0)
[13:46:57.011] Timer tick, asking Buildbucket for the build status
[13:46:57.332] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:57.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:181:0) after 1m0s
[13:47:57.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:181:0)
[13:47:57.394] Timer tick, asking Buildbucket for the build status
[13:47:57.533] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:57.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:182:0) after 1m0s
[13:48:57.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:182:0)
[13:48:57.585] Timer tick, asking Buildbucket for the build status
[13:48:57.894] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:57.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:183:0) after 1m0s
[13:49:57.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:183:0)
[13:49:57.927] Timer tick, asking Buildbucket for the build status
[13:49:58.221] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:58.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:184:0) after 1m0s
[13:50:58.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:184:0)
[13:50:58.246] Timer tick, asking Buildbucket for the build status
[13:50:58.448] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:58.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:185:0) after 1m0s
[13:51:58.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:185:0)
[13:51:58.513] Timer tick, asking Buildbucket for the build status
[13:51:58.669] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:58.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:186:0) after 1m0s
[13:52:58.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:186:0)
[13:52:58.709] Timer tick, asking Buildbucket for the build status
[13:52:59.245] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:59.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:187:0) after 1m0s
[13:53:59.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:187:0)
[13:53:59.318] Timer tick, asking Buildbucket for the build status
[13:53:59.431] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:59.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:188:0) after 1m0s
[13:54:59.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:188:0)
[13:54:59.494] Timer tick, asking Buildbucket for the build status
[13:54:59.706] Build 8878972211905696272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:59.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044402274047073584:189:0) after 1m0s
[13:55:10.202] Received PubSub notification, asking Buildbucket for the build status
[13:55:10.305] Build 8878972211905696272: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:55:10.305] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1590760509065781",
    "created_by": "project:infra",
    "created_ts": "1590749267628688",
    "id": "8878972211905696272",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4159.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d1076c51-37c1-47c2-84c6-eb2ce4cb4228\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4159.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-7zkx\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5142-e16b32c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1590749695387057",
    "status": "COMPLETED",
    "status_changed_ts": "1590760509513914",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9044402274047073584",
      "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/8878972211905696272/+/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:4c78dd1400a53d10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1590760509646546",
    "url": "https://ci.chromium.org/b/8878972211905696272",
    "utcnow_ts": "1590760510297799"
  }
}
[13:55:10.305] Invocation finished in 3h7m24.102851777s with status SUCCEEDED