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

• parent_buildername:publish_tarball
• user_agent:recipe

[08:29:26.906] New invocation is queued and will start shortly
[08:29:26.906] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:29:28.174] Starting the invocation (attempt 1)
[08:29:28.233] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:29:28.233] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:29:28.233] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9027559940757535232",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4342.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"34cd658b-a70a-42a6-a8a0-d0c81cb353b9\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4342.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:9027559940757535232",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:29:28.770] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1606811368395594",
    "id": "8862129878532064032",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4342.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"34cd658b-a70a-42a6-a8a0-d0c81cb353b9\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4342.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1606811368627692",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9027559940757535232",
      "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/8862129878532064032/+/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": "1606811368627800",
    "url": "https://ci.chromium.org/b/8862129878532064032",
    "utcnow_ts": "1606811368762092"
  }
}
[08:29:28.770] Task URL: https://ci.chromium.org/b/8862129878532064032
[08:29:28.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:2:0) after 1m0s
[08:30:25.171] Received PubSub notification, asking Buildbucket for the build status
[08:30:25.286] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:28.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:2:0)
[08:30:28.800] Timer tick, asking Buildbucket for the build status
[08:30:28.874] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:28.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:4:0) after 1m0s
[08:31:29.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:4:0)
[08:31:29.016] Timer tick, asking Buildbucket for the build status
[08:31:29.437] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:29.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:5:0) after 1m0s
[08:32:29.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:5:0)
[08:32:29.456] Timer tick, asking Buildbucket for the build status
[08:32:29.521] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:29.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:6:0) after 1m0s
[08:33:29.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:6:0)
[08:33:29.541] Timer tick, asking Buildbucket for the build status
[08:33:29.631] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:29.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:7:0) after 1m0s
[08:34:29.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:7:0)
[08:34:29.658] Timer tick, asking Buildbucket for the build status
[08:34:29.712] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:29.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:8:0) after 1m0s
[08:35:29.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:8:0)
[08:35:29.737] Timer tick, asking Buildbucket for the build status
[08:35:30.385] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:30.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:9:0) after 1m0s
[08:36:30.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:9:0)
[08:36:30.429] Timer tick, asking Buildbucket for the build status
[08:36:30.497] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:30.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:10:0) after 1m0s
[08:37:30.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:10:0)
[08:37:30.538] Timer tick, asking Buildbucket for the build status
[08:37:30.633] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:30.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:11:0) after 1m0s
[08:38:30.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:11:0)
[08:38:30.659] Timer tick, asking Buildbucket for the build status
[08:38:30.740] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:30.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:12:0) after 1m0s
[08:39:30.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:12:0)
[08:39:30.757] Timer tick, asking Buildbucket for the build status
[08:39:30.885] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:30.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:13:0) after 1m0s
[08:40:30.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:13:0)
[08:40:30.940] Timer tick, asking Buildbucket for the build status
[08:40:31.019] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:31.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:14:0) after 1m0s
[08:41:31.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:14:0)
[08:41:31.159] Timer tick, asking Buildbucket for the build status
[08:41:31.537] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:31.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:15:0) after 1m0s
[08:42:31.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:15:0)
[08:42:31.566] Timer tick, asking Buildbucket for the build status
[08:42:31.629] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:31.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:16:0) after 1m0s
[08:43:31.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:16:0)
[08:43:31.649] Timer tick, asking Buildbucket for the build status
[08:43:31.742] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:31.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:17:0) after 1m0s
[08:44:31.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:17:0)
[08:44:31.734] Timer tick, asking Buildbucket for the build status
[08:44:32.425] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:32.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:18:0) after 1m0s
[08:45:32.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:18:0)
[08:45:32.487] Timer tick, asking Buildbucket for the build status
[08:45:32.602] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:32.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:19:0) after 1m0s
[08:46:32.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:19:0)
[08:46:32.638] Timer tick, asking Buildbucket for the build status
[08:46:32.696] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:32.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:20:0) after 1m0s
[08:47:32.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:20:0)
[08:47:32.766] Timer tick, asking Buildbucket for the build status
[08:47:32.873] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:32.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:21:0) after 1m0s
[08:48:32.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:21:0)
[08:48:32.964] Timer tick, asking Buildbucket for the build status
[08:48:33.172] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:33.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:22:0) after 1m0s
[08:49:33.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:22:0)
[08:49:33.216] Timer tick, asking Buildbucket for the build status
[08:49:34.046] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:34.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:23:0) after 1m0s
[08:50:34.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:23:0)
[08:50:34.058] Timer tick, asking Buildbucket for the build status
[08:50:34.129] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:34.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:24:0) after 1m0s
[08:51:34.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:24:0)
[08:51:34.208] Timer tick, asking Buildbucket for the build status
[08:51:34.540] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:34.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:25:0) after 1m0s
[08:52:34.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:25:0)
[08:52:34.595] Timer tick, asking Buildbucket for the build status
[08:52:34.686] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:34.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:26:0) after 1m0s
[08:53:35.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:26:0)
[08:53:35.166] Timer tick, asking Buildbucket for the build status
[08:53:35.284] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:35.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:27:0) after 1m0s
[08:54:36.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:27:0)
[08:54:36.308] Timer tick, asking Buildbucket for the build status
[08:54:36.488] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:36.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:28:0) after 1m0s
[08:55:36.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:28:0)
[08:55:36.636] Timer tick, asking Buildbucket for the build status
[08:55:36.696] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:36.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:29:0) after 1m0s
[08:56:38.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:29:0)
[08:56:38.047] Timer tick, asking Buildbucket for the build status
[08:56:38.270] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:38.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:30:0) after 1m0s
[08:57:38.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:30:0)
[08:57:38.363] Timer tick, asking Buildbucket for the build status
[08:57:38.790] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:38.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:31:0) after 1m0s
[08:58:39.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:31:0)
[08:58:39.045] Timer tick, asking Buildbucket for the build status
[08:58:39.556] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:39.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:32:0) after 1m0s
[08:59:39.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:32:0)
[08:59:39.665] Timer tick, asking Buildbucket for the build status
[08:59:39.789] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:39.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:33:0) after 1m0s
[09:00:40.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:33:0)
[09:00:40.026] Timer tick, asking Buildbucket for the build status
[09:00:40.114] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:40.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:34:0) after 1m0s
[09:01:40.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:34:0)
[09:01:40.377] Timer tick, asking Buildbucket for the build status
[09:01:40.438] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:40.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:35:0) after 1m0s
[09:02:40.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:35:0)
[09:02:40.473] Timer tick, asking Buildbucket for the build status
[09:02:40.541] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:40.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:36:0) after 1m0s
[09:03:40.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:36:0)
[09:03:40.535] Timer tick, asking Buildbucket for the build status
[09:03:40.613] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:40.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:37:0) after 1m0s
[09:04:40.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:37:0)
[09:04:40.663] Timer tick, asking Buildbucket for the build status
[09:04:40.790] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:40.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:38:0) after 1m0s
[09:05:40.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:38:0)
[09:05:40.886] Timer tick, asking Buildbucket for the build status
[09:05:40.954] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:40.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:39:0) after 1m0s
[09:06:41.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:39:0)
[09:06:41.087] Timer tick, asking Buildbucket for the build status
[09:06:41.202] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:41.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:40:0) after 1m0s
[09:07:41.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:40:0)
[09:07:41.283] Timer tick, asking Buildbucket for the build status
[09:07:41.583] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:41.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:41:0) after 1m0s
[09:08:41.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:41:0)
[09:08:41.811] Timer tick, asking Buildbucket for the build status
[09:08:42.059] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:42.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:42:0) after 1m0s
[09:09:42.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:42:0)
[09:09:42.143] Timer tick, asking Buildbucket for the build status
[09:09:42.301] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:42.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:43:0) after 1m0s
[09:10:42.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:43:0)
[09:10:42.283] Timer tick, asking Buildbucket for the build status
[09:10:42.589] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:42.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:44:0) after 1m0s
[09:11:42.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:44:0)
[09:11:42.637] Timer tick, asking Buildbucket for the build status
[09:11:42.707] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:42.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:45:0) after 1m0s
[09:12:42.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:45:0)
[09:12:42.731] Timer tick, asking Buildbucket for the build status
[09:12:42.836] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:42.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:46:0) after 1m0s
[09:13:42.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:46:0)
[09:13:42.857] Timer tick, asking Buildbucket for the build status
[09:13:43.880] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:43.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:47:0) after 1m0s
[09:14:43.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:47:0)
[09:14:43.903] Timer tick, asking Buildbucket for the build status
[09:14:44.024] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:44.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:48:0) after 1m0s
[09:15:44.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:48:0)
[09:15:44.199] Timer tick, asking Buildbucket for the build status
[09:15:45.094] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:45.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:49:0) after 1m0s
[09:16:45.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:49:0)
[09:16:45.134] Timer tick, asking Buildbucket for the build status
[09:16:45.206] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:45.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:50:0) after 1m0s
[09:17:45.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:50:0)
[09:17:45.281] Timer tick, asking Buildbucket for the build status
[09:17:45.389] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:45.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:51:0) after 1m0s
[09:18:45.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:51:0)
[09:18:45.469] Timer tick, asking Buildbucket for the build status
[09:18:45.551] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:45.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:52:0) after 1m0s
[09:19:45.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:52:0)
[09:19:45.603] Timer tick, asking Buildbucket for the build status
[09:19:45.799] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:45.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:53:0) after 1m0s
[09:20:45.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:53:0)
[09:20:45.826] Timer tick, asking Buildbucket for the build status
[09:20:45.938] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:45.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:54:0) after 1m0s
[09:21:45.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:54:0)
[09:21:45.921] Timer tick, asking Buildbucket for the build status
[09:21:45.993] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:45.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:55:0) after 1m0s
[09:22:46.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:55:0)
[09:22:46.017] Timer tick, asking Buildbucket for the build status
[09:22:46.091] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:46.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:56:0) after 1m0s
[09:23:46.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:56:0)
[09:23:46.068] Timer tick, asking Buildbucket for the build status
[09:23:46.342] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:46.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:57:0) after 1m0s
[09:24:46.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:57:0)
[09:24:46.440] Timer tick, asking Buildbucket for the build status
[09:24:46.565] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:46.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:58:0) after 1m0s
[09:25:46.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:58:0)
[09:25:46.604] Timer tick, asking Buildbucket for the build status
[09:25:46.668] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:46.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:59:0) after 1m0s
[09:26:46.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:59:0)
[09:26:46.685] Timer tick, asking Buildbucket for the build status
[09:26:46.756] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:46.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:60:0) after 1m0s
[09:27:46.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:60:0)
[09:27:46.801] Timer tick, asking Buildbucket for the build status
[09:27:46.938] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:46.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:61:0) after 1m0s
[09:28:46.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:61:0)
[09:28:46.976] Timer tick, asking Buildbucket for the build status
[09:28:47.193] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:47.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:62:0) after 1m0s
[09:29:47.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:62:0)
[09:29:47.295] Timer tick, asking Buildbucket for the build status
[09:29:47.608] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:47.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:63:0) after 1m0s
[09:30:47.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:63:0)
[09:30:47.676] Timer tick, asking Buildbucket for the build status
[09:30:47.746] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:47.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:64:0) after 1m0s
[09:31:47.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:64:0)
[09:31:47.814] Timer tick, asking Buildbucket for the build status
[09:31:53.951] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:53.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:65:0) after 1m0s
[09:32:54.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:65:0)
[09:32:54.019] Timer tick, asking Buildbucket for the build status
[09:32:54.080] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:54.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:66:0) after 1m0s
[09:33:54.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:66:0)
[09:33:54.108] Timer tick, asking Buildbucket for the build status
[09:33:54.189] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:54.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:67:0) after 1m0s
[09:34:54.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:67:0)
[09:34:54.218] Timer tick, asking Buildbucket for the build status
[09:34:54.403] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:54.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:68:0) after 1m0s
[09:35:54.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:68:0)
[09:35:54.467] Timer tick, asking Buildbucket for the build status
[09:35:54.529] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:54.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:69:0) after 1m0s
[09:36:54.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:69:0)
[09:36:54.628] Timer tick, asking Buildbucket for the build status
[09:36:54.800] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:54.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:70:0) after 1m0s
[09:37:54.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:70:0)
[09:37:54.876] Timer tick, asking Buildbucket for the build status
[09:37:55.099] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:55.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:71:0) after 1m0s
[09:38:55.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:71:0)
[09:38:55.125] Timer tick, asking Buildbucket for the build status
[09:38:55.316] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:55.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:72:0) after 1m0s
[09:39:55.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:72:0)
[09:39:55.326] Timer tick, asking Buildbucket for the build status
[09:39:55.667] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:55.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:73:0) after 1m0s
[09:40:55.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:73:0)
[09:40:55.668] Timer tick, asking Buildbucket for the build status
[09:40:55.731] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:55.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:74:0) after 1m0s
[09:41:55.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:74:0)
[09:41:55.746] Timer tick, asking Buildbucket for the build status
[09:41:55.807] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:55.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:75:0) after 1m0s
[09:42:55.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:75:0)
[09:42:55.975] Timer tick, asking Buildbucket for the build status
[09:43:06.771] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:06.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:76:0) after 1m0s
[09:44:07.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:76:0)
[09:44:07.101] Timer tick, asking Buildbucket for the build status
[09:44:07.175] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:07.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:77:0) after 1m0s
[09:45:07.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:77:0)
[09:45:07.215] Timer tick, asking Buildbucket for the build status
[09:45:07.488] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:07.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:78:0) after 1m0s
[09:46:07.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:78:0)
[09:46:07.583] Timer tick, asking Buildbucket for the build status
[09:46:07.678] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:07.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:79:0) after 1m0s
[09:47:07.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:79:0)
[09:47:07.893] Timer tick, asking Buildbucket for the build status
[09:47:07.965] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:07.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:80:0) after 1m0s
[09:48:08.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:80:0)
[09:48:08.177] Timer tick, asking Buildbucket for the build status
[09:48:08.243] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:08.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:81:0) after 1m0s
[09:49:08.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:81:0)
[09:49:08.443] Timer tick, asking Buildbucket for the build status
[09:49:08.533] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:08.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:82:0) after 1m0s
[09:50:08.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:82:0)
[09:50:08.902] Timer tick, asking Buildbucket for the build status
[09:50:09.184] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:09.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:83:0) after 1m0s
[09:51:09.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:83:0)
[09:51:09.797] Timer tick, asking Buildbucket for the build status
[09:51:09.873] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:09.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:84:0) after 1m0s
[09:52:10.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:84:0)
[09:52:10.059] Timer tick, asking Buildbucket for the build status
[09:52:10.322] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:10.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:85:0) after 1m0s
[09:53:10.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:85:0)
[09:53:10.355] Timer tick, asking Buildbucket for the build status
[09:53:10.431] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:10.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:86:0) after 1m0s
[09:54:10.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:86:0)
[09:54:10.459] Timer tick, asking Buildbucket for the build status
[09:54:10.513] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:10.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:87:0) after 1m0s
[09:55:10.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:87:0)
[09:55:10.579] Timer tick, asking Buildbucket for the build status
[09:55:10.841] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:10.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:88:0) after 1m0s
[09:56:10.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:88:0)
[09:56:10.874] Timer tick, asking Buildbucket for the build status
[09:56:10.990] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:10.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:89:0) after 1m0s
[09:57:11.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:89:0)
[09:57:11.127] Timer tick, asking Buildbucket for the build status
[09:57:11.275] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:11.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:90:0) after 1m0s
[09:58:11.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:90:0)
[09:58:11.321] Timer tick, asking Buildbucket for the build status
[09:58:11.399] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:11.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:91:0) after 1m0s
[09:59:11.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:91:0)
[09:59:11.420] Timer tick, asking Buildbucket for the build status
[09:59:11.478] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:11.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:92:0) after 1m0s
[10:00:11.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:92:0)
[10:00:11.645] Timer tick, asking Buildbucket for the build status
[10:00:11.754] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:11.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:93:0) after 1m0s
[10:01:11.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:93:0)
[10:01:11.826] Timer tick, asking Buildbucket for the build status
[10:01:12.336] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:12.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:94:0) after 1m0s
[10:02:12.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:94:0)
[10:02:12.463] Timer tick, asking Buildbucket for the build status
[10:02:12.768] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:12.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:95:0) after 1m0s
[10:03:12.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:95:0)
[10:03:12.793] Timer tick, asking Buildbucket for the build status
[10:03:12.863] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:12.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:96:0) after 1m0s
[10:04:12.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:96:0)
[10:04:12.907] Timer tick, asking Buildbucket for the build status
[10:04:13.119] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:13.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:97:0) after 1m0s
[10:05:13.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:97:0)
[10:05:13.144] Timer tick, asking Buildbucket for the build status
[10:05:13.299] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:13.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:98:0) after 1m0s
[10:06:13.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:98:0)
[10:06:13.348] Timer tick, asking Buildbucket for the build status
[10:06:13.546] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:13.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:99:0) after 1m0s
[10:07:13.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:99:0)
[10:07:13.575] Timer tick, asking Buildbucket for the build status
[10:07:13.822] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:13.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:100:0) after 1m0s
[10:08:13.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:100:0)
[10:08:13.890] Timer tick, asking Buildbucket for the build status
[10:08:13.968] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:13.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:101:0) after 1m0s
[10:09:14.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:101:0)
[10:09:14.156] Timer tick, asking Buildbucket for the build status
[10:09:14.274] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:14.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:102:0) after 1m0s
[10:10:14.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:102:0)
[10:10:14.404] Timer tick, asking Buildbucket for the build status
[10:10:14.543] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:14.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:103:0) after 1m0s
[10:11:14.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:103:0)
[10:11:14.549] Timer tick, asking Buildbucket for the build status
[10:11:14.625] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:14.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:104:0) after 1m0s
[10:12:14.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:104:0)
[10:12:14.638] Timer tick, asking Buildbucket for the build status
[10:12:14.728] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:14.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:105:0) after 1m0s
[10:13:14.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:105:0)
[10:13:14.842] Timer tick, asking Buildbucket for the build status
[10:13:15.037] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:15.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:106:0) after 1m0s
[10:14:15.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:106:0)
[10:14:15.121] Timer tick, asking Buildbucket for the build status
[10:14:15.297] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:15.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:107:0) after 1m0s
[10:15:15.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:107:0)
[10:15:15.325] Timer tick, asking Buildbucket for the build status
[10:15:17.773] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:17.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:108:0) after 1m0s
[10:16:17.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:108:0)
[10:16:17.818] Timer tick, asking Buildbucket for the build status
[10:16:18.078] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:18.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:109:0) after 1m0s
[10:17:18.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:109:0)
[10:17:18.198] Timer tick, asking Buildbucket for the build status
[10:17:18.329] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:18.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:110:0) after 1m0s
[10:18:18.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:110:0)
[10:18:18.455] Timer tick, asking Buildbucket for the build status
[10:18:18.556] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:18.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:111:0) after 1m0s
[10:19:18.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:111:0)
[10:19:18.674] Timer tick, asking Buildbucket for the build status
[10:19:18.742] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:18.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:112:0) after 1m0s
[10:20:18.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:112:0)
[10:20:18.795] Timer tick, asking Buildbucket for the build status
[10:20:19.125] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:19.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:113:0) after 1m0s
[10:21:19.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:113:0)
[10:21:19.201] Timer tick, asking Buildbucket for the build status
[10:21:19.299] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:19.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:114:0) after 1m0s
[10:22:19.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:114:0)
[10:22:19.329] Timer tick, asking Buildbucket for the build status
[10:22:19.381] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:19.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:115:0) after 1m0s
[10:23:19.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:115:0)
[10:23:19.479] Timer tick, asking Buildbucket for the build status
[10:23:19.544] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:19.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:116:0) after 1m0s
[10:24:19.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:116:0)
[10:24:19.638] Timer tick, asking Buildbucket for the build status
[10:24:19.742] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:19.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:117:0) after 1m0s
[10:25:19.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:117:0)
[10:25:19.880] Timer tick, asking Buildbucket for the build status
[10:25:20.031] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:20.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:118:0) after 1m0s
[10:26:20.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:118:0)
[10:26:20.169] Timer tick, asking Buildbucket for the build status
[10:26:20.228] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:20.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:119:0) after 1m0s
[10:27:20.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:119:0)
[10:27:20.284] Timer tick, asking Buildbucket for the build status
[10:27:20.340] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:20.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:120:0) after 1m0s
[10:28:20.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:120:0)
[10:28:20.339] Timer tick, asking Buildbucket for the build status
[10:28:20.463] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:20.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:121:0) after 1m0s
[10:29:20.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:121:0)
[10:29:20.498] Timer tick, asking Buildbucket for the build status
[10:29:20.621] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:20.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:122:0) after 1m0s
[10:30:20.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:122:0)
[10:30:20.783] Timer tick, asking Buildbucket for the build status
[10:30:20.924] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:20.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:123:0) after 1m0s
[10:31:20.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:123:0)
[10:31:20.992] Timer tick, asking Buildbucket for the build status
[10:31:21.062] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:21.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:124:0) after 1m0s
[10:32:21.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:124:0)
[10:32:21.116] Timer tick, asking Buildbucket for the build status
[10:32:21.172] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:21.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:125:0) after 1m0s
[10:33:21.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:125:0)
[10:33:21.277] Timer tick, asking Buildbucket for the build status
[10:33:21.338] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:21.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:126:0) after 1m0s
[10:34:21.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:126:0)
[10:34:21.453] Timer tick, asking Buildbucket for the build status
[10:34:21.521] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:21.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:127:0) after 1m0s
[10:35:21.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:127:0)
[10:35:21.631] Timer tick, asking Buildbucket for the build status
[10:35:21.775] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:21.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:128:0) after 1m0s
[10:36:21.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:128:0)
[10:36:21.832] Timer tick, asking Buildbucket for the build status
[10:36:21.899] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:21.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:129:0) after 1m0s
[10:37:21.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:129:0)
[10:37:21.985] Timer tick, asking Buildbucket for the build status
[10:37:22.202] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:22.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:130:0) after 1m0s
[10:38:22.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:130:0)
[10:38:22.334] Timer tick, asking Buildbucket for the build status
[10:38:22.459] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:22.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:131:0) after 1m0s
[10:39:22.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:131:0)
[10:39:22.486] Timer tick, asking Buildbucket for the build status
[10:39:22.547] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:22.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:132:0) after 1m0s
[10:40:22.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:132:0)
[10:40:22.573] Timer tick, asking Buildbucket for the build status
[10:40:23.004] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:23.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:133:0) after 1m0s
[10:41:23.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:133:0)
[10:41:23.167] Timer tick, asking Buildbucket for the build status
[10:41:23.230] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:23.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:134:0) after 1m0s
[10:42:23.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:134:0)
[10:42:23.325] Timer tick, asking Buildbucket for the build status
[10:42:23.415] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:23.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:135:0) after 1m0s
[10:43:23.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:135:0)
[10:43:23.529] Timer tick, asking Buildbucket for the build status
[10:43:23.595] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:23.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:136:0) after 1m0s
[10:44:23.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:136:0)
[10:44:23.772] Timer tick, asking Buildbucket for the build status
[10:44:23.956] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:23.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:137:0) after 1m0s
[10:45:23.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:137:0)
[10:45:23.990] Timer tick, asking Buildbucket for the build status
[10:45:24.391] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:24.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:138:0) after 1m0s
[10:46:24.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:138:0)
[10:46:24.491] Timer tick, asking Buildbucket for the build status
[10:46:24.965] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:24.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:139:0) after 1m0s
[10:47:25.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:139:0)
[10:47:25.075] Timer tick, asking Buildbucket for the build status
[10:47:25.331] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:25.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:140:0) after 1m0s
[10:48:25.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:140:0)
[10:48:25.477] Timer tick, asking Buildbucket for the build status
[10:48:25.555] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:25.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:141:0) after 1m0s
[10:49:25.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:141:0)
[10:49:25.802] Timer tick, asking Buildbucket for the build status
[10:49:26.112] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:26.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:142:0) after 1m0s
[10:50:26.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:142:0)
[10:50:26.414] Timer tick, asking Buildbucket for the build status
[10:50:26.843] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:26.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:143:0) after 1m0s
[10:51:26.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:143:0)
[10:51:26.923] Timer tick, asking Buildbucket for the build status
[10:51:26.992] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:26.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:144:0) after 1m0s
[10:52:27.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:144:0)
[10:52:27.069] Timer tick, asking Buildbucket for the build status
[10:52:27.212] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:27.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:145:0) after 1m0s
[10:53:27.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:145:0)
[10:53:27.263] Timer tick, asking Buildbucket for the build status
[10:53:27.326] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:27.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:146:0) after 1m0s
[10:54:27.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:146:0)
[10:54:27.369] Timer tick, asking Buildbucket for the build status
[10:54:27.426] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:27.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:147:0) after 1m0s
[10:55:27.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:147:0)
[10:55:27.616] Timer tick, asking Buildbucket for the build status
[10:55:28.111] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:28.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:148:0) after 1m0s
[10:56:28.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:148:0)
[10:56:28.175] Timer tick, asking Buildbucket for the build status
[10:56:28.227] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:28.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:149:0) after 1m0s
[10:57:28.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:149:0)
[10:57:28.281] Timer tick, asking Buildbucket for the build status
[10:57:28.357] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:28.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:150:0) after 1m0s
[10:58:28.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:150:0)
[10:58:28.381] Timer tick, asking Buildbucket for the build status
[10:58:28.451] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:28.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:151:0) after 1m0s
[10:59:28.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:151:0)
[10:59:28.521] Timer tick, asking Buildbucket for the build status
[10:59:28.581] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:28.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:152:0) after 1m0s
[11:00:28.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:152:0)
[11:00:28.759] Timer tick, asking Buildbucket for the build status
[11:00:28.941] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:28.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:153:0) after 1m0s
[11:01:29.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:153:0)
[11:01:29.160] Timer tick, asking Buildbucket for the build status
[11:01:29.220] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:29.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:154:0) after 1m0s
[11:02:29.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:154:0)
[11:02:29.337] Timer tick, asking Buildbucket for the build status
[11:02:29.402] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:29.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:155:0) after 1m0s
[11:03:29.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:155:0)
[11:03:29.461] Timer tick, asking Buildbucket for the build status
[11:03:29.520] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:29.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:156:0) after 1m0s
[11:04:29.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:156:0)
[11:04:29.538] Timer tick, asking Buildbucket for the build status
[11:04:29.593] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:29.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:157:0) after 1m0s
[11:05:29.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:157:0)
[11:05:29.603] Timer tick, asking Buildbucket for the build status
[11:05:30.223] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:30.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:158:0) after 1m0s
[11:06:30.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:158:0)
[11:06:30.210] Timer tick, asking Buildbucket for the build status
[11:06:30.331] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:30.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:159:0) after 1m0s
[11:07:30.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:159:0)
[11:07:30.401] Timer tick, asking Buildbucket for the build status
[11:07:30.542] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:30.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:160:0) after 1m0s
[11:08:30.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:160:0)
[11:08:30.597] Timer tick, asking Buildbucket for the build status
[11:08:30.675] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:30.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:161:0) after 1m0s
[11:09:30.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:161:0)
[11:09:30.691] Timer tick, asking Buildbucket for the build status
[11:09:30.937] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:30.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:162:0) after 1m0s
[11:10:30.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:162:0)
[11:10:30.921] Timer tick, asking Buildbucket for the build status
[11:10:31.471] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:31.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:163:0) after 1m0s
[11:11:31.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:163:0)
[11:11:31.532] Timer tick, asking Buildbucket for the build status
[11:11:31.614] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:31.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:164:0) after 1m0s
[11:12:31.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:164:0)
[11:12:31.627] Timer tick, asking Buildbucket for the build status
[11:12:31.817] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:31.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:165:0) after 1m0s
[11:13:31.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:165:0)
[11:13:31.936] Timer tick, asking Buildbucket for the build status
[11:13:31.992] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:31.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:166:0) after 1m0s
[11:14:32.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:166:0)
[11:14:32.024] Timer tick, asking Buildbucket for the build status
[11:14:32.096] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:32.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:167:0) after 1m0s
[11:15:32.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:167:0)
[11:15:32.160] Timer tick, asking Buildbucket for the build status
[11:15:32.320] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:32.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:168:0) after 1m0s
[11:16:32.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:168:0)
[11:16:32.396] Timer tick, asking Buildbucket for the build status
[11:16:32.674] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:32.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:169:0) after 1m0s
[11:17:32.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:169:0)
[11:17:32.675] Timer tick, asking Buildbucket for the build status
[11:17:33.017] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:33.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:170:0) after 1m0s
[11:18:33.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:170:0)
[11:18:33.049] Timer tick, asking Buildbucket for the build status
[11:18:33.134] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:33.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:171:0) after 1m0s
[11:19:33.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:171:0)
[11:19:33.195] Timer tick, asking Buildbucket for the build status
[11:19:33.265] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:33.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:172:0) after 1m0s
[11:20:33.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:172:0)
[11:20:33.257] Timer tick, asking Buildbucket for the build status
[11:20:33.413] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:33.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:173:0) after 1m0s
[11:21:33.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:173:0)
[11:21:33.433] Timer tick, asking Buildbucket for the build status
[11:21:33.500] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:33.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:174:0) after 1m0s
[11:22:33.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:174:0)
[11:22:33.526] Timer tick, asking Buildbucket for the build status
[11:22:33.608] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:33.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:175:0) after 1m0s
[11:23:33.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:175:0)
[11:23:33.860] Timer tick, asking Buildbucket for the build status
[11:23:33.953] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:33.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:176:0) after 1m0s
[11:24:33.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:176:0)
[11:24:33.937] Timer tick, asking Buildbucket for the build status
[11:24:34.561] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:34.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:177:0) after 1m0s
[11:25:34.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:177:0)
[11:25:34.660] Timer tick, asking Buildbucket for the build status
[11:25:34.794] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:34.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:178:0) after 1m0s
[11:26:34.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:178:0)
[11:26:34.932] Timer tick, asking Buildbucket for the build status
[11:26:35.210] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:35.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:179:0) after 1m0s
[11:27:35.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:179:0)
[11:27:35.225] Timer tick, asking Buildbucket for the build status
[11:27:35.293] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:35.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:180:0) after 1m0s
[11:28:35.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:180:0)
[11:28:35.346] Timer tick, asking Buildbucket for the build status
[11:28:35.423] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:35.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:181:0) after 1m0s
[11:29:35.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:181:0)
[11:29:35.468] Timer tick, asking Buildbucket for the build status
[11:29:35.525] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:35.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:182:0) after 1m0s
[11:30:35.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:182:0)
[11:30:35.761] Timer tick, asking Buildbucket for the build status
[11:30:36.167] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:36.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:183:0) after 1m0s
[11:31:36.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:183:0)
[11:31:36.286] Timer tick, asking Buildbucket for the build status
[11:31:37.540] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:37.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:184:0) after 1m0s
[11:32:39.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:184:0)
[11:32:39.427] Timer tick, asking Buildbucket for the build status
[11:32:39.510] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:39.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:185:0) after 1m0s
[11:33:39.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:185:0)
[11:33:39.785] Timer tick, asking Buildbucket for the build status
[11:33:39.851] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:39.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:186:0) after 1m0s
[11:34:39.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:186:0)
[11:34:39.922] Timer tick, asking Buildbucket for the build status
[11:34:39.996] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:39.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:187:0) after 1m0s
[11:35:40.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:187:0)
[11:35:40.720] Timer tick, asking Buildbucket for the build status
[11:35:40.996] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:40.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:188:0) after 1m0s
[11:36:41.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:188:0)
[11:36:41.305] Timer tick, asking Buildbucket for the build status
[11:36:41.423] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:41.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:189:0) after 1m0s
[11:37:42.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:189:0)
[11:37:42.187] Timer tick, asking Buildbucket for the build status
[11:37:42.264] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:42.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:190:0) after 1m0s
[11:38:42.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:190:0)
[11:38:42.457] Timer tick, asking Buildbucket for the build status
[11:38:42.712] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:42.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:191:0) after 1m0s
[11:39:42.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:191:0)
[11:39:42.744] Timer tick, asking Buildbucket for the build status
[11:39:43.154] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:43.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:192:0) after 1m0s
[11:40:43.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:192:0)
[11:40:43.203] Timer tick, asking Buildbucket for the build status
[11:40:43.430] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:43.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:193:0) after 1m0s
[11:41:43.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:193:0)
[11:41:43.520] Timer tick, asking Buildbucket for the build status
[11:41:43.907] Build 8862129878532064032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:43.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9027559940757535232:194:0) after 1m0s
[11:42:23.825] Received PubSub notification, asking Buildbucket for the build status
[11:42:23.884] Build 8862129878532064032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:42:23.884] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1606822943169329",
    "created_by": "project:infra",
    "created_ts": "1606811368395594",
    "id": "8862129878532064032",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4342.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"34cd658b-a70a-42a6-a8a0-d0c81cb353b9\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4342.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-jk7r\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1606811424331704",
    "status": "COMPLETED",
    "status_changed_ts": "1606822943525645",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9027559940757535232",
      "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/8862129878532064032/+/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:50363d4644a23610",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1606822943540344",
    "url": "https://ci.chromium.org/b/8862129878532064032",
    "utcnow_ts": "1606822943877655"
  }
}
[11:42:23.884] Invocation finished in 3h12m56.995820298s with status SUCCEEDED