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

• parent_buildername:publish_tarball
• user_agent:recipe

[15:59:30.333] New invocation is queued and will start shortly
[15:59:30.333] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[15:59:33.203] Starting the invocation (attempt 1)
[15:59:33.248] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:59:33.249] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:59:33.249] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9029162371006651392",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4324.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"4c545b04-2fc3-4a30-9d79-776be178c285\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4324.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:9029162371006651392",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[15:59:33.830] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1605283173378096",
    "id": "8863732307150211808",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4324.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"4c545b04-2fc3-4a30-9d79-776be178c285\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4324.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1605283173571526",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029162371006651392",
      "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/8863732307150211808/+/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": "1605283173571633",
    "url": "https://ci.chromium.org/b/8863732307150211808",
    "utcnow_ts": "1605283173821862"
  }
}
[15:59:33.830] Task URL: https://ci.chromium.org/b/8863732307150211808
[15:59:33.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:2:0) after 1m0s
[16:00:07.317] Received PubSub notification, asking Buildbucket for the build status
[16:00:07.534] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:33.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:2:0)
[16:00:33.907] Timer tick, asking Buildbucket for the build status
[16:00:34.459] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:34.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:4:0) after 1m0s
[16:01:34.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:4:0)
[16:01:34.696] Timer tick, asking Buildbucket for the build status
[16:01:34.861] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:34.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:5:0) after 1m0s
[16:02:34.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:5:0)
[16:02:34.990] Timer tick, asking Buildbucket for the build status
[16:02:36.762] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:36.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:6:0) after 1m0s
[16:03:36.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:6:0)
[16:03:36.825] Timer tick, asking Buildbucket for the build status
[16:03:36.928] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:36.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:7:0) after 1m0s
[16:04:37.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:7:0)
[16:04:37.103] Timer tick, asking Buildbucket for the build status
[16:04:37.314] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:37.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:8:0) after 1m0s
[16:05:37.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:8:0)
[16:05:37.388] Timer tick, asking Buildbucket for the build status
[16:05:37.499] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:37.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:9:0) after 1m0s
[16:06:37.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:9:0)
[16:06:37.554] Timer tick, asking Buildbucket for the build status
[16:06:37.639] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:37.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:10:0) after 1m0s
[16:07:37.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:10:0)
[16:07:37.679] Timer tick, asking Buildbucket for the build status
[16:07:37.774] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:37.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:11:0) after 1m0s
[16:08:37.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:11:0)
[16:08:37.777] Timer tick, asking Buildbucket for the build status
[16:08:37.850] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:37.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:12:0) after 1m0s
[16:09:37.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:12:0)
[16:09:37.906] Timer tick, asking Buildbucket for the build status
[16:09:38.000] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:38.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:13:0) after 1m0s
[16:10:38.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:13:0)
[16:10:38.042] Timer tick, asking Buildbucket for the build status
[16:10:38.160] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:38.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:14:0) after 1m0s
[16:11:38.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:14:0)
[16:11:38.201] Timer tick, asking Buildbucket for the build status
[16:11:38.298] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:38.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:15:0) after 1m0s
[16:12:38.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:15:0)
[16:12:38.291] Timer tick, asking Buildbucket for the build status
[16:12:38.381] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:38.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:16:0) after 1m0s
[16:13:38.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:16:0)
[16:13:38.423] Timer tick, asking Buildbucket for the build status
[16:13:38.503] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:38.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:17:0) after 1m0s
[16:14:38.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:17:0)
[16:14:38.539] Timer tick, asking Buildbucket for the build status
[16:14:38.899] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:38.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:18:0) after 1m0s
[16:15:38.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:18:0)
[16:15:38.920] Timer tick, asking Buildbucket for the build status
[16:15:39.047] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:39.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:19:0) after 1m0s
[16:16:39.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:19:0)
[16:16:39.082] Timer tick, asking Buildbucket for the build status
[16:16:39.172] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:39.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:20:0) after 1m0s
[16:17:39.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:20:0)
[16:17:39.312] Timer tick, asking Buildbucket for the build status
[16:17:40.433] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:40.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:21:0) after 1m0s
[16:18:40.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:21:0)
[16:18:40.446] Timer tick, asking Buildbucket for the build status
[16:18:40.551] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:40.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:22:0) after 1m0s
[16:19:40.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:22:0)
[16:19:40.600] Timer tick, asking Buildbucket for the build status
[16:19:40.685] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:40.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:23:0) after 1m0s
[16:20:40.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:23:0)
[16:20:40.674] Timer tick, asking Buildbucket for the build status
[16:20:40.812] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:40.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:24:0) after 1m0s
[16:21:40.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:24:0)
[16:21:40.906] Timer tick, asking Buildbucket for the build status
[16:21:40.987] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:40.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:25:0) after 1m0s
[16:22:41.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:25:0)
[16:22:41.038] Timer tick, asking Buildbucket for the build status
[16:22:41.153] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:41.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:26:0) after 1m0s
[16:23:41.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:26:0)
[16:23:41.217] Timer tick, asking Buildbucket for the build status
[16:23:41.352] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:41.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:27:0) after 1m0s
[16:24:41.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:27:0)
[16:24:41.375] Timer tick, asking Buildbucket for the build status
[16:24:41.453] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:41.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:28:0) after 1m0s
[16:25:41.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:28:0)
[16:25:41.487] Timer tick, asking Buildbucket for the build status
[16:25:41.617] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:41.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:29:0) after 1m0s
[16:26:41.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:29:0)
[16:26:41.647] Timer tick, asking Buildbucket for the build status
[16:26:41.971] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:41.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:30:0) after 1m0s
[16:27:42.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:30:0)
[16:27:42.175] Timer tick, asking Buildbucket for the build status
[16:27:42.277] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:42.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:31:0) after 1m0s
[16:28:42.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:31:0)
[16:28:42.533] Timer tick, asking Buildbucket for the build status
[16:28:42.722] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:42.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:32:0) after 1m0s
[16:29:42.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:32:0)
[16:29:42.844] Timer tick, asking Buildbucket for the build status
[16:29:42.974] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:42.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:33:0) after 1m0s
[16:30:43.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:33:0)
[16:30:43.027] Timer tick, asking Buildbucket for the build status
[16:30:43.186] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:43.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:34:0) after 1m0s
[16:31:43.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:34:0)
[16:31:43.241] Timer tick, asking Buildbucket for the build status
[16:31:43.516] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:43.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:35:0) after 1m0s
[16:32:43.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:35:0)
[16:32:43.592] Timer tick, asking Buildbucket for the build status
[16:32:43.995] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:43.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:36:0) after 1m0s
[16:33:44.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:36:0)
[16:33:44.021] Timer tick, asking Buildbucket for the build status
[16:33:44.333] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:44.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:37:0) after 1m0s
[16:34:44.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:37:0)
[16:34:44.387] Timer tick, asking Buildbucket for the build status
[16:34:44.507] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:44.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:38:0) after 1m0s
[16:35:44.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:38:0)
[16:35:44.511] Timer tick, asking Buildbucket for the build status
[16:35:44.969] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:44.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:39:0) after 1m0s
[16:36:45.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:39:0)
[16:36:45.038] Timer tick, asking Buildbucket for the build status
[16:36:45.120] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:45.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:40:0) after 1m0s
[16:37:45.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:40:0)
[16:37:45.211] Timer tick, asking Buildbucket for the build status
[16:37:45.303] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:45.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:41:0) after 1m0s
[16:38:45.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:41:0)
[16:38:45.352] Timer tick, asking Buildbucket for the build status
[16:38:45.464] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:45.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:42:0) after 1m0s
[16:39:45.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:42:0)
[16:39:45.513] Timer tick, asking Buildbucket for the build status
[16:39:46.001] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:46.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:43:0) after 1m0s
[16:40:45.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:43:0)
[16:40:45.993] Timer tick, asking Buildbucket for the build status
[16:40:46.087] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:46.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:44:0) after 1m0s
[16:41:46.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:44:0)
[16:41:46.139] Timer tick, asking Buildbucket for the build status
[16:41:46.256] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:46.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:45:0) after 1m0s
[16:42:46.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:45:0)
[16:42:46.292] Timer tick, asking Buildbucket for the build status
[16:42:46.463] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:46.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:46:0) after 1m0s
[16:43:46.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:46:0)
[16:43:46.542] Timer tick, asking Buildbucket for the build status
[16:43:46.623] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:46.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:47:0) after 1m0s
[16:44:46.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:47:0)
[16:44:46.737] Timer tick, asking Buildbucket for the build status
[16:44:46.837] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:46.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:48:0) after 1m0s
[16:45:46.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:48:0)
[16:45:46.914] Timer tick, asking Buildbucket for the build status
[16:45:47.546] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:47.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:49:0) after 1m0s
[16:46:47.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:49:0)
[16:46:47.530] Timer tick, asking Buildbucket for the build status
[16:46:49.908] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:49.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:50:0) after 1m0s
[16:47:49.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:50:0)
[16:47:49.957] Timer tick, asking Buildbucket for the build status
[16:47:50.219] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:50.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:51:0) after 1m0s
[16:48:50.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:51:0)
[16:48:50.214] Timer tick, asking Buildbucket for the build status
[16:48:50.304] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:50.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:52:0) after 1m0s
[16:49:50.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:52:0)
[16:49:50.331] Timer tick, asking Buildbucket for the build status
[16:49:50.433] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:50.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:53:0) after 1m0s
[16:50:50.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:53:0)
[16:50:50.534] Timer tick, asking Buildbucket for the build status
[16:50:50.916] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:50.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:54:0) after 1m0s
[16:51:50.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:54:0)
[16:51:50.934] Timer tick, asking Buildbucket for the build status
[16:51:51.086] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:51.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:55:0) after 1m0s
[16:52:51.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:55:0)
[16:52:51.195] Timer tick, asking Buildbucket for the build status
[16:52:52.626] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:52.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:56:0) after 1m0s
[16:53:52.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:56:0)
[16:53:52.623] Timer tick, asking Buildbucket for the build status
[16:53:52.739] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:52.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:57:0) after 1m0s
[16:54:52.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:57:0)
[16:54:52.789] Timer tick, asking Buildbucket for the build status
[16:54:52.930] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:52.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:58:0) after 1m0s
[16:55:52.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:58:0)
[16:55:52.957] Timer tick, asking Buildbucket for the build status
[16:55:53.038] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:53.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:59:0) after 1m0s
[16:56:53.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:59:0)
[16:56:53.079] Timer tick, asking Buildbucket for the build status
[16:56:53.151] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:53.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:60:0) after 1m0s
[16:57:53.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:60:0)
[16:57:53.178] Timer tick, asking Buildbucket for the build status
[16:57:53.261] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:53.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:61:0) after 1m0s
[16:58:53.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:61:0)
[16:58:53.277] Timer tick, asking Buildbucket for the build status
[16:58:53.366] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:53.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:62:0) after 1m0s
[16:59:53.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:62:0)
[16:59:53.383] Timer tick, asking Buildbucket for the build status
[16:59:53.541] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:53.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:63:0) after 1m0s
[17:00:53.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:63:0)
[17:00:53.646] Timer tick, asking Buildbucket for the build status
[17:00:53.819] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:53.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:64:0) after 1m0s
[17:01:53.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:64:0)
[17:01:53.850] Timer tick, asking Buildbucket for the build status
[17:01:54.033] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:54.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:65:0) after 1m0s
[17:02:54.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:65:0)
[17:02:54.083] Timer tick, asking Buildbucket for the build status
[17:02:54.507] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:54.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:66:0) after 1m0s
[17:03:54.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:66:0)
[17:03:54.550] Timer tick, asking Buildbucket for the build status
[17:03:54.790] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:54.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:67:0) after 1m0s
[17:04:54.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:67:0)
[17:04:54.861] Timer tick, asking Buildbucket for the build status
[17:04:56.766] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:56.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:68:0) after 1m0s
[17:05:56.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:68:0)
[17:05:56.820] Timer tick, asking Buildbucket for the build status
[17:05:56.904] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:56.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:69:0) after 1m0s
[17:06:56.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:69:0)
[17:06:56.979] Timer tick, asking Buildbucket for the build status
[17:06:57.101] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:57.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:70:0) after 1m0s
[17:07:57.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:70:0)
[17:07:57.138] Timer tick, asking Buildbucket for the build status
[17:07:57.235] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:57.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:71:0) after 1m0s
[17:08:57.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:71:0)
[17:08:57.277] Timer tick, asking Buildbucket for the build status
[17:08:57.493] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:57.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:72:0) after 1m0s
[17:09:57.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:72:0)
[17:09:57.524] Timer tick, asking Buildbucket for the build status
[17:09:57.652] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:57.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:73:0) after 1m0s
[17:10:57.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:73:0)
[17:10:57.689] Timer tick, asking Buildbucket for the build status
[17:10:58.138] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:58.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:74:0) after 1m0s
[17:11:58.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:74:0)
[17:11:58.204] Timer tick, asking Buildbucket for the build status
[17:11:58.351] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:58.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:75:0) after 1m0s
[17:12:58.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:75:0)
[17:12:58.398] Timer tick, asking Buildbucket for the build status
[17:12:58.659] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:58.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:76:0) after 1m0s
[17:13:58.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:76:0)
[17:13:58.702] Timer tick, asking Buildbucket for the build status
[17:13:58.787] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:58.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:77:0) after 1m0s
[17:14:58.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:77:0)
[17:14:58.844] Timer tick, asking Buildbucket for the build status
[17:14:58.964] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:58.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:78:0) after 1m0s
[17:15:59.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:78:0)
[17:15:59.055] Timer tick, asking Buildbucket for the build status
[17:16:00.219] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:00.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:79:0) after 1m0s
[17:17:00.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:79:0)
[17:17:00.366] Timer tick, asking Buildbucket for the build status
[17:17:00.547] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:00.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:80:0) after 1m0s
[17:18:00.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:80:0)
[17:18:00.574] Timer tick, asking Buildbucket for the build status
[17:18:00.667] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:00.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:81:0) after 1m0s
[17:19:02.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:81:0)
[17:19:02.194] Timer tick, asking Buildbucket for the build status
[17:19:02.338] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:02.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:82:0) after 1m0s
[17:20:02.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:82:0)
[17:20:02.721] Timer tick, asking Buildbucket for the build status
[17:20:02.953] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:02.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:83:0) after 1m0s
[17:21:03.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:83:0)
[17:21:03.023] Timer tick, asking Buildbucket for the build status
[17:21:03.566] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:03.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:84:0) after 1m0s
[17:22:03.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:84:0)
[17:22:03.635] Timer tick, asking Buildbucket for the build status
[17:22:03.790] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:03.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:85:0) after 1m0s
[17:23:03.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:85:0)
[17:23:03.864] Timer tick, asking Buildbucket for the build status
[17:23:04.312] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:04.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:86:0) after 1m0s
[17:24:04.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:86:0)
[17:24:04.404] Timer tick, asking Buildbucket for the build status
[17:24:05.040] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:05.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:87:0) after 1m0s
[17:25:05.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:87:0)
[17:25:05.238] Timer tick, asking Buildbucket for the build status
[17:25:05.534] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:05.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:88:0) after 1m0s
[17:26:05.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:88:0)
[17:26:05.609] Timer tick, asking Buildbucket for the build status
[17:26:06.489] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:06.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:89:0) after 1m0s
[17:27:06.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:89:0)
[17:27:06.603] Timer tick, asking Buildbucket for the build status
[17:27:07.212] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:07.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:90:0) after 1m0s
[17:28:07.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:90:0)
[17:28:07.244] Timer tick, asking Buildbucket for the build status
[17:28:07.376] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:07.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:91:0) after 1m0s
[17:29:07.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:91:0)
[17:29:07.539] Timer tick, asking Buildbucket for the build status
[17:29:07.833] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:07.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:92:0) after 1m0s
[17:30:07.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:92:0)
[17:30:07.987] Timer tick, asking Buildbucket for the build status
[17:30:08.113] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:08.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:93:0) after 1m0s
[17:31:08.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:93:0)
[17:31:08.214] Timer tick, asking Buildbucket for the build status
[17:31:08.310] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:08.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:94:0) after 1m0s
[17:32:08.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:94:0)
[17:32:08.579] Timer tick, asking Buildbucket for the build status
[17:32:08.700] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:08.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:95:0) after 1m0s
[17:33:08.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:95:0)
[17:33:08.855] Timer tick, asking Buildbucket for the build status
[17:33:09.009] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:09.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:96:0) after 1m0s
[17:34:09.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:96:0)
[17:34:09.175] Timer tick, asking Buildbucket for the build status
[17:34:09.294] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:09.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:97:0) after 1m0s
[17:35:09.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:97:0)
[17:35:09.395] Timer tick, asking Buildbucket for the build status
[17:35:09.594] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:09.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:98:0) after 1m0s
[17:36:09.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:98:0)
[17:36:09.702] Timer tick, asking Buildbucket for the build status
[17:36:09.807] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:09.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:99:0) after 1m0s
[17:37:09.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:99:0)
[17:37:09.815] Timer tick, asking Buildbucket for the build status
[17:37:09.941] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:09.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:100:0) after 1m0s
[17:38:10.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:100:0)
[17:38:10.042] Timer tick, asking Buildbucket for the build status
[17:38:10.953] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:10.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:101:0) after 1m0s
[17:39:11.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:101:0)
[17:39:11.005] Timer tick, asking Buildbucket for the build status
[17:39:11.297] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:11.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:102:0) after 1m0s
[17:40:11.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:102:0)
[17:40:11.359] Timer tick, asking Buildbucket for the build status
[17:40:11.477] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:11.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:103:0) after 1m0s
[17:41:11.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:103:0)
[17:41:11.535] Timer tick, asking Buildbucket for the build status
[17:41:11.612] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:11.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:104:0) after 1m0s
[17:42:11.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:104:0)
[17:42:11.657] Timer tick, asking Buildbucket for the build status
[17:42:11.825] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:11.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:105:0) after 1m0s
[17:43:11.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:105:0)
[17:43:11.887] Timer tick, asking Buildbucket for the build status
[17:43:11.981] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:11.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:106:0) after 1m0s
[17:44:12.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:106:0)
[17:44:12.028] Timer tick, asking Buildbucket for the build status
[17:44:12.148] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:12.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:107:0) after 1m0s
[17:45:12.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:107:0)
[17:45:12.256] Timer tick, asking Buildbucket for the build status
[17:45:12.429] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:12.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:108:0) after 1m0s
[17:46:12.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:108:0)
[17:46:12.627] Timer tick, asking Buildbucket for the build status
[17:46:12.931] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:12.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:109:0) after 1m0s
[17:47:13.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:109:0)
[17:47:13.073] Timer tick, asking Buildbucket for the build status
[17:47:13.176] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:13.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:110:0) after 1m0s
[17:48:13.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:110:0)
[17:48:13.197] Timer tick, asking Buildbucket for the build status
[17:48:13.285] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:13.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:111:0) after 1m0s
[17:49:13.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:111:0)
[17:49:13.373] Timer tick, asking Buildbucket for the build status
[17:49:13.748] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:13.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:112:0) after 1m0s
[17:50:13.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:112:0)
[17:50:13.765] Timer tick, asking Buildbucket for the build status
[17:50:13.961] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:13.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:113:0) after 1m0s
[17:51:14.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:113:0)
[17:51:14.031] Timer tick, asking Buildbucket for the build status
[17:51:14.155] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:14.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:114:0) after 1m0s
[17:52:14.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:114:0)
[17:52:14.233] Timer tick, asking Buildbucket for the build status
[17:52:14.607] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:14.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:115:0) after 1m0s
[17:53:14.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:115:0)
[17:53:14.713] Timer tick, asking Buildbucket for the build status
[17:53:14.825] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:14.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:116:0) after 1m0s
[17:54:14.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:116:0)
[17:54:14.894] Timer tick, asking Buildbucket for the build status
[17:54:15.151] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:15.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:117:0) after 1m0s
[17:55:15.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:117:0)
[17:55:15.210] Timer tick, asking Buildbucket for the build status
[17:55:17.575] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:17.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:118:0) after 1m0s
[17:56:17.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:118:0)
[17:56:17.627] Timer tick, asking Buildbucket for the build status
[17:56:17.738] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:17.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:119:0) after 1m0s
[17:57:17.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:119:0)
[17:57:17.809] Timer tick, asking Buildbucket for the build status
[17:57:17.927] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:17.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:120:0) after 1m0s
[17:58:18.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:120:0)
[17:58:18.009] Timer tick, asking Buildbucket for the build status
[17:58:18.166] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:18.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:121:0) after 1m0s
[17:59:18.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:121:0)
[17:59:18.240] Timer tick, asking Buildbucket for the build status
[17:59:18.394] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:18.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:122:0) after 1m0s
[18:00:18.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:122:0)
[18:00:18.556] Timer tick, asking Buildbucket for the build status
[18:00:18.695] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:18.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:123:0) after 1m0s
[18:01:18.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:123:0)
[18:01:18.843] Timer tick, asking Buildbucket for the build status
[18:01:18.945] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:18.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:124:0) after 1m0s
[18:02:19.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:124:0)
[18:02:19.026] Timer tick, asking Buildbucket for the build status
[18:02:20.606] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:20.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:125:0) after 1m0s
[18:03:20.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:125:0)
[18:03:20.710] Timer tick, asking Buildbucket for the build status
[18:03:20.799] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:20.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:126:0) after 1m0s
[18:04:20.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:126:0)
[18:04:20.938] Timer tick, asking Buildbucket for the build status
[18:04:21.045] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:21.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:127:0) after 1m0s
[18:05:21.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:127:0)
[18:05:21.112] Timer tick, asking Buildbucket for the build status
[18:05:21.225] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:21.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:128:0) after 1m0s
[18:06:21.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:128:0)
[18:06:21.262] Timer tick, asking Buildbucket for the build status
[18:06:21.417] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:21.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:129:0) after 1m0s
[18:07:21.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:129:0)
[18:07:21.533] Timer tick, asking Buildbucket for the build status
[18:07:21.633] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:21.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:130:0) after 1m0s
[18:08:21.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:130:0)
[18:08:21.704] Timer tick, asking Buildbucket for the build status
[18:08:21.786] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:21.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:131:0) after 1m0s
[18:09:21.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:131:0)
[18:09:21.905] Timer tick, asking Buildbucket for the build status
[18:09:22.085] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:22.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:132:0) after 1m0s
[18:10:22.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:132:0)
[18:10:22.121] Timer tick, asking Buildbucket for the build status
[18:10:22.438] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:22.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:133:0) after 1m0s
[18:11:22.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:133:0)
[18:11:22.545] Timer tick, asking Buildbucket for the build status
[18:11:22.627] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:22.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:134:0) after 1m0s
[18:12:22.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:134:0)
[18:12:22.659] Timer tick, asking Buildbucket for the build status
[18:12:22.754] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:22.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:135:0) after 1m0s
[18:13:22.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:135:0)
[18:13:22.837] Timer tick, asking Buildbucket for the build status
[18:13:22.953] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:22.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:136:0) after 1m0s
[18:14:23.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:136:0)
[18:14:23.169] Timer tick, asking Buildbucket for the build status
[18:14:24.431] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:24.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:137:0) after 1m0s
[18:15:24.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:137:0)
[18:15:24.525] Timer tick, asking Buildbucket for the build status
[18:15:25.928] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:25.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:138:0) after 1m0s
[18:16:25.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:138:0)
[18:16:25.955] Timer tick, asking Buildbucket for the build status
[18:16:26.686] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:26.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:139:0) after 1m0s
[18:17:26.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:139:0)
[18:17:26.795] Timer tick, asking Buildbucket for the build status
[18:17:26.910] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:26.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:140:0) after 1m0s
[18:18:26.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:140:0)
[18:18:26.995] Timer tick, asking Buildbucket for the build status
[18:18:27.490] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:27.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:141:0) after 1m0s
[18:19:27.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:141:0)
[18:19:27.621] Timer tick, asking Buildbucket for the build status
[18:19:27.847] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:27.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:142:0) after 1m0s
[18:20:27.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:142:0)
[18:20:27.932] Timer tick, asking Buildbucket for the build status
[18:20:28.058] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:28.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:143:0) after 1m0s
[18:21:28.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:143:0)
[18:21:28.158] Timer tick, asking Buildbucket for the build status
[18:21:28.321] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:28.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:144:0) after 1m0s
[18:22:28.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:144:0)
[18:22:28.385] Timer tick, asking Buildbucket for the build status
[18:22:28.496] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:28.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:145:0) after 1m0s
[18:23:28.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:145:0)
[18:23:28.570] Timer tick, asking Buildbucket for the build status
[18:23:28.658] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:28.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:146:0) after 1m0s
[18:24:28.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:146:0)
[18:24:28.896] Timer tick, asking Buildbucket for the build status
[18:24:30.051] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:30.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:147:0) after 1m0s
[18:25:30.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:147:0)
[18:25:30.117] Timer tick, asking Buildbucket for the build status
[18:25:30.685] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:30.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:148:0) after 1m0s
[18:26:30.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:148:0)
[18:26:30.771] Timer tick, asking Buildbucket for the build status
[18:26:31.017] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:31.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:149:0) after 1m0s
[18:27:31.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:149:0)
[18:27:31.045] Timer tick, asking Buildbucket for the build status
[18:27:31.178] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:31.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:150:0) after 1m0s
[18:28:31.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:150:0)
[18:28:31.392] Timer tick, asking Buildbucket for the build status
[18:28:31.492] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:31.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:151:0) after 1m0s
[18:29:31.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:151:0)
[18:29:31.547] Timer tick, asking Buildbucket for the build status
[18:29:31.678] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:31.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:152:0) after 1m0s
[18:30:31.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:152:0)
[18:30:31.852] Timer tick, asking Buildbucket for the build status
[18:30:32.370] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:32.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:153:0) after 1m0s
[18:31:32.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:153:0)
[18:31:32.408] Timer tick, asking Buildbucket for the build status
[18:31:32.522] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:32.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:154:0) after 1m0s
[18:32:32.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:154:0)
[18:32:32.571] Timer tick, asking Buildbucket for the build status
[18:32:32.663] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:32.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:155:0) after 1m0s
[18:33:32.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:155:0)
[18:33:32.950] Timer tick, asking Buildbucket for the build status
[18:33:33.037] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:33.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:156:0) after 1m0s
[18:34:33.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:156:0)
[18:34:33.253] Timer tick, asking Buildbucket for the build status
[18:34:33.358] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:33.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:157:0) after 1m0s
[18:35:33.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:157:0)
[18:35:33.374] Timer tick, asking Buildbucket for the build status
[18:35:34.409] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:34.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:158:0) after 1m0s
[18:36:34.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:158:0)
[18:36:34.557] Timer tick, asking Buildbucket for the build status
[18:36:34.666] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:34.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:159:0) after 1m0s
[18:37:34.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:159:0)
[18:37:34.744] Timer tick, asking Buildbucket for the build status
[18:37:34.974] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:34.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:160:0) after 1m0s
[18:38:34.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:160:0)
[18:38:34.985] Timer tick, asking Buildbucket for the build status
[18:38:35.180] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:35.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:161:0) after 1m0s
[18:39:35.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:161:0)
[18:39:35.188] Timer tick, asking Buildbucket for the build status
[18:39:35.256] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:35.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:162:0) after 1m0s
[18:40:35.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:162:0)
[18:40:35.250] Timer tick, asking Buildbucket for the build status
[18:40:35.381] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:35.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:163:0) after 1m0s
[18:41:35.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:163:0)
[18:41:35.432] Timer tick, asking Buildbucket for the build status
[18:41:35.755] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:35.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:164:0) after 1m0s
[18:42:35.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:164:0)
[18:42:35.806] Timer tick, asking Buildbucket for the build status
[18:42:35.888] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:35.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:165:0) after 1m0s
[18:43:35.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:165:0)
[18:43:35.979] Timer tick, asking Buildbucket for the build status
[18:43:36.392] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:36.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:166:0) after 1m0s
[18:44:36.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:166:0)
[18:44:36.525] Timer tick, asking Buildbucket for the build status
[18:44:36.680] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:36.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:167:0) after 1m0s
[18:45:36.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:167:0)
[18:45:36.740] Timer tick, asking Buildbucket for the build status
[18:45:36.877] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:36.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:168:0) after 1m0s
[18:46:36.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:168:0)
[18:46:36.924] Timer tick, asking Buildbucket for the build status
[18:46:37.023] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:37.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:169:0) after 1m0s
[18:47:37.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:169:0)
[18:47:37.145] Timer tick, asking Buildbucket for the build status
[18:47:37.264] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:37.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:170:0) after 1m0s
[18:48:37.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:170:0)
[18:48:37.353] Timer tick, asking Buildbucket for the build status
[18:48:37.664] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:37.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:171:0) after 1m0s
[18:49:37.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:171:0)
[18:49:37.742] Timer tick, asking Buildbucket for the build status
[18:49:37.847] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:37.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:172:0) after 1m0s
[18:50:37.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:172:0)
[18:50:37.920] Timer tick, asking Buildbucket for the build status
[18:50:38.226] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:38.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:173:0) after 1m0s
[18:51:38.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:173:0)
[18:51:38.288] Timer tick, asking Buildbucket for the build status
[18:51:38.384] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:38.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:174:0) after 1m0s
[18:52:38.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:174:0)
[18:52:38.451] Timer tick, asking Buildbucket for the build status
[18:52:38.553] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:38.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:175:0) after 1m0s
[18:53:38.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:175:0)
[18:53:38.596] Timer tick, asking Buildbucket for the build status
[18:53:38.907] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:38.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:176:0) after 1m0s
[18:54:39.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:176:0)
[18:54:39.001] Timer tick, asking Buildbucket for the build status
[18:54:39.349] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:39.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:177:0) after 1m0s
[18:55:39.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:177:0)
[18:55:39.340] Timer tick, asking Buildbucket for the build status
[18:55:39.586] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:39.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:178:0) after 1m0s
[18:56:39.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:178:0)
[18:56:39.580] Timer tick, asking Buildbucket for the build status
[18:56:39.664] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:39.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:179:0) after 1m0s
[18:57:39.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:179:0)
[18:57:39.742] Timer tick, asking Buildbucket for the build status
[18:57:39.836] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:39.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:180:0) after 1m0s
[18:58:39.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:180:0)
[18:58:39.865] Timer tick, asking Buildbucket for the build status
[18:58:39.969] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:39.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:181:0) after 1m0s
[18:59:40.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:181:0)
[18:59:40.225] Timer tick, asking Buildbucket for the build status
[18:59:40.357] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:40.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:182:0) after 1m0s
[19:00:40.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:182:0)
[19:00:40.448] Timer tick, asking Buildbucket for the build status
[19:00:40.935] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:40.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:183:0) after 1m0s
[19:01:41.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:183:0)
[19:01:41.087] Timer tick, asking Buildbucket for the build status
[19:01:41.342] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:41.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:184:0) after 1m0s
[19:02:41.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:184:0)
[19:02:41.378] Timer tick, asking Buildbucket for the build status
[19:02:42.453] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:42.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:185:0) after 1m0s
[19:03:42.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:185:0)
[19:03:42.600] Timer tick, asking Buildbucket for the build status
[19:03:42.693] Build 8863732307150211808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:42.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029162371006651392:186:0) after 1m0s
[19:03:53.929] Received PubSub notification, asking Buildbucket for the build status
[19:03:54.138] Build 8863732307150211808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:03:54.138] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1605294232391563",
    "created_by": "project:infra",
    "created_ts": "1605283173378096",
    "id": "8863732307150211808",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4324.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"4c545b04-2fc3-4a30-9d79-776be178c285\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4324.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-jm3o\"], \"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": "1605283204184389",
    "status": "COMPLETED",
    "status_changed_ts": "1605294233391894",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029162371006651392",
      "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/8863732307150211808/+/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:4fdb26e9d2c0aa10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1605294233463507",
    "url": "https://ci.chromium.org/b/8863732307150211808",
    "utcnow_ts": "1605294234114283"
  }
}
[19:03:54.138] Invocation finished in 3h4m23.821640288s with status SUCCEEDED