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

• parent_buildername:publish_tarball
• user_agent:recipe

[16:25:18.344] New invocation is queued and will start shortly
[16:25:18.344] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[16:25:19.394] Starting the invocation (attempt 1)
[16:25:19.465] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:25:19.465] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:25:19.465] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9029885523529878496",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4315.6\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"9ab191a3-b60a-46f6-bd36-12801352fb13\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4315.6\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9029885523529878496",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[16:25:19.880] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1604593519597362",
    "id": "8864455461553380704",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4315.6\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"9ab191a3-b60a-46f6-bd36-12801352fb13\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4315.6\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1604593519755640",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029885523529878496",
      "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/8864455461553380704/+/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": "1604593519755802",
    "url": "https://ci.chromium.org/b/8864455461553380704",
    "utcnow_ts": "1604593519872144"
  }
}
[16:25:19.880] Task URL: https://ci.chromium.org/b/8864455461553380704
[16:25:19.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:2:0) after 1m0s
[16:25:59.342] Received PubSub notification, asking Buildbucket for the build status
[16:25:59.469] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:19.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:2:0)
[16:26:19.961] Timer tick, asking Buildbucket for the build status
[16:26:20.329] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:20.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:4:0) after 1m0s
[16:27:20.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:4:0)
[16:27:20.387] Timer tick, asking Buildbucket for the build status
[16:27:20.495] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:20.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:5:0) after 1m0s
[16:28:20.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:5:0)
[16:28:20.648] Timer tick, asking Buildbucket for the build status
[16:28:20.993] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:20.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:6:0) after 1m0s
[16:29:21.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:6:0)
[16:29:21.208] Timer tick, asking Buildbucket for the build status
[16:29:21.308] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:21.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:7:0) after 1m0s
[16:30:21.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:7:0)
[16:30:21.356] Timer tick, asking Buildbucket for the build status
[16:30:21.548] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:21.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:8:0) after 1m0s
[16:31:21.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:8:0)
[16:31:21.757] Timer tick, asking Buildbucket for the build status
[16:31:21.953] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:21.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:9:0) after 1m0s
[16:32:22.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:9:0)
[16:32:22.004] Timer tick, asking Buildbucket for the build status
[16:32:22.074] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:22.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:10:0) after 1m0s
[16:33:22.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:10:0)
[16:33:22.141] Timer tick, asking Buildbucket for the build status
[16:33:22.683] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:22.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:11:0) after 1m0s
[16:34:22.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:11:0)
[16:34:22.795] Timer tick, asking Buildbucket for the build status
[16:34:23.024] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:23.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:12:0) after 1m0s
[16:35:23.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:12:0)
[16:35:23.069] Timer tick, asking Buildbucket for the build status
[16:35:23.348] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:23.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:13:0) after 1m0s
[16:36:23.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:13:0)
[16:36:23.392] Timer tick, asking Buildbucket for the build status
[16:36:23.532] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:23.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:14:0) after 1m0s
[16:37:23.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:14:0)
[16:37:23.563] Timer tick, asking Buildbucket for the build status
[16:37:23.635] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:23.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:15:0) after 1m0s
[16:38:23.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:15:0)
[16:38:23.686] Timer tick, asking Buildbucket for the build status
[16:38:23.746] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:23.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:16:0) after 1m0s
[16:39:23.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:16:0)
[16:39:23.867] Timer tick, asking Buildbucket for the build status
[16:39:23.931] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:23.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:17:0) after 1m0s
[16:40:24.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:17:0)
[16:40:24.000] Timer tick, asking Buildbucket for the build status
[16:40:24.115] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:24.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:18:0) after 1m0s
[16:41:24.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:18:0)
[16:41:24.148] Timer tick, asking Buildbucket for the build status
[16:41:24.236] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:24.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:19:0) after 1m0s
[16:42:24.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:19:0)
[16:42:24.315] Timer tick, asking Buildbucket for the build status
[16:42:24.399] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:24.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:20:0) after 1m0s
[16:43:24.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:20:0)
[16:43:24.457] Timer tick, asking Buildbucket for the build status
[16:43:24.527] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:24.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:21:0) after 1m0s
[16:44:24.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:21:0)
[16:44:24.589] Timer tick, asking Buildbucket for the build status
[16:44:24.648] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:24.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:22:0) after 1m0s
[16:45:24.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:22:0)
[16:45:24.736] Timer tick, asking Buildbucket for the build status
[16:45:24.853] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:24.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:23:0) after 1m0s
[16:46:24.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:23:0)
[16:46:24.878] Timer tick, asking Buildbucket for the build status
[16:46:25.004] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:25.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:24:0) after 1m0s
[16:47:25.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:24:0)
[16:47:25.036] Timer tick, asking Buildbucket for the build status
[16:47:25.117] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:25.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:25:0) after 1m0s
[16:48:25.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:25:0)
[16:48:25.190] Timer tick, asking Buildbucket for the build status
[16:48:25.802] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:25.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:26:0) after 1m0s
[16:49:25.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:26:0)
[16:49:25.805] Timer tick, asking Buildbucket for the build status
[16:49:25.863] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:25.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:27:0) after 1m0s
[16:50:25.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:27:0)
[16:50:25.949] Timer tick, asking Buildbucket for the build status
[16:50:26.058] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:26.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:28:0) after 1m0s
[16:51:26.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:28:0)
[16:51:26.253] Timer tick, asking Buildbucket for the build status
[16:51:26.383] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:26.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:29:0) after 1m0s
[16:52:26.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:29:0)
[16:52:26.470] Timer tick, asking Buildbucket for the build status
[16:52:26.557] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:26.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:30:0) after 1m0s
[16:53:26.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:30:0)
[16:53:26.651] Timer tick, asking Buildbucket for the build status
[16:53:26.743] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:26.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:31:0) after 1m0s
[16:54:26.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:31:0)
[16:54:26.848] Timer tick, asking Buildbucket for the build status
[16:54:27.069] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:27.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:32:0) after 1m0s
[16:55:27.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:32:0)
[16:55:27.097] Timer tick, asking Buildbucket for the build status
[16:55:27.415] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:27.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:33:0) after 1m0s
[16:56:27.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:33:0)
[16:56:27.479] Timer tick, asking Buildbucket for the build status
[16:56:27.549] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:27.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:34:0) after 1m0s
[16:57:27.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:34:0)
[16:57:27.628] Timer tick, asking Buildbucket for the build status
[16:57:27.697] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:27.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:35:0) after 1m0s
[16:58:27.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:35:0)
[16:58:27.746] Timer tick, asking Buildbucket for the build status
[16:58:27.818] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:27.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:36:0) after 1m0s
[16:59:27.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:36:0)
[16:59:27.810] Timer tick, asking Buildbucket for the build status
[16:59:27.875] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:27.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:37:0) after 1m0s
[17:00:28.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:37:0)
[17:00:28.093] Timer tick, asking Buildbucket for the build status
[17:00:28.778] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:28.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:38:0) after 1m0s
[17:01:29.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:38:0)
[17:01:29.336] Timer tick, asking Buildbucket for the build status
[17:01:29.488] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:29.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:39:0) after 1m0s
[17:02:29.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:39:0)
[17:02:29.730] Timer tick, asking Buildbucket for the build status
[17:02:29.821] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:29.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:40:0) after 1m0s
[17:03:30.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:40:0)
[17:03:30.021] Timer tick, asking Buildbucket for the build status
[17:03:30.109] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:30.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:41:0) after 1m0s
[17:04:30.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:41:0)
[17:04:30.310] Timer tick, asking Buildbucket for the build status
[17:04:30.402] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:30.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:42:0) after 1m0s
[17:05:30.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:42:0)
[17:05:30.663] Timer tick, asking Buildbucket for the build status
[17:05:30.835] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:30.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:43:0) after 1m0s
[17:06:31.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:43:0)
[17:06:31.028] Timer tick, asking Buildbucket for the build status
[17:06:31.105] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:31.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:44:0) after 1m0s
[17:07:31.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:44:0)
[17:07:31.273] Timer tick, asking Buildbucket for the build status
[17:07:31.347] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:31.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:45:0) after 1m0s
[17:08:31.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:45:0)
[17:08:31.491] Timer tick, asking Buildbucket for the build status
[17:08:31.562] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:31.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:46:0) after 1m0s
[17:09:31.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:46:0)
[17:09:31.719] Timer tick, asking Buildbucket for the build status
[17:09:32.411] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:32.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:47:0) after 1m0s
[17:10:32.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:47:0)
[17:10:32.562] Timer tick, asking Buildbucket for the build status
[17:10:32.928] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:32.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:48:0) after 1m0s
[17:11:32.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:48:0)
[17:11:32.964] Timer tick, asking Buildbucket for the build status
[17:11:33.033] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:33.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:49:0) after 1m0s
[17:12:33.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:49:0)
[17:12:33.062] Timer tick, asking Buildbucket for the build status
[17:12:34.188] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:34.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:50:0) after 1m0s
[17:13:34.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:50:0)
[17:13:34.468] Timer tick, asking Buildbucket for the build status
[17:13:34.539] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:34.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:51:0) after 1m0s
[17:14:34.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:51:0)
[17:14:34.639] Timer tick, asking Buildbucket for the build status
[17:14:34.762] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:34.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:52:0) after 1m0s
[17:15:34.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:52:0)
[17:15:34.743] Timer tick, asking Buildbucket for the build status
[17:15:35.106] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:35.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:53:0) after 1m0s
[17:16:35.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:53:0)
[17:16:35.212] Timer tick, asking Buildbucket for the build status
[17:16:35.441] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:35.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:54:0) after 1m0s
[17:17:35.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:54:0)
[17:17:35.544] Timer tick, asking Buildbucket for the build status
[17:17:35.617] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:35.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:55:0) after 1m0s
[17:18:35.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:55:0)
[17:18:35.659] Timer tick, asking Buildbucket for the build status
[17:18:35.765] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:35.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:56:0) after 1m0s
[17:19:35.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:56:0)
[17:19:35.784] Timer tick, asking Buildbucket for the build status
[17:19:35.881] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:35.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:57:0) after 1m0s
[17:20:36.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:57:0)
[17:20:36.004] Timer tick, asking Buildbucket for the build status
[17:20:36.116] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:36.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:58:0) after 1m0s
[17:21:36.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:58:0)
[17:21:36.249] Timer tick, asking Buildbucket for the build status
[17:21:37.126] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:37.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:59:0) after 1m0s
[17:22:37.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:59:0)
[17:22:37.211] Timer tick, asking Buildbucket for the build status
[17:22:37.324] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:37.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:60:0) after 1m0s
[17:23:37.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:60:0)
[17:23:37.618] Timer tick, asking Buildbucket for the build status
[17:23:37.885] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:37.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:61:0) after 1m0s
[17:24:38.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:61:0)
[17:24:38.005] Timer tick, asking Buildbucket for the build status
[17:24:38.072] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:38.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:62:0) after 1m0s
[17:25:38.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:62:0)
[17:25:38.155] Timer tick, asking Buildbucket for the build status
[17:25:38.283] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:38.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:63:0) after 1m0s
[17:26:38.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:63:0)
[17:26:38.286] Timer tick, asking Buildbucket for the build status
[17:26:38.412] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:38.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:64:0) after 1m0s
[17:27:38.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:64:0)
[17:27:38.476] Timer tick, asking Buildbucket for the build status
[17:27:38.672] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:38.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:65:0) after 1m0s
[17:28:38.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:65:0)
[17:28:38.900] Timer tick, asking Buildbucket for the build status
[17:28:38.991] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:38.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:66:0) after 1m0s
[17:29:39.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:66:0)
[17:29:39.162] Timer tick, asking Buildbucket for the build status
[17:29:39.491] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:39.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:67:0) after 1m0s
[17:30:39.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:67:0)
[17:30:39.628] Timer tick, asking Buildbucket for the build status
[17:30:39.785] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:39.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:68:0) after 1m0s
[17:31:39.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:68:0)
[17:31:39.947] Timer tick, asking Buildbucket for the build status
[17:31:40.016] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:40.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:69:0) after 1m0s
[17:32:40.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:69:0)
[17:32:40.175] Timer tick, asking Buildbucket for the build status
[17:32:40.276] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:40.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:70:0) after 1m0s
[17:33:40.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:70:0)
[17:33:40.327] Timer tick, asking Buildbucket for the build status
[17:33:40.407] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:40.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:71:0) after 1m0s
[17:34:40.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:71:0)
[17:34:40.456] Timer tick, asking Buildbucket for the build status
[17:34:40.539] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:40.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:72:0) after 1m0s
[17:35:40.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:72:0)
[17:35:40.598] Timer tick, asking Buildbucket for the build status
[17:35:40.892] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:40.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:73:0) after 1m0s
[17:36:40.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:73:0)
[17:36:40.907] Timer tick, asking Buildbucket for the build status
[17:36:40.965] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:40.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:74:0) after 1m0s
[17:37:41.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:74:0)
[17:37:41.047] Timer tick, asking Buildbucket for the build status
[17:37:41.208] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:41.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:75:0) after 1m0s
[17:38:41.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:75:0)
[17:38:41.253] Timer tick, asking Buildbucket for the build status
[17:38:41.341] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:41.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:76:0) after 1m0s
[17:39:41.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:76:0)
[17:39:41.416] Timer tick, asking Buildbucket for the build status
[17:39:41.587] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:41.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:77:0) after 1m0s
[17:40:41.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:77:0)
[17:40:41.727] Timer tick, asking Buildbucket for the build status
[17:40:41.875] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:41.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:78:0) after 1m0s
[17:41:41.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:78:0)
[17:41:41.954] Timer tick, asking Buildbucket for the build status
[17:41:42.088] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:42.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:79:0) after 1m0s
[17:42:42.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:79:0)
[17:42:42.102] Timer tick, asking Buildbucket for the build status
[17:42:42.268] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:42.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:80:0) after 1m0s
[17:43:42.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:80:0)
[17:43:42.300] Timer tick, asking Buildbucket for the build status
[17:43:42.388] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:42.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:81:0) after 1m0s
[17:44:42.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:81:0)
[17:44:42.410] Timer tick, asking Buildbucket for the build status
[17:44:42.474] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:42.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:82:0) after 1m0s
[17:45:42.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:82:0)
[17:45:42.566] Timer tick, asking Buildbucket for the build status
[17:45:42.818] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:42.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:83:0) after 1m0s
[17:46:42.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:83:0)
[17:46:42.899] Timer tick, asking Buildbucket for the build status
[17:46:42.970] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:42.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:84:0) after 1m0s
[17:47:43.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:84:0)
[17:47:43.044] Timer tick, asking Buildbucket for the build status
[17:47:43.222] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:43.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:85:0) after 1m0s
[17:48:43.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:85:0)
[17:48:43.292] Timer tick, asking Buildbucket for the build status
[17:48:43.368] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:43.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:86:0) after 1m0s
[17:49:43.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:86:0)
[17:49:43.356] Timer tick, asking Buildbucket for the build status
[17:49:43.586] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:43.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:87:0) after 1m0s
[17:50:43.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:87:0)
[17:50:43.655] Timer tick, asking Buildbucket for the build status
[17:50:43.762] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:43.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:88:0) after 1m0s
[17:51:43.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:88:0)
[17:51:43.815] Timer tick, asking Buildbucket for the build status
[17:51:44.391] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:44.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:89:0) after 1m0s
[17:52:44.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:89:0)
[17:52:44.422] Timer tick, asking Buildbucket for the build status
[17:52:44.493] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:44.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:90:0) after 1m0s
[17:53:44.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:90:0)
[17:53:44.585] Timer tick, asking Buildbucket for the build status
[17:53:44.657] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:44.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:91:0) after 1m0s
[17:54:44.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:91:0)
[17:54:44.683] Timer tick, asking Buildbucket for the build status
[17:54:44.755] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:44.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:92:0) after 1m0s
[17:55:44.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:92:0)
[17:55:44.822] Timer tick, asking Buildbucket for the build status
[17:55:45.036] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:45.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:93:0) after 1m0s
[17:56:45.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:93:0)
[17:56:45.143] Timer tick, asking Buildbucket for the build status
[17:56:46.107] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:46.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:94:0) after 1m0s
[17:57:46.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:94:0)
[17:57:46.135] Timer tick, asking Buildbucket for the build status
[17:57:46.248] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:46.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:95:0) after 1m0s
[17:58:46.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:95:0)
[17:58:46.283] Timer tick, asking Buildbucket for the build status
[17:58:46.359] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:46.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:96:0) after 1m0s
[17:59:46.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:96:0)
[17:59:46.396] Timer tick, asking Buildbucket for the build status
[17:59:46.592] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:46.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:97:0) after 1m0s
[18:00:46.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:97:0)
[18:00:46.611] Timer tick, asking Buildbucket for the build status
[18:00:46.759] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:46.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:98:0) after 1m0s
[18:01:46.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:98:0)
[18:01:46.811] Timer tick, asking Buildbucket for the build status
[18:01:47.495] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:47.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:99:0) after 1m0s
[18:02:47.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:99:0)
[18:02:47.567] Timer tick, asking Buildbucket for the build status
[18:02:47.663] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:47.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:100:0) after 1m0s
[18:03:47.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:100:0)
[18:03:47.716] Timer tick, asking Buildbucket for the build status
[18:03:47.868] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:47.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:101:0) after 1m0s
[18:04:47.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:101:0)
[18:04:47.919] Timer tick, asking Buildbucket for the build status
[18:04:48.205] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:48.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:102:0) after 1m0s
[18:05:48.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:102:0)
[18:05:48.297] Timer tick, asking Buildbucket for the build status
[18:05:48.628] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:48.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:103:0) after 1m0s
[18:06:48.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:103:0)
[18:06:48.721] Timer tick, asking Buildbucket for the build status
[18:06:48.862] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:48.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:104:0) after 1m0s
[18:07:48.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:104:0)
[18:07:48.912] Timer tick, asking Buildbucket for the build status
[18:07:48.983] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:48.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:105:0) after 1m0s
[18:08:49.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:105:0)
[18:08:49.119] Timer tick, asking Buildbucket for the build status
[18:08:49.193] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:49.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:106:0) after 1m0s
[18:09:49.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:106:0)
[18:09:49.265] Timer tick, asking Buildbucket for the build status
[18:09:49.348] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:49.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:107:0) after 1m0s
[18:10:49.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:107:0)
[18:10:49.429] Timer tick, asking Buildbucket for the build status
[18:10:49.576] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:49.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:108:0) after 1m0s
[18:11:49.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:108:0)
[18:11:49.649] Timer tick, asking Buildbucket for the build status
[18:11:49.877] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:49.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:109:0) after 1m0s
[18:12:49.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:109:0)
[18:12:49.915] Timer tick, asking Buildbucket for the build status
[18:12:49.977] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:49.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:110:0) after 1m0s
[18:13:50.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:110:0)
[18:13:50.023] Timer tick, asking Buildbucket for the build status
[18:13:51.353] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:51.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:111:0) after 1m0s
[18:14:51.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:111:0)
[18:14:51.391] Timer tick, asking Buildbucket for the build status
[18:14:51.525] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:51.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:112:0) after 1m0s
[18:15:51.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:112:0)
[18:15:51.551] Timer tick, asking Buildbucket for the build status
[18:15:51.714] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:51.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:113:0) after 1m0s
[18:16:51.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:113:0)
[18:16:51.775] Timer tick, asking Buildbucket for the build status
[18:16:51.838] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:51.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:114:0) after 1m0s
[18:17:51.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:114:0)
[18:17:51.878] Timer tick, asking Buildbucket for the build status
[18:17:52.043] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:52.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:115:0) after 1m0s
[18:18:52.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:115:0)
[18:18:52.139] Timer tick, asking Buildbucket for the build status
[18:18:52.294] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:52.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:116:0) after 1m0s
[18:19:52.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:116:0)
[18:19:52.319] Timer tick, asking Buildbucket for the build status
[18:19:52.388] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:52.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:117:0) after 1m0s
[18:20:52.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:117:0)
[18:20:52.421] Timer tick, asking Buildbucket for the build status
[18:20:52.555] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:52.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:118:0) after 1m0s
[18:21:52.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:118:0)
[18:21:52.631] Timer tick, asking Buildbucket for the build status
[18:21:52.796] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:52.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:119:0) after 1m0s
[18:22:52.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:119:0)
[18:22:52.871] Timer tick, asking Buildbucket for the build status
[18:22:52.938] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:52.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:120:0) after 1m0s
[18:23:52.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:120:0)
[18:23:52.972] Timer tick, asking Buildbucket for the build status
[18:23:53.096] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:53.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:121:0) after 1m0s
[18:24:53.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:121:0)
[18:24:53.149] Timer tick, asking Buildbucket for the build status
[18:24:53.898] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:53.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:122:0) after 1m0s
[18:25:53.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:122:0)
[18:25:53.948] Timer tick, asking Buildbucket for the build status
[18:25:54.172] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:54.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:123:0) after 1m0s
[18:26:54.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:123:0)
[18:26:54.178] Timer tick, asking Buildbucket for the build status
[18:26:54.397] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:54.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:124:0) after 1m0s
[18:27:54.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:124:0)
[18:27:54.440] Timer tick, asking Buildbucket for the build status
[18:27:54.525] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:54.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:125:0) after 1m0s
[18:28:54.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:125:0)
[18:28:54.579] Timer tick, asking Buildbucket for the build status
[18:28:54.783] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:54.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:126:0) after 1m0s
[18:29:54.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:126:0)
[18:29:54.893] Timer tick, asking Buildbucket for the build status
[18:29:54.969] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:54.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:127:0) after 1m0s
[18:30:54.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:127:0)
[18:30:54.965] Timer tick, asking Buildbucket for the build status
[18:30:55.084] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:55.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:128:0) after 1m0s
[18:31:55.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:128:0)
[18:31:55.174] Timer tick, asking Buildbucket for the build status
[18:31:55.378] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:55.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:129:0) after 1m0s
[18:32:55.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:129:0)
[18:32:55.440] Timer tick, asking Buildbucket for the build status
[18:32:55.509] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:55.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:130:0) after 1m0s
[18:33:55.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:130:0)
[18:33:55.565] Timer tick, asking Buildbucket for the build status
[18:33:55.653] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:55.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:131:0) after 1m0s
[18:34:55.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:131:0)
[18:34:55.681] Timer tick, asking Buildbucket for the build status
[18:34:55.767] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:55.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:132:0) after 1m0s
[18:35:55.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:132:0)
[18:35:55.840] Timer tick, asking Buildbucket for the build status
[18:35:56.043] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:56.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:133:0) after 1m0s
[18:36:56.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:133:0)
[18:36:56.209] Timer tick, asking Buildbucket for the build status
[18:36:56.301] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:56.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:134:0) after 1m0s
[18:37:56.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:134:0)
[18:37:56.489] Timer tick, asking Buildbucket for the build status
[18:37:56.628] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:56.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:135:0) after 1m0s
[18:38:58.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:135:0)
[18:38:58.149] Timer tick, asking Buildbucket for the build status
[18:38:58.225] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:58.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:136:0) after 1m0s
[18:39:58.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:136:0)
[18:39:58.251] Timer tick, asking Buildbucket for the build status
[18:39:59.177] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:59.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:137:0) after 1m0s
[18:40:59.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:137:0)
[18:40:59.254] Timer tick, asking Buildbucket for the build status
[18:40:59.447] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:59.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:138:0) after 1m0s
[18:41:59.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:138:0)
[18:41:59.449] Timer tick, asking Buildbucket for the build status
[18:42:00.222] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:00.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:139:0) after 1m0s
[18:43:00.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:139:0)
[18:43:00.228] Timer tick, asking Buildbucket for the build status
[18:43:00.338] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:00.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:140:0) after 1m0s
[18:44:00.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:140:0)
[18:44:00.432] Timer tick, asking Buildbucket for the build status
[18:44:00.660] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:00.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:141:0) after 1m0s
[18:45:00.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:141:0)
[18:45:00.733] Timer tick, asking Buildbucket for the build status
[18:45:00.822] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:00.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:142:0) after 1m0s
[18:46:01.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:142:0)
[18:46:01.466] Timer tick, asking Buildbucket for the build status
[18:46:02.068] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:02.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:143:0) after 1m0s
[18:47:02.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:143:0)
[18:47:02.183] Timer tick, asking Buildbucket for the build status
[18:47:02.619] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:02.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:144:0) after 1m0s
[18:48:02.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:144:0)
[18:48:02.686] Timer tick, asking Buildbucket for the build status
[18:48:03.282] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:03.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:145:0) after 1m0s
[18:49:03.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:145:0)
[18:49:03.359] Timer tick, asking Buildbucket for the build status
[18:49:03.778] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:03.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:146:0) after 1m0s
[18:50:03.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:146:0)
[18:50:03.837] Timer tick, asking Buildbucket for the build status
[18:50:04.817] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:04.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:147:0) after 1m0s
[18:51:04.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:147:0)
[18:51:04.937] Timer tick, asking Buildbucket for the build status
[18:51:05.095] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:05.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:148:0) after 1m0s
[18:52:05.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:148:0)
[18:52:05.173] Timer tick, asking Buildbucket for the build status
[18:52:05.248] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:05.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:149:0) after 1m0s
[18:53:05.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:149:0)
[18:53:05.316] Timer tick, asking Buildbucket for the build status
[18:53:05.437] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:05.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:150:0) after 1m0s
[18:54:05.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:150:0)
[18:54:05.474] Timer tick, asking Buildbucket for the build status
[18:54:05.747] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:05.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:151:0) after 1m0s
[18:55:05.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:151:0)
[18:55:05.791] Timer tick, asking Buildbucket for the build status
[18:55:05.857] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:05.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:152:0) after 1m0s
[18:56:05.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:152:0)
[18:56:05.923] Timer tick, asking Buildbucket for the build status
[18:56:06.045] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:06.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:153:0) after 1m0s
[18:57:06.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:153:0)
[18:57:06.118] Timer tick, asking Buildbucket for the build status
[18:57:06.193] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:06.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:154:0) after 1m0s
[18:58:06.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:154:0)
[18:58:06.201] Timer tick, asking Buildbucket for the build status
[18:58:06.267] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:06.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:155:0) after 1m0s
[18:59:06.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:155:0)
[18:59:06.369] Timer tick, asking Buildbucket for the build status
[18:59:06.465] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:06.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:156:0) after 1m0s
[19:00:06.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:156:0)
[19:00:06.626] Timer tick, asking Buildbucket for the build status
[19:00:06.810] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:06.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:157:0) after 1m0s
[19:01:06.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:157:0)
[19:01:06.986] Timer tick, asking Buildbucket for the build status
[19:01:07.163] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:07.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:158:0) after 1m0s
[19:02:07.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:158:0)
[19:02:07.385] Timer tick, asking Buildbucket for the build status
[19:02:08.026] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:08.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:159:0) after 1m0s
[19:03:08.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:159:0)
[19:03:08.054] Timer tick, asking Buildbucket for the build status
[19:03:08.141] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:08.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:160:0) after 1m0s
[19:04:08.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:160:0)
[19:04:08.322] Timer tick, asking Buildbucket for the build status
[19:04:08.440] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:08.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:161:0) after 1m0s
[19:05:08.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:161:0)
[19:05:08.492] Timer tick, asking Buildbucket for the build status
[19:05:08.618] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:08.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:162:0) after 1m0s
[19:06:08.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:162:0)
[19:06:08.664] Timer tick, asking Buildbucket for the build status
[19:06:08.841] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:08.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:163:0) after 1m0s
[19:07:08.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:163:0)
[19:07:08.901] Timer tick, asking Buildbucket for the build status
[19:07:09.002] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:09.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:164:0) after 1m0s
[19:08:09.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:164:0)
[19:08:09.134] Timer tick, asking Buildbucket for the build status
[19:08:09.238] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:09.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:165:0) after 1m0s
[19:09:09.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:165:0)
[19:09:09.247] Timer tick, asking Buildbucket for the build status
[19:09:09.322] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:09.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:166:0) after 1m0s
[19:10:09.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:166:0)
[19:10:09.402] Timer tick, asking Buildbucket for the build status
[19:10:09.699] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:09.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:167:0) after 1m0s
[19:11:09.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:167:0)
[19:11:09.754] Timer tick, asking Buildbucket for the build status
[19:11:09.885] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:09.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:168:0) after 1m0s
[19:12:09.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:168:0)
[19:12:09.948] Timer tick, asking Buildbucket for the build status
[19:12:10.068] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:10.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:169:0) after 1m0s
[19:13:10.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:169:0)
[19:13:10.103] Timer tick, asking Buildbucket for the build status
[19:13:10.246] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:10.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:170:0) after 1m0s
[19:14:10.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:170:0)
[19:14:10.389] Timer tick, asking Buildbucket for the build status
[19:14:10.450] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:10.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:171:0) after 1m0s
[19:15:10.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:171:0)
[19:15:10.542] Timer tick, asking Buildbucket for the build status
[19:15:10.921] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:10.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:172:0) after 1m0s
[19:16:10.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:172:0)
[19:16:10.942] Timer tick, asking Buildbucket for the build status
[19:16:11.315] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:11.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:173:0) after 1m0s
[19:17:11.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:173:0)
[19:17:11.472] Timer tick, asking Buildbucket for the build status
[19:17:11.580] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:11.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:174:0) after 1m0s
[19:18:11.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:174:0)
[19:18:11.656] Timer tick, asking Buildbucket for the build status
[19:18:11.729] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:11.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:175:0) after 1m0s
[19:19:11.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:175:0)
[19:19:11.764] Timer tick, asking Buildbucket for the build status
[19:19:11.830] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:11.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:176:0) after 1m0s
[19:20:11.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:176:0)
[19:20:11.857] Timer tick, asking Buildbucket for the build status
[19:20:11.935] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:11.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:177:0) after 1m0s
[19:21:12.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:177:0)
[19:21:12.080] Timer tick, asking Buildbucket for the build status
[19:21:12.231] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:12.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:178:0) after 1m0s
[19:22:12.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:178:0)
[19:22:12.228] Timer tick, asking Buildbucket for the build status
[19:22:12.582] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:12.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:179:0) after 1m0s
[19:23:12.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:179:0)
[19:23:12.658] Timer tick, asking Buildbucket for the build status
[19:23:12.723] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:12.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:180:0) after 1m0s
[19:24:12.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:180:0)
[19:24:12.782] Timer tick, asking Buildbucket for the build status
[19:24:12.849] Build 8864455461553380704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:12.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029885523529878496:181:0) after 1m0s
[19:25:01.309] Received PubSub notification, asking Buildbucket for the build status
[19:25:01.525] Build 8864455461553380704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:25:01.525] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1604604300660098",
    "created_by": "project:infra",
    "created_ts": "1604593519597362",
    "id": "8864455461553380704",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4315.6\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"9ab191a3-b60a-46f6-bd36-12801352fb13\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4315.6\"}}",
    "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-dz7f\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1604593558025193",
    "status": "COMPLETED",
    "status_changed_ts": "1604604301017553",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029885523529878496",
      "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/8864455461553380704/+/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:4fb20b95872e0610",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1604604301031583",
    "url": "https://ci.chromium.org/b/8864455461553380704",
    "utcnow_ts": "1604604301504093"
  }
}
[19:25:01.525] Invocation finished in 2h59m43.196912523s with status SUCCEEDED