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

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

{
  "version": "88.0.4321.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[10:49:41.160] New invocation is queued and will start shortly
[10:49:41.160] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:49:42.568] Starting the invocation (attempt 1)
[10:49:42.641] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:49:42.641] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:49:42.642] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9029453654068766912",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4321.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"0b41de0c-6bf4-4400-8909-d612f016abdf\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4321.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:9029453654068766912",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[10:49:43.252] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1605005382848208",
    "id": "8864023591633518784",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4321.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"0b41de0c-6bf4-4400-8909-d612f016abdf\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4321.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1605005383095384",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029453654068766912",
      "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/8864023591633518784/+/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": "1605005383095541",
    "url": "https://ci.chromium.org/b/8864023591633518784",
    "utcnow_ts": "1605005383235039"
  }
}
[10:49:43.252] Task URL: https://ci.chromium.org/b/8864023591633518784
[10:49:43.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:2:0) after 1m0s
[10:50:26.937] Received PubSub notification, asking Buildbucket for the build status
[10:50:27.273] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:43.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:2:0)
[10:50:43.341] Timer tick, asking Buildbucket for the build status
[10:50:43.835] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:43.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:4:0) after 1m0s
[10:51:44.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:4:0)
[10:51:44.002] Timer tick, asking Buildbucket for the build status
[10:51:44.241] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:44.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:5:0) after 1m0s
[10:52:44.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:5:0)
[10:52:44.593] Timer tick, asking Buildbucket for the build status
[10:52:44.678] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:44.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:6:0) after 1m0s
[10:53:44.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:6:0)
[10:53:44.803] Timer tick, asking Buildbucket for the build status
[10:53:45.047] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:45.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:7:0) after 1m0s
[10:54:45.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:7:0)
[10:54:45.051] Timer tick, asking Buildbucket for the build status
[10:54:45.186] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:45.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:8:0) after 1m0s
[10:55:45.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:8:0)
[10:55:45.349] Timer tick, asking Buildbucket for the build status
[10:55:45.413] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:45.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:9:0) after 1m0s
[10:56:45.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:9:0)
[10:56:45.482] Timer tick, asking Buildbucket for the build status
[10:56:45.561] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:45.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:10:0) after 1m0s
[10:57:45.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:10:0)
[10:57:45.593] Timer tick, asking Buildbucket for the build status
[10:57:46.077] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:46.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:11:0) after 1m0s
[10:58:46.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:11:0)
[10:58:46.088] Timer tick, asking Buildbucket for the build status
[10:58:46.227] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:46.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:12:0) after 1m0s
[10:59:46.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:12:0)
[10:59:46.252] Timer tick, asking Buildbucket for the build status
[10:59:46.382] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:46.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:13:0) after 1m0s
[11:00:46.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:13:0)
[11:00:46.513] Timer tick, asking Buildbucket for the build status
[11:00:46.702] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:46.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:14:0) after 1m0s
[11:01:47.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:14:0)
[11:01:47.306] Timer tick, asking Buildbucket for the build status
[11:01:47.411] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:47.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:15:0) after 1m0s
[11:02:47.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:15:0)
[11:02:47.559] Timer tick, asking Buildbucket for the build status
[11:02:47.665] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:47.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:16:0) after 1m0s
[11:03:47.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:16:0)
[11:03:47.880] Timer tick, asking Buildbucket for the build status
[11:03:49.125] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:49.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:17:0) after 1m0s
[11:04:49.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:17:0)
[11:04:49.546] Timer tick, asking Buildbucket for the build status
[11:04:49.874] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:49.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:18:0) after 1m0s
[11:05:49.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:18:0)
[11:05:49.941] Timer tick, asking Buildbucket for the build status
[11:05:50.052] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:50.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:19:0) after 1m0s
[11:06:50.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:19:0)
[11:06:50.427] Timer tick, asking Buildbucket for the build status
[11:06:50.524] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:50.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:20:0) after 1m0s
[11:07:50.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:20:0)
[11:07:50.688] Timer tick, asking Buildbucket for the build status
[11:07:50.905] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:50.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:21:0) after 1m0s
[11:08:51.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:21:0)
[11:08:51.049] Timer tick, asking Buildbucket for the build status
[11:08:51.286] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:51.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:22:0) after 1m0s
[11:09:51.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:22:0)
[11:09:51.327] Timer tick, asking Buildbucket for the build status
[11:09:51.793] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:51.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:23:0) after 1m0s
[11:10:51.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:23:0)
[11:10:51.874] Timer tick, asking Buildbucket for the build status
[11:10:51.978] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:51.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:24:0) after 1m0s
[11:11:52.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:24:0)
[11:11:52.005] Timer tick, asking Buildbucket for the build status
[11:11:52.087] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:52.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:25:0) after 1m0s
[11:12:52.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:25:0)
[11:12:52.142] Timer tick, asking Buildbucket for the build status
[11:12:52.227] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:52.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:26:0) after 1m0s
[11:13:52.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:26:0)
[11:13:52.279] Timer tick, asking Buildbucket for the build status
[11:13:52.487] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:52.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:27:0) after 1m0s
[11:14:52.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:27:0)
[11:14:52.599] Timer tick, asking Buildbucket for the build status
[11:14:52.712] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:52.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:28:0) after 1m0s
[11:15:52.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:28:0)
[11:15:52.817] Timer tick, asking Buildbucket for the build status
[11:15:52.966] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:52.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:29:0) after 1m0s
[11:16:53.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:29:0)
[11:16:53.027] Timer tick, asking Buildbucket for the build status
[11:16:53.107] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:53.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:30:0) after 1m0s
[11:17:53.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:30:0)
[11:17:53.207] Timer tick, asking Buildbucket for the build status
[11:17:53.318] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:53.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:31:0) after 1m0s
[11:18:53.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:31:0)
[11:18:53.396] Timer tick, asking Buildbucket for the build status
[11:18:53.483] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:53.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:32:0) after 1m0s
[11:19:53.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:32:0)
[11:19:53.622] Timer tick, asking Buildbucket for the build status
[11:19:53.730] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:53.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:33:0) after 1m0s
[11:20:53.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:33:0)
[11:20:53.811] Timer tick, asking Buildbucket for the build status
[11:20:53.976] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:53.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:34:0) after 1m0s
[11:21:54.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:34:0)
[11:21:54.455] Timer tick, asking Buildbucket for the build status
[11:21:54.531] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:54.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:35:0) after 1m0s
[11:22:54.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:35:0)
[11:22:54.812] Timer tick, asking Buildbucket for the build status
[11:22:54.897] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:54.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:36:0) after 1m0s
[11:23:54.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:36:0)
[11:23:54.924] Timer tick, asking Buildbucket for the build status
[11:23:55.001] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:55.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:37:0) after 1m0s
[11:24:55.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:37:0)
[11:24:55.175] Timer tick, asking Buildbucket for the build status
[11:24:55.313] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:55.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:38:0) after 1m0s
[11:25:55.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:38:0)
[11:25:55.432] Timer tick, asking Buildbucket for the build status
[11:25:55.708] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:55.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:39:0) after 1m0s
[11:26:55.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:39:0)
[11:26:55.852] Timer tick, asking Buildbucket for the build status
[11:26:55.929] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:55.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:40:0) after 1m0s
[11:27:56.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:40:0)
[11:27:56.214] Timer tick, asking Buildbucket for the build status
[11:27:56.297] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:56.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:41:0) after 1m0s
[11:28:56.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:41:0)
[11:28:56.535] Timer tick, asking Buildbucket for the build status
[11:28:56.615] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:56.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:42:0) after 1m0s
[11:29:56.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:42:0)
[11:29:56.784] Timer tick, asking Buildbucket for the build status
[11:29:56.918] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:56.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:43:0) after 1m0s
[11:30:57.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:43:0)
[11:30:57.121] Timer tick, asking Buildbucket for the build status
[11:30:57.369] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:57.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:44:0) after 1m0s
[11:31:57.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:44:0)
[11:31:57.478] Timer tick, asking Buildbucket for the build status
[11:31:57.633] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:57.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:45:0) after 1m0s
[11:32:57.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:45:0)
[11:32:57.689] Timer tick, asking Buildbucket for the build status
[11:32:58.433] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:58.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:46:0) after 1m0s
[11:33:58.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:46:0)
[11:33:58.498] Timer tick, asking Buildbucket for the build status
[11:33:58.579] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:58.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:47:0) after 1m0s
[11:34:58.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:47:0)
[11:34:58.645] Timer tick, asking Buildbucket for the build status
[11:34:58.786] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:58.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:48:0) after 1m0s
[11:35:58.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:48:0)
[11:35:58.891] Timer tick, asking Buildbucket for the build status
[11:35:58.962] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:58.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:49:0) after 1m0s
[11:36:59.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:49:0)
[11:36:59.083] Timer tick, asking Buildbucket for the build status
[11:36:59.250] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:59.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:50:0) after 1m0s
[11:37:59.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:50:0)
[11:37:59.248] Timer tick, asking Buildbucket for the build status
[11:37:59.407] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:59.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:51:0) after 1m0s
[11:38:59.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:51:0)
[11:38:59.449] Timer tick, asking Buildbucket for the build status
[11:38:59.542] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:59.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:52:0) after 1m0s
[11:39:59.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:52:0)
[11:39:59.601] Timer tick, asking Buildbucket for the build status
[11:39:59.756] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:59.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:53:0) after 1m0s
[11:40:59.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:53:0)
[11:40:59.786] Timer tick, asking Buildbucket for the build status
[11:41:00.175] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:00.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:54:0) after 1m0s
[11:42:00.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:54:0)
[11:42:00.201] Timer tick, asking Buildbucket for the build status
[11:42:00.284] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:00.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:55:0) after 1m0s
[11:43:00.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:55:0)
[11:43:00.366] Timer tick, asking Buildbucket for the build status
[11:43:00.461] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:00.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:56:0) after 1m0s
[11:44:00.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:56:0)
[11:44:00.524] Timer tick, asking Buildbucket for the build status
[11:44:01.495] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:01.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:57:0) after 1m0s
[11:45:02.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:57:0)
[11:45:02.841] Timer tick, asking Buildbucket for the build status
[11:45:03.901] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:03.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:58:0) after 1m0s
[11:46:04.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:58:0)
[11:46:04.212] Timer tick, asking Buildbucket for the build status
[11:46:04.304] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:04.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:59:0) after 1m0s
[11:47:04.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:59:0)
[11:47:04.460] Timer tick, asking Buildbucket for the build status
[11:47:04.825] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:04.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:60:0) after 1m0s
[11:48:04.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:60:0)
[11:48:04.982] Timer tick, asking Buildbucket for the build status
[11:48:05.264] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:05.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:61:0) after 1m0s
[11:49:05.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:61:0)
[11:49:05.289] Timer tick, asking Buildbucket for the build status
[11:49:05.367] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:05.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:62:0) after 1m0s
[11:50:05.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:62:0)
[11:50:05.450] Timer tick, asking Buildbucket for the build status
[11:50:05.567] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:05.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:63:0) after 1m0s
[11:51:05.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:63:0)
[11:51:05.662] Timer tick, asking Buildbucket for the build status
[11:51:05.758] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:05.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:64:0) after 1m0s
[11:52:05.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:64:0)
[11:52:05.807] Timer tick, asking Buildbucket for the build status
[11:52:05.876] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:05.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:65:0) after 1m0s
[11:53:06.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:65:0)
[11:53:06.045] Timer tick, asking Buildbucket for the build status
[11:53:06.159] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:06.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:66:0) after 1m0s
[11:54:06.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:66:0)
[11:54:06.331] Timer tick, asking Buildbucket for the build status
[11:54:06.617] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:06.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:67:0) after 1m0s
[11:55:06.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:67:0)
[11:55:06.665] Timer tick, asking Buildbucket for the build status
[11:55:06.780] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:06.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:68:0) after 1m0s
[11:56:06.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:68:0)
[11:56:06.987] Timer tick, asking Buildbucket for the build status
[11:56:07.644] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:07.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:69:0) after 1m0s
[11:57:07.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:69:0)
[11:57:07.859] Timer tick, asking Buildbucket for the build status
[11:57:08.343] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:08.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:70:0) after 1m0s
[11:58:08.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:70:0)
[11:58:08.417] Timer tick, asking Buildbucket for the build status
[11:58:08.527] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:08.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:71:0) after 1m0s
[11:59:08.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:71:0)
[11:59:08.712] Timer tick, asking Buildbucket for the build status
[11:59:08.853] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:08.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:72:0) after 1m0s
[12:00:08.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:72:0)
[12:00:08.917] Timer tick, asking Buildbucket for the build status
[12:00:09.379] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:09.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:73:0) after 1m0s
[12:01:09.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:73:0)
[12:01:09.622] Timer tick, asking Buildbucket for the build status
[12:01:09.753] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:09.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:74:0) after 1m0s
[12:02:09.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:74:0)
[12:02:09.980] Timer tick, asking Buildbucket for the build status
[12:02:10.170] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:10.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:75:0) after 1m0s
[12:03:10.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:75:0)
[12:03:10.303] Timer tick, asking Buildbucket for the build status
[12:03:10.403] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:10.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:76:0) after 1m0s
[12:04:10.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:76:0)
[12:04:10.454] Timer tick, asking Buildbucket for the build status
[12:04:10.565] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:10.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:77:0) after 1m0s
[12:05:10.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:77:0)
[12:05:10.658] Timer tick, asking Buildbucket for the build status
[12:05:11.180] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:11.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:78:0) after 1m0s
[12:06:11.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:78:0)
[12:06:11.324] Timer tick, asking Buildbucket for the build status
[12:06:11.518] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:11.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:79:0) after 1m0s
[12:07:11.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:79:0)
[12:07:11.541] Timer tick, asking Buildbucket for the build status
[12:07:13.295] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:13.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:80:0) after 1m0s
[12:08:13.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:80:0)
[12:08:13.324] Timer tick, asking Buildbucket for the build status
[12:08:13.932] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:13.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:81:0) after 1m0s
[12:09:13.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:81:0)
[12:09:13.959] Timer tick, asking Buildbucket for the build status
[12:09:14.229] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:14.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:82:0) after 1m0s
[12:10:14.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:82:0)
[12:10:14.263] Timer tick, asking Buildbucket for the build status
[12:10:14.432] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:14.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:83:0) after 1m0s
[12:11:14.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:83:0)
[12:11:14.496] Timer tick, asking Buildbucket for the build status
[12:11:14.585] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:14.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:84:0) after 1m0s
[12:12:14.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:84:0)
[12:12:14.899] Timer tick, asking Buildbucket for the build status
[12:12:15.067] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:15.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:85:0) after 1m0s
[12:13:15.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:85:0)
[12:13:15.169] Timer tick, asking Buildbucket for the build status
[12:13:15.278] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:15.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:86:0) after 1m0s
[12:14:15.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:86:0)
[12:14:15.392] Timer tick, asking Buildbucket for the build status
[12:14:15.490] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:15.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:87:0) after 1m0s
[12:15:15.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:87:0)
[12:15:15.679] Timer tick, asking Buildbucket for the build status
[12:15:16.826] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:16.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:88:0) after 1m0s
[12:16:16.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:88:0)
[12:16:16.878] Timer tick, asking Buildbucket for the build status
[12:16:16.989] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:16.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:89:0) after 1m0s
[12:17:17.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:89:0)
[12:17:17.123] Timer tick, asking Buildbucket for the build status
[12:17:17.244] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:17.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:90:0) after 1m0s
[12:18:17.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:90:0)
[12:18:17.433] Timer tick, asking Buildbucket for the build status
[12:18:17.593] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:17.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:91:0) after 1m0s
[12:19:17.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:91:0)
[12:19:17.657] Timer tick, asking Buildbucket for the build status
[12:19:17.989] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:17.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:92:0) after 1m0s
[12:20:18.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:92:0)
[12:20:18.095] Timer tick, asking Buildbucket for the build status
[12:20:18.766] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:18.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:93:0) after 1m0s
[12:21:18.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:93:0)
[12:21:18.786] Timer tick, asking Buildbucket for the build status
[12:21:18.939] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:18.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:94:0) after 1m0s
[12:22:18.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:94:0)
[12:22:18.973] Timer tick, asking Buildbucket for the build status
[12:22:19.100] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:19.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:95:0) after 1m0s
[12:23:19.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:95:0)
[12:23:19.089] Timer tick, asking Buildbucket for the build status
[12:23:19.232] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:19.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:96:0) after 1m0s
[12:24:19.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:96:0)
[12:24:19.269] Timer tick, asking Buildbucket for the build status
[12:24:19.433] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:19.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:97:0) after 1m0s
[12:25:19.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:97:0)
[12:25:19.498] Timer tick, asking Buildbucket for the build status
[12:25:20.743] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:20.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:98:0) after 1m0s
[12:26:20.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:98:0)
[12:26:20.812] Timer tick, asking Buildbucket for the build status
[12:26:20.946] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:20.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:99:0) after 1m0s
[12:27:21.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:99:0)
[12:27:21.064] Timer tick, asking Buildbucket for the build status
[12:27:21.263] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:21.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:100:0) after 1m0s
[12:28:21.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:100:0)
[12:28:21.310] Timer tick, asking Buildbucket for the build status
[12:28:21.399] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:21.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:101:0) after 1m0s
[12:29:21.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:101:0)
[12:29:21.451] Timer tick, asking Buildbucket for the build status
[12:29:21.542] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:21.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:102:0) after 1m0s
[12:30:21.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:102:0)
[12:30:21.596] Timer tick, asking Buildbucket for the build status
[12:30:21.763] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:21.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:103:0) after 1m0s
[12:31:21.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:103:0)
[12:31:21.799] Timer tick, asking Buildbucket for the build status
[12:31:23.127] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:23.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:104:0) after 1m0s
[12:32:23.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:104:0)
[12:32:23.299] Timer tick, asking Buildbucket for the build status
[12:32:23.382] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:23.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:105:0) after 1m0s
[12:33:23.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:105:0)
[12:33:23.433] Timer tick, asking Buildbucket for the build status
[12:33:23.578] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:23.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:106:0) after 1m0s
[12:34:23.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:106:0)
[12:34:23.573] Timer tick, asking Buildbucket for the build status
[12:34:24.118] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:24.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:107:0) after 1m0s
[12:35:24.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:107:0)
[12:35:24.191] Timer tick, asking Buildbucket for the build status
[12:35:24.737] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:24.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:108:0) after 1m0s
[12:36:24.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:108:0)
[12:36:24.772] Timer tick, asking Buildbucket for the build status
[12:36:24.867] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:24.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:109:0) after 1m0s
[12:37:25.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:109:0)
[12:37:25.007] Timer tick, asking Buildbucket for the build status
[12:37:25.604] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:25.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:110:0) after 1m0s
[12:38:26.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:110:0)
[12:38:26.651] Timer tick, asking Buildbucket for the build status
[12:38:26.858] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:26.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:111:0) after 1m0s
[12:39:26.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:111:0)
[12:39:26.869] Timer tick, asking Buildbucket for the build status
[12:39:26.977] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:26.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:112:0) after 1m0s
[12:40:27.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:112:0)
[12:40:27.051] Timer tick, asking Buildbucket for the build status
[12:40:27.255] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:27.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:113:0) after 1m0s
[12:41:27.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:113:0)
[12:41:27.487] Timer tick, asking Buildbucket for the build status
[12:41:27.616] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:27.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:114:0) after 1m0s
[12:42:27.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:114:0)
[12:42:27.731] Timer tick, asking Buildbucket for the build status
[12:42:28.366] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:28.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:115:0) after 1m0s
[12:43:28.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:115:0)
[12:43:28.405] Timer tick, asking Buildbucket for the build status
[12:43:28.774] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:28.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:116:0) after 1m0s
[12:44:28.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:116:0)
[12:44:28.826] Timer tick, asking Buildbucket for the build status
[12:44:28.932] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:28.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:117:0) after 1m0s
[12:45:29.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:117:0)
[12:45:29.086] Timer tick, asking Buildbucket for the build status
[12:45:29.737] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:29.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:118:0) after 1m0s
[12:46:29.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:118:0)
[12:46:29.874] Timer tick, asking Buildbucket for the build status
[12:46:30.110] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:30.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:119:0) after 1m0s
[12:47:30.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:119:0)
[12:47:30.419] Timer tick, asking Buildbucket for the build status
[12:47:30.752] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:30.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:120:0) after 1m0s
[12:48:30.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:120:0)
[12:48:30.896] Timer tick, asking Buildbucket for the build status
[12:48:31.465] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:31.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:121:0) after 1m0s
[12:49:31.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:121:0)
[12:49:31.599] Timer tick, asking Buildbucket for the build status
[12:49:31.869] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:31.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:122:0) after 1m0s
[12:50:32.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:122:0)
[12:50:32.025] Timer tick, asking Buildbucket for the build status
[12:50:32.165] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:32.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:123:0) after 1m0s
[12:51:32.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:123:0)
[12:51:32.218] Timer tick, asking Buildbucket for the build status
[12:51:32.362] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:32.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:124:0) after 1m0s
[12:52:32.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:124:0)
[12:52:32.575] Timer tick, asking Buildbucket for the build status
[12:52:32.707] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:32.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:125:0) after 1m0s
[12:53:32.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:125:0)
[12:53:32.833] Timer tick, asking Buildbucket for the build status
[12:53:32.986] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:32.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:126:0) after 1m0s
[12:54:33.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:126:0)
[12:54:33.067] Timer tick, asking Buildbucket for the build status
[12:54:33.162] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:33.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:127:0) after 1m0s
[12:55:33.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:127:0)
[12:55:33.227] Timer tick, asking Buildbucket for the build status
[12:55:34.323] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:34.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:128:0) after 1m0s
[12:56:34.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:128:0)
[12:56:34.389] Timer tick, asking Buildbucket for the build status
[12:56:34.617] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:34.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:129:0) after 1m0s
[12:57:34.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:129:0)
[12:57:34.692] Timer tick, asking Buildbucket for the build status
[12:57:34.902] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:34.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:130:0) after 1m0s
[12:58:34.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:130:0)
[12:58:34.943] Timer tick, asking Buildbucket for the build status
[12:58:35.354] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:35.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:131:0) after 1m0s
[12:59:35.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:131:0)
[12:59:35.387] Timer tick, asking Buildbucket for the build status
[12:59:35.472] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:35.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:132:0) after 1m0s
[13:00:35.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:132:0)
[13:00:35.609] Timer tick, asking Buildbucket for the build status
[13:00:37.399] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:37.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:133:0) after 1m0s
[13:01:37.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:133:0)
[13:01:37.654] Timer tick, asking Buildbucket for the build status
[13:01:37.962] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:37.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:134:0) after 1m0s
[13:02:38.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:134:0)
[13:02:38.021] Timer tick, asking Buildbucket for the build status
[13:02:38.141] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:38.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:135:0) after 1m0s
[13:03:38.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:135:0)
[13:03:38.134] Timer tick, asking Buildbucket for the build status
[13:03:38.212] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:38.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:136:0) after 1m0s
[13:04:38.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:136:0)
[13:04:38.248] Timer tick, asking Buildbucket for the build status
[13:04:38.552] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:38.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:137:0) after 1m0s
[13:05:38.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:137:0)
[13:05:38.662] Timer tick, asking Buildbucket for the build status
[13:05:38.976] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:38.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:138:0) after 1m0s
[13:06:39.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:138:0)
[13:06:39.034] Timer tick, asking Buildbucket for the build status
[13:06:39.191] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:39.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:139:0) after 1m0s
[13:07:39.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:139:0)
[13:07:39.228] Timer tick, asking Buildbucket for the build status
[13:07:39.331] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:39.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:140:0) after 1m0s
[13:08:39.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:140:0)
[13:08:39.367] Timer tick, asking Buildbucket for the build status
[13:08:39.447] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:39.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:141:0) after 1m0s
[13:09:39.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:141:0)
[13:09:39.551] Timer tick, asking Buildbucket for the build status
[13:09:39.630] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:39.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:142:0) after 1m0s
[13:10:39.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:142:0)
[13:10:39.626] Timer tick, asking Buildbucket for the build status
[13:10:39.744] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:39.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:143:0) after 1m0s
[13:11:39.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:143:0)
[13:11:39.775] Timer tick, asking Buildbucket for the build status
[13:11:41.026] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:41.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:144:0) after 1m0s
[13:12:41.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:144:0)
[13:12:41.093] Timer tick, asking Buildbucket for the build status
[13:12:42.158] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:42.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:145:0) after 1m0s
[13:13:42.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:145:0)
[13:13:42.194] Timer tick, asking Buildbucket for the build status
[13:13:42.431] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:42.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:146:0) after 1m0s
[13:14:42.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:146:0)
[13:14:42.544] Timer tick, asking Buildbucket for the build status
[13:14:42.676] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:42.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:147:0) after 1m0s
[13:15:42.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:147:0)
[13:15:42.799] Timer tick, asking Buildbucket for the build status
[13:15:43.068] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:43.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:148:0) after 1m0s
[13:16:43.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:148:0)
[13:16:43.124] Timer tick, asking Buildbucket for the build status
[13:16:47.701] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:47.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:149:0) after 1m0s
[13:17:47.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:149:0)
[13:17:47.785] Timer tick, asking Buildbucket for the build status
[13:17:47.886] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:47.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:150:0) after 1m0s
[13:18:47.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:150:0)
[13:18:47.925] Timer tick, asking Buildbucket for the build status
[13:18:48.023] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:48.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:151:0) after 1m0s
[13:19:48.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:151:0)
[13:19:48.056] Timer tick, asking Buildbucket for the build status
[13:19:48.155] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:48.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:152:0) after 1m0s
[13:20:48.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:152:0)
[13:20:48.196] Timer tick, asking Buildbucket for the build status
[13:20:48.323] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:48.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:153:0) after 1m0s
[13:21:48.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:153:0)
[13:21:48.387] Timer tick, asking Buildbucket for the build status
[13:21:48.511] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:48.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:154:0) after 1m0s
[13:22:48.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:154:0)
[13:22:48.508] Timer tick, asking Buildbucket for the build status
[13:22:48.618] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:48.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:155:0) after 1m0s
[13:23:48.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:155:0)
[13:23:48.710] Timer tick, asking Buildbucket for the build status
[13:23:48.825] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:48.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:156:0) after 1m0s
[13:24:48.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:156:0)
[13:24:48.867] Timer tick, asking Buildbucket for the build status
[13:24:48.952] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:48.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:157:0) after 1m0s
[13:25:49.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:157:0)
[13:25:49.011] Timer tick, asking Buildbucket for the build status
[13:25:49.127] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:49.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:158:0) after 1m0s
[13:26:49.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:158:0)
[13:26:49.183] Timer tick, asking Buildbucket for the build status
[13:26:49.315] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:49.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:159:0) after 1m0s
[13:27:49.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:159:0)
[13:27:49.354] Timer tick, asking Buildbucket for the build status
[13:27:50.249] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:50.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:160:0) after 1m0s
[13:28:50.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:160:0)
[13:28:50.287] Timer tick, asking Buildbucket for the build status
[13:28:50.463] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:50.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:161:0) after 1m0s
[13:29:50.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:161:0)
[13:29:50.501] Timer tick, asking Buildbucket for the build status
[13:29:50.983] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:50.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:162:0) after 1m0s
[13:30:51.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:162:0)
[13:30:51.064] Timer tick, asking Buildbucket for the build status
[13:30:51.522] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:51.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:163:0) after 1m0s
[13:31:53.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:163:0)
[13:31:53.541] Timer tick, asking Buildbucket for the build status
[13:31:55.487] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:55.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:164:0) after 1m0s
[13:32:55.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:164:0)
[13:32:55.621] Timer tick, asking Buildbucket for the build status
[13:33:05.759] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:05.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:165:0) after 1m0s
[13:34:05.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:165:0)
[13:34:05.875] Timer tick, asking Buildbucket for the build status
[13:34:06.130] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:06.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:166:0) after 1m0s
[13:35:06.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:166:0)
[13:35:06.165] Timer tick, asking Buildbucket for the build status
[13:35:06.272] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:06.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:167:0) after 1m0s
[13:36:08.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:167:0)
[13:36:08.069] Timer tick, asking Buildbucket for the build status
[13:36:08.407] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:08.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:168:0) after 1m0s
[13:37:08.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:168:0)
[13:37:08.442] Timer tick, asking Buildbucket for the build status
[13:37:08.529] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:08.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:169:0) after 1m0s
[13:38:08.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:169:0)
[13:38:08.618] Timer tick, asking Buildbucket for the build status
[13:38:08.700] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:08.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:170:0) after 1m0s
[13:39:08.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:170:0)
[13:39:08.728] Timer tick, asking Buildbucket for the build status
[13:39:09.049] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:09.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:171:0) after 1m0s
[13:40:09.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:171:0)
[13:40:09.209] Timer tick, asking Buildbucket for the build status
[13:40:09.323] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:09.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:172:0) after 1m0s
[13:41:09.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:172:0)
[13:41:09.549] Timer tick, asking Buildbucket for the build status
[13:41:09.726] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:09.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:173:0) after 1m0s
[13:42:09.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:173:0)
[13:42:09.764] Timer tick, asking Buildbucket for the build status
[13:42:10.993] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:10.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:174:0) after 1m0s
[13:43:11.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:174:0)
[13:43:11.062] Timer tick, asking Buildbucket for the build status
[13:43:11.802] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:11.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:175:0) after 1m0s
[13:44:11.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:175:0)
[13:44:11.861] Timer tick, asking Buildbucket for the build status
[13:44:11.937] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:11.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:176:0) after 1m0s
[13:45:12.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:176:0)
[13:45:12.175] Timer tick, asking Buildbucket for the build status
[13:45:15.696] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:15.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:177:0) after 1m0s
[13:46:15.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:177:0)
[13:46:15.901] Timer tick, asking Buildbucket for the build status
[13:46:16.368] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:16.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:178:0) after 1m0s
[13:47:16.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:178:0)
[13:47:16.470] Timer tick, asking Buildbucket for the build status
[13:47:17.474] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:17.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:179:0) after 1m0s
[13:48:17.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:179:0)
[13:48:17.643] Timer tick, asking Buildbucket for the build status
[13:48:19.734] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:19.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:180:0) after 1m0s
[13:49:19.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:180:0)
[13:49:19.974] Timer tick, asking Buildbucket for the build status
[13:49:20.065] Build 8864023591633518784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:20.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029453654068766912:181:0) after 1m0s
[13:49:39.716] Received PubSub notification, asking Buildbucket for the build status
[13:49:39.886] Build 8864023591633518784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:49:39.886] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1605016178807151",
    "created_by": "project:infra",
    "created_ts": "1605005382848208",
    "id": "8864023591633518784",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4321.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"0b41de0c-6bf4-4400-8909-d612f016abdf\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4321.0\"}}",
    "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-06kg\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1605005425512326",
    "status": "COMPLETED",
    "status_changed_ts": "1605016179318335",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029453654068766912",
      "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/8864023591633518784/+/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:4fca981eb6514210",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1605016179383382",
    "url": "https://ci.chromium.org/b/8864023591633518784",
    "utcnow_ts": "1605016179864859"
  }
}
[13:49:39.886] Invocation finished in 2h59m58.744000905s with status SUCCEEDED