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

• parent_buildername:publish_tarball
• user_agent:recipe

[07:14:37.143] New invocation is queued and will start shortly
[07:14:37.143] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[07:14:38.212] Starting the invocation (attempt 1)
[07:14:38.257] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:14:38.257] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:14:38.257] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9029376587944414112",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4319.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"cf81c10c-37fc-4ae0-b85a-5be2ac9ed441\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4319.3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9029376587944414112",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[07:14:38.694] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1605078878348878",
    "id": "8863946526015493984",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4319.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"cf81c10c-37fc-4ae0-b85a-5be2ac9ed441\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4319.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1605078878546275",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029376587944414112",
      "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/8863946526015493984/+/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": "1605078878546387",
    "url": "https://ci.chromium.org/b/8863946526015493984",
    "utcnow_ts": "1605078878685272"
  }
}
[07:14:38.694] Task URL: https://ci.chromium.org/b/8863946526015493984
[07:14:38.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:2:0) after 1m0s
[07:14:54.716] Received PubSub notification, asking Buildbucket for the build status
[07:14:54.983] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:38.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:2:0)
[07:15:38.714] Timer tick, asking Buildbucket for the build status
[07:15:38.802] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:38.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:4:0) after 1m0s
[07:16:38.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:4:0)
[07:16:38.818] Timer tick, asking Buildbucket for the build status
[07:16:39.293] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:39.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:5:0) after 1m0s
[07:17:39.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:5:0)
[07:17:39.339] Timer tick, asking Buildbucket for the build status
[07:17:39.454] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:39.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:6:0) after 1m0s
[07:18:39.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:6:0)
[07:18:39.458] Timer tick, asking Buildbucket for the build status
[07:18:40.028] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:40.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:7:0) after 1m0s
[07:19:40.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:7:0)
[07:19:40.052] Timer tick, asking Buildbucket for the build status
[07:19:40.958] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:40.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:8:0) after 1m0s
[07:20:41.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:8:0)
[07:20:41.004] Timer tick, asking Buildbucket for the build status
[07:20:41.302] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:41.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:9:0) after 1m0s
[07:21:41.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:9:0)
[07:21:41.325] Timer tick, asking Buildbucket for the build status
[07:21:41.421] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:41.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:10:0) after 1m0s
[07:22:41.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:10:0)
[07:22:41.444] Timer tick, asking Buildbucket for the build status
[07:22:41.765] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:41.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:11:0) after 1m0s
[07:23:41.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:11:0)
[07:23:41.809] Timer tick, asking Buildbucket for the build status
[07:23:41.931] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:41.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:12:0) after 1m0s
[07:24:41.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:12:0)
[07:24:41.976] Timer tick, asking Buildbucket for the build status
[07:24:42.496] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:42.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:13:0) after 1m0s
[07:25:42.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:13:0)
[07:25:42.550] Timer tick, asking Buildbucket for the build status
[07:25:42.638] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:42.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:14:0) after 1m0s
[07:26:42.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:14:0)
[07:26:42.660] Timer tick, asking Buildbucket for the build status
[07:26:43.357] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:43.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:15:0) after 1m0s
[07:27:43.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:15:0)
[07:27:43.403] Timer tick, asking Buildbucket for the build status
[07:27:43.527] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:43.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:16:0) after 1m0s
[07:28:43.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:16:0)
[07:28:43.532] Timer tick, asking Buildbucket for the build status
[07:28:43.655] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:43.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:17:0) after 1m0s
[07:29:43.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:17:0)
[07:29:43.725] Timer tick, asking Buildbucket for the build status
[07:29:43.994] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:43.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:18:0) after 1m0s
[07:30:44.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:18:0)
[07:30:44.016] Timer tick, asking Buildbucket for the build status
[07:30:44.119] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:44.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:19:0) after 1m0s
[07:31:44.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:19:0)
[07:31:44.150] Timer tick, asking Buildbucket for the build status
[07:31:44.227] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:44.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:20:0) after 1m0s
[07:32:44.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:20:0)
[07:32:44.273] Timer tick, asking Buildbucket for the build status
[07:32:44.428] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:44.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:21:0) after 1m0s
[07:33:44.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:21:0)
[07:33:44.442] Timer tick, asking Buildbucket for the build status
[07:33:44.527] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:44.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:22:0) after 1m0s
[07:34:44.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:22:0)
[07:34:44.650] Timer tick, asking Buildbucket for the build status
[07:34:45.195] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:45.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:23:0) after 1m0s
[07:35:45.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:23:0)
[07:35:45.219] Timer tick, asking Buildbucket for the build status
[07:35:45.297] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:45.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:24:0) after 1m0s
[07:36:45.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:24:0)
[07:36:45.372] Timer tick, asking Buildbucket for the build status
[07:36:45.457] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:45.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:25:0) after 1m0s
[07:37:45.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:25:0)
[07:37:45.561] Timer tick, asking Buildbucket for the build status
[07:37:45.639] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:45.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:26:0) after 1m0s
[07:38:45.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:26:0)
[07:38:45.956] Timer tick, asking Buildbucket for the build status
[07:38:46.053] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:46.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:27:0) after 1m0s
[07:39:46.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:27:0)
[07:39:46.135] Timer tick, asking Buildbucket for the build status
[07:39:46.428] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:46.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:28:0) after 1m0s
[07:40:46.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:28:0)
[07:40:46.491] Timer tick, asking Buildbucket for the build status
[07:40:46.579] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:46.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:29:0) after 1m0s
[07:41:46.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:29:0)
[07:41:46.607] Timer tick, asking Buildbucket for the build status
[07:41:46.824] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:46.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:30:0) after 1m0s
[07:42:46.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:30:0)
[07:42:46.972] Timer tick, asking Buildbucket for the build status
[07:42:47.329] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:47.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:31:0) after 1m0s
[07:43:47.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:31:0)
[07:43:47.404] Timer tick, asking Buildbucket for the build status
[07:43:47.501] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:47.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:32:0) after 1m0s
[07:44:47.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:32:0)
[07:44:47.536] Timer tick, asking Buildbucket for the build status
[07:44:47.639] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:47.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:33:0) after 1m0s
[07:45:47.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:33:0)
[07:45:47.715] Timer tick, asking Buildbucket for the build status
[07:45:48.063] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:48.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:34:0) after 1m0s
[07:46:48.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:34:0)
[07:46:48.153] Timer tick, asking Buildbucket for the build status
[07:46:48.648] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:48.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:35:0) after 1m0s
[07:47:48.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:35:0)
[07:47:48.749] Timer tick, asking Buildbucket for the build status
[07:47:48.998] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:48.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:36:0) after 1m0s
[07:48:49.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:36:0)
[07:48:49.050] Timer tick, asking Buildbucket for the build status
[07:48:49.623] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:49.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:37:0) after 1m0s
[07:49:49.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:37:0)
[07:49:49.666] Timer tick, asking Buildbucket for the build status
[07:49:50.153] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:50.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:38:0) after 1m0s
[07:50:50.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:38:0)
[07:50:50.269] Timer tick, asking Buildbucket for the build status
[07:50:50.391] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:50.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:39:0) after 1m0s
[07:51:50.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:39:0)
[07:51:50.511] Timer tick, asking Buildbucket for the build status
[07:51:50.587] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:50.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:40:0) after 1m0s
[07:52:50.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:40:0)
[07:52:50.710] Timer tick, asking Buildbucket for the build status
[07:52:50.869] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:50.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:41:0) after 1m0s
[07:53:50.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:41:0)
[07:53:50.939] Timer tick, asking Buildbucket for the build status
[07:53:51.593] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:51.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:42:0) after 1m0s
[07:54:51.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:42:0)
[07:54:51.610] Timer tick, asking Buildbucket for the build status
[07:54:52.142] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:52.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:43:0) after 1m0s
[07:55:52.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:43:0)
[07:55:52.131] Timer tick, asking Buildbucket for the build status
[07:55:52.222] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:52.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:44:0) after 1m0s
[07:56:52.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:44:0)
[07:56:52.273] Timer tick, asking Buildbucket for the build status
[07:56:52.432] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:52.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:45:0) after 1m0s
[07:57:52.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:45:0)
[07:57:52.480] Timer tick, asking Buildbucket for the build status
[07:57:52.562] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:52.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:46:0) after 1m0s
[07:58:52.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:46:0)
[07:58:52.558] Timer tick, asking Buildbucket for the build status
[07:58:52.643] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:52.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:47:0) after 1m0s
[07:59:52.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:47:0)
[07:59:52.671] Timer tick, asking Buildbucket for the build status
[07:59:52.906] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:52.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:48:0) after 1m0s
[08:00:52.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:48:0)
[08:00:52.969] Timer tick, asking Buildbucket for the build status
[08:00:53.309] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:53.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:49:0) after 1m0s
[08:01:53.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:49:0)
[08:01:53.333] Timer tick, asking Buildbucket for the build status
[08:01:53.418] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:53.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:50:0) after 1m0s
[08:02:53.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:50:0)
[08:02:53.447] Timer tick, asking Buildbucket for the build status
[08:02:53.558] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:53.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:51:0) after 1m0s
[08:03:53.577] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:51:0)
[08:03:53.577] Timer tick, asking Buildbucket for the build status
[08:03:53.678] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:53.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:52:0) after 1m0s
[08:04:53.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:52:0)
[08:04:53.679] Timer tick, asking Buildbucket for the build status
[08:04:53.773] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:53.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:53:0) after 1m0s
[08:05:53.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:53:0)
[08:05:53.803] Timer tick, asking Buildbucket for the build status
[08:05:55.810] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:55.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:54:0) after 1m0s
[08:06:55.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:54:0)
[08:06:55.806] Timer tick, asking Buildbucket for the build status
[08:06:57.104] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:57.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:55:0) after 1m0s
[08:07:57.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:55:0)
[08:07:57.203] Timer tick, asking Buildbucket for the build status
[08:07:57.273] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:57.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:56:0) after 1m0s
[08:08:57.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:56:0)
[08:08:57.277] Timer tick, asking Buildbucket for the build status
[08:08:57.378] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:57.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:57:0) after 1m0s
[08:09:57.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:57:0)
[08:09:57.381] Timer tick, asking Buildbucket for the build status
[08:09:57.593] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:57.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:58:0) after 1m0s
[08:10:57.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:58:0)
[08:10:57.653] Timer tick, asking Buildbucket for the build status
[08:10:57.999] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:57.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:59:0) after 1m0s
[08:11:58.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:59:0)
[08:11:58.062] Timer tick, asking Buildbucket for the build status
[08:11:58.178] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:58.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:60:0) after 1m0s
[08:12:58.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:60:0)
[08:12:58.779] Timer tick, asking Buildbucket for the build status
[08:12:59.286] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:59.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:61:0) after 1m0s
[08:13:59.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:61:0)
[08:13:59.383] Timer tick, asking Buildbucket for the build status
[08:14:00.312] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:00.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:62:0) after 1m0s
[08:15:00.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:62:0)
[08:15:00.779] Timer tick, asking Buildbucket for the build status
[08:15:01.190] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:01.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:63:0) after 1m0s
[08:16:01.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:63:0)
[08:16:01.347] Timer tick, asking Buildbucket for the build status
[08:16:01.448] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:01.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:64:0) after 1m0s
[08:17:03.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:64:0)
[08:17:03.137] Timer tick, asking Buildbucket for the build status
[08:17:03.397] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:03.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:65:0) after 1m0s
[08:18:03.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:65:0)
[08:18:03.421] Timer tick, asking Buildbucket for the build status
[08:18:03.526] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:03.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:66:0) after 1m0s
[08:19:04.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:66:0)
[08:19:04.276] Timer tick, asking Buildbucket for the build status
[08:19:04.361] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:04.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:67:0) after 1m0s
[08:20:04.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:67:0)
[08:20:04.444] Timer tick, asking Buildbucket for the build status
[08:20:04.763] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:04.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:68:0) after 1m0s
[08:21:05.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:68:0)
[08:21:05.173] Timer tick, asking Buildbucket for the build status
[08:21:05.335] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:05.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:69:0) after 1m0s
[08:22:05.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:69:0)
[08:22:05.478] Timer tick, asking Buildbucket for the build status
[08:22:05.642] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:05.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:70:0) after 1m0s
[08:23:06.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:70:0)
[08:23:06.120] Timer tick, asking Buildbucket for the build status
[08:23:06.212] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:06.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:71:0) after 1m0s
[08:24:06.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:71:0)
[08:24:06.415] Timer tick, asking Buildbucket for the build status
[08:24:06.517] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:06.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:72:0) after 1m0s
[08:25:06.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:72:0)
[08:25:06.684] Timer tick, asking Buildbucket for the build status
[08:25:08.661] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:08.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:73:0) after 1m0s
[08:26:09.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:73:0)
[08:26:09.679] Timer tick, asking Buildbucket for the build status
[08:26:09.754] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:09.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:74:0) after 1m0s
[08:27:09.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:74:0)
[08:27:09.874] Timer tick, asking Buildbucket for the build status
[08:27:09.961] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:09.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:75:0) after 1m0s
[08:28:10.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:75:0)
[08:28:10.401] Timer tick, asking Buildbucket for the build status
[08:28:18.134] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:18.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:76:0) after 1m0s
[08:29:18.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:76:0)
[08:29:18.717] Timer tick, asking Buildbucket for the build status
[08:29:18.815] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:18.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:77:0) after 1m0s
[08:30:19.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:77:0)
[08:30:19.015] Timer tick, asking Buildbucket for the build status
[08:30:19.162] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:19.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:78:0) after 1m0s
[08:31:19.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:78:0)
[08:31:19.238] Timer tick, asking Buildbucket for the build status
[08:31:19.561] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:19.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:79:0) after 1m0s
[08:32:19.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:79:0)
[08:32:19.755] Timer tick, asking Buildbucket for the build status
[08:32:19.835] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:19.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:80:0) after 1m0s
[08:33:20.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:80:0)
[08:33:20.008] Timer tick, asking Buildbucket for the build status
[08:33:20.214] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:20.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:81:0) after 1m0s
[08:34:20.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:81:0)
[08:34:20.346] Timer tick, asking Buildbucket for the build status
[08:34:21.114] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:21.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:82:0) after 1m0s
[08:35:21.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:82:0)
[08:35:21.151] Timer tick, asking Buildbucket for the build status
[08:35:21.359] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:21.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:83:0) after 1m0s
[08:36:21.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:83:0)
[08:36:21.417] Timer tick, asking Buildbucket for the build status
[08:36:21.534] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:21.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:84:0) after 1m0s
[08:37:21.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:84:0)
[08:37:21.598] Timer tick, asking Buildbucket for the build status
[08:37:22.081] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:22.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:85:0) after 1m0s
[08:38:22.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:85:0)
[08:38:22.142] Timer tick, asking Buildbucket for the build status
[08:38:22.222] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:22.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:86:0) after 1m0s
[08:39:22.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:86:0)
[08:39:22.220] Timer tick, asking Buildbucket for the build status
[08:39:22.427] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:22.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:87:0) after 1m0s
[08:40:22.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:87:0)
[08:40:22.451] Timer tick, asking Buildbucket for the build status
[08:40:23.288] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:23.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:88:0) after 1m0s
[08:41:23.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:88:0)
[08:41:23.320] Timer tick, asking Buildbucket for the build status
[08:41:23.447] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:23.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:89:0) after 1m0s
[08:42:23.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:89:0)
[08:42:23.510] Timer tick, asking Buildbucket for the build status
[08:42:23.828] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:23.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:90:0) after 1m0s
[08:43:23.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:90:0)
[08:43:23.894] Timer tick, asking Buildbucket for the build status
[08:43:23.985] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:23.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:91:0) after 1m0s
[08:44:24.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:91:0)
[08:44:24.022] Timer tick, asking Buildbucket for the build status
[08:44:24.378] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:24.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:92:0) after 1m0s
[08:45:24.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:92:0)
[08:45:24.411] Timer tick, asking Buildbucket for the build status
[08:45:24.612] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:24.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:93:0) after 1m0s
[08:46:24.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:93:0)
[08:46:24.639] Timer tick, asking Buildbucket for the build status
[08:46:26.190] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:26.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:94:0) after 1m0s
[08:47:26.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:94:0)
[08:47:26.235] Timer tick, asking Buildbucket for the build status
[08:47:26.431] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:26.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:95:0) after 1m0s
[08:48:26.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:95:0)
[08:48:26.469] Timer tick, asking Buildbucket for the build status
[08:48:26.572] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:26.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:96:0) after 1m0s
[08:49:26.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:96:0)
[08:49:26.599] Timer tick, asking Buildbucket for the build status
[08:49:26.718] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:26.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:97:0) after 1m0s
[08:50:26.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:97:0)
[08:50:26.776] Timer tick, asking Buildbucket for the build status
[08:50:26.987] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:26.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:98:0) after 1m0s
[08:51:27.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:98:0)
[08:51:27.049] Timer tick, asking Buildbucket for the build status
[08:51:27.157] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:27.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:99:0) after 1m0s
[08:52:27.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:99:0)
[08:52:27.181] Timer tick, asking Buildbucket for the build status
[08:52:27.432] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:27.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:100:0) after 1m0s
[08:53:27.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:100:0)
[08:53:27.478] Timer tick, asking Buildbucket for the build status
[08:53:27.698] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:27.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:101:0) after 1m0s
[08:54:27.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:101:0)
[08:54:27.715] Timer tick, asking Buildbucket for the build status
[08:54:27.821] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:27.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:102:0) after 1m0s
[08:55:27.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:102:0)
[08:55:27.910] Timer tick, asking Buildbucket for the build status
[08:55:28.142] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:28.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:103:0) after 1m0s
[08:56:28.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:103:0)
[08:56:28.199] Timer tick, asking Buildbucket for the build status
[08:56:28.532] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:28.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:104:0) after 1m0s
[08:57:28.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:104:0)
[08:57:28.624] Timer tick, asking Buildbucket for the build status
[08:57:28.715] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:28.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:105:0) after 1m0s
[08:58:28.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:105:0)
[08:58:28.737] Timer tick, asking Buildbucket for the build status
[08:58:28.819] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:28.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:106:0) after 1m0s
[08:59:28.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:106:0)
[08:59:28.965] Timer tick, asking Buildbucket for the build status
[08:59:29.135] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:29.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:107:0) after 1m0s
[09:00:29.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:107:0)
[09:00:29.213] Timer tick, asking Buildbucket for the build status
[09:00:29.343] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:29.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:108:0) after 1m0s
[09:01:29.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:108:0)
[09:01:29.416] Timer tick, asking Buildbucket for the build status
[09:01:29.505] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:29.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:109:0) after 1m0s
[09:02:29.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:109:0)
[09:02:29.604] Timer tick, asking Buildbucket for the build status
[09:02:29.753] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:29.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:110:0) after 1m0s
[09:03:29.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:110:0)
[09:03:29.794] Timer tick, asking Buildbucket for the build status
[09:03:30.034] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:30.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:111:0) after 1m0s
[09:04:30.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:111:0)
[09:04:30.043] Timer tick, asking Buildbucket for the build status
[09:04:30.216] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:30.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:112:0) after 1m0s
[09:05:30.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:112:0)
[09:05:30.290] Timer tick, asking Buildbucket for the build status
[09:05:30.433] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:30.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:113:0) after 1m0s
[09:06:30.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:113:0)
[09:06:30.445] Timer tick, asking Buildbucket for the build status
[09:06:30.542] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:30.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:114:0) after 1m0s
[09:07:31.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:114:0)
[09:07:31.486] Timer tick, asking Buildbucket for the build status
[09:07:31.561] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:31.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:115:0) after 1m0s
[09:08:31.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:115:0)
[09:08:31.951] Timer tick, asking Buildbucket for the build status
[09:08:33.651] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:33.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:116:0) after 1m0s
[09:09:33.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:116:0)
[09:09:33.788] Timer tick, asking Buildbucket for the build status
[09:09:33.995] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:33.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:117:0) after 1m0s
[09:10:34.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:117:0)
[09:10:34.138] Timer tick, asking Buildbucket for the build status
[09:10:34.239] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:34.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:118:0) after 1m0s
[09:11:34.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:118:0)
[09:11:34.336] Timer tick, asking Buildbucket for the build status
[09:11:34.418] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:34.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:119:0) after 1m0s
[09:12:35.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:119:0)
[09:12:35.227] Timer tick, asking Buildbucket for the build status
[09:12:35.372] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:35.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:120:0) after 1m0s
[09:13:36.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:120:0)
[09:13:36.688] Timer tick, asking Buildbucket for the build status
[09:13:37.044] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:37.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:121:0) after 1m0s
[09:14:37.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:121:0)
[09:14:37.147] Timer tick, asking Buildbucket for the build status
[09:14:37.535] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:37.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:122:0) after 1m0s
[09:15:38.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:122:0)
[09:15:38.808] Timer tick, asking Buildbucket for the build status
[09:15:38.950] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:38.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:123:0) after 1m0s
[09:16:38.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:123:0)
[09:16:38.979] Timer tick, asking Buildbucket for the build status
[09:16:39.049] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:39.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:124:0) after 1m0s
[09:17:39.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:124:0)
[09:17:39.106] Timer tick, asking Buildbucket for the build status
[09:17:39.403] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:39.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:125:0) after 1m0s
[09:18:39.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:125:0)
[09:18:39.437] Timer tick, asking Buildbucket for the build status
[09:18:39.533] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:39.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:126:0) after 1m0s
[09:19:39.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:126:0)
[09:19:39.581] Timer tick, asking Buildbucket for the build status
[09:19:39.660] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:39.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:127:0) after 1m0s
[09:20:39.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:127:0)
[09:20:39.687] Timer tick, asking Buildbucket for the build status
[09:20:39.796] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:39.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:128:0) after 1m0s
[09:21:39.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:128:0)
[09:21:39.833] Timer tick, asking Buildbucket for the build status
[09:21:39.928] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:39.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:129:0) after 1m0s
[09:22:39.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:129:0)
[09:22:39.958] Timer tick, asking Buildbucket for the build status
[09:22:40.107] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:40.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:130:0) after 1m0s
[09:23:40.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:130:0)
[09:23:40.199] Timer tick, asking Buildbucket for the build status
[09:23:40.412] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:40.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:131:0) after 1m0s
[09:24:40.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:131:0)
[09:24:40.583] Timer tick, asking Buildbucket for the build status
[09:24:40.714] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:40.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:132:0) after 1m0s
[09:25:40.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:132:0)
[09:25:40.751] Timer tick, asking Buildbucket for the build status
[09:25:40.909] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:40.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:133:0) after 1m0s
[09:26:40.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:133:0)
[09:26:40.960] Timer tick, asking Buildbucket for the build status
[09:26:41.068] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:41.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:134:0) after 1m0s
[09:27:41.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:134:0)
[09:27:41.155] Timer tick, asking Buildbucket for the build status
[09:27:41.320] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:41.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:135:0) after 1m0s
[09:28:41.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:135:0)
[09:28:41.300] Timer tick, asking Buildbucket for the build status
[09:28:41.373] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:41.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:136:0) after 1m0s
[09:29:41.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:136:0)
[09:29:41.433] Timer tick, asking Buildbucket for the build status
[09:29:41.707] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:41.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:137:0) after 1m0s
[09:30:41.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:137:0)
[09:30:41.717] Timer tick, asking Buildbucket for the build status
[09:30:41.890] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:41.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:138:0) after 1m0s
[09:31:41.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:138:0)
[09:31:41.958] Timer tick, asking Buildbucket for the build status
[09:31:42.125] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:42.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:139:0) after 1m0s
[09:32:42.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:139:0)
[09:32:42.128] Timer tick, asking Buildbucket for the build status
[09:32:42.226] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:42.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:140:0) after 1m0s
[09:33:42.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:140:0)
[09:33:42.381] Timer tick, asking Buildbucket for the build status
[09:33:42.536] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:42.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:141:0) after 1m0s
[09:34:42.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:141:0)
[09:34:42.537] Timer tick, asking Buildbucket for the build status
[09:34:42.656] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:42.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:142:0) after 1m0s
[09:35:42.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:142:0)
[09:35:42.766] Timer tick, asking Buildbucket for the build status
[09:35:42.869] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:42.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:143:0) after 1m0s
[09:36:42.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:143:0)
[09:36:42.905] Timer tick, asking Buildbucket for the build status
[09:36:43.223] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:43.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:144:0) after 1m0s
[09:37:43.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:144:0)
[09:37:43.251] Timer tick, asking Buildbucket for the build status
[09:37:43.342] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:43.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:145:0) after 1m0s
[09:38:43.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:145:0)
[09:38:43.430] Timer tick, asking Buildbucket for the build status
[09:38:44.279] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:44.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:146:0) after 1m0s
[09:39:44.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:146:0)
[09:39:44.329] Timer tick, asking Buildbucket for the build status
[09:39:44.421] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:44.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:147:0) after 1m0s
[09:40:44.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:147:0)
[09:40:44.501] Timer tick, asking Buildbucket for the build status
[09:40:44.932] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:44.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:148:0) after 1m0s
[09:41:44.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:148:0)
[09:41:44.997] Timer tick, asking Buildbucket for the build status
[09:41:45.496] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:45.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:149:0) after 1m0s
[09:42:45.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:149:0)
[09:42:45.617] Timer tick, asking Buildbucket for the build status
[09:42:45.711] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:45.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:150:0) after 1m0s
[09:43:45.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:150:0)
[09:43:45.813] Timer tick, asking Buildbucket for the build status
[09:43:45.959] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:45.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:151:0) after 1m0s
[09:44:46.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:151:0)
[09:44:46.062] Timer tick, asking Buildbucket for the build status
[09:44:46.314] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:46.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:152:0) after 1m0s
[09:45:46.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:152:0)
[09:45:46.421] Timer tick, asking Buildbucket for the build status
[09:45:46.607] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:46.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:153:0) after 1m0s
[09:46:46.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:153:0)
[09:46:46.648] Timer tick, asking Buildbucket for the build status
[09:46:46.727] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:46.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:154:0) after 1m0s
[09:47:46.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:154:0)
[09:47:46.788] Timer tick, asking Buildbucket for the build status
[09:47:46.938] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:46.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:155:0) after 1m0s
[09:48:47.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:155:0)
[09:48:47.022] Timer tick, asking Buildbucket for the build status
[09:48:47.197] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:47.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:156:0) after 1m0s
[09:49:47.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:156:0)
[09:49:47.351] Timer tick, asking Buildbucket for the build status
[09:49:48.254] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:48.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:157:0) after 1m0s
[09:50:48.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:157:0)
[09:50:48.324] Timer tick, asking Buildbucket for the build status
[09:50:48.872] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:48.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:158:0) after 1m0s
[09:51:48.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:158:0)
[09:51:48.947] Timer tick, asking Buildbucket for the build status
[09:51:49.145] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:49.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:159:0) after 1m0s
[09:52:49.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:159:0)
[09:52:49.306] Timer tick, asking Buildbucket for the build status
[09:52:49.420] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:49.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:160:0) after 1m0s
[09:53:49.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:160:0)
[09:53:49.503] Timer tick, asking Buildbucket for the build status
[09:53:49.650] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:49.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:161:0) after 1m0s
[09:54:49.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:161:0)
[09:54:49.745] Timer tick, asking Buildbucket for the build status
[09:54:49.894] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:49.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:162:0) after 1m0s
[09:55:49.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:162:0)
[09:55:49.887] Timer tick, asking Buildbucket for the build status
[09:55:51.070] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:51.070] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:163:0) after 1m0s
[09:56:51.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:163:0)
[09:56:51.134] Timer tick, asking Buildbucket for the build status
[09:56:51.491] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:51.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:164:0) after 1m0s
[09:57:51.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:164:0)
[09:57:51.529] Timer tick, asking Buildbucket for the build status
[09:57:51.617] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:51.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:165:0) after 1m0s
[09:58:51.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:165:0)
[09:58:51.663] Timer tick, asking Buildbucket for the build status
[09:58:51.746] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:51.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:166:0) after 1m0s
[09:59:51.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:166:0)
[09:59:51.772] Timer tick, asking Buildbucket for the build status
[09:59:51.858] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:51.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:167:0) after 1m0s
[10:00:51.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:167:0)
[10:00:51.924] Timer tick, asking Buildbucket for the build status
[10:00:52.281] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:52.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:168:0) after 1m0s
[10:01:52.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:168:0)
[10:01:52.429] Timer tick, asking Buildbucket for the build status
[10:01:52.606] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:52.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:169:0) after 1m0s
[10:02:52.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:169:0)
[10:02:52.727] Timer tick, asking Buildbucket for the build status
[10:02:53.749] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:53.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:170:0) after 1m0s
[10:03:53.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:170:0)
[10:03:53.765] Timer tick, asking Buildbucket for the build status
[10:03:54.062] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:54.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:171:0) after 1m0s
[10:04:55.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:171:0)
[10:04:55.224] Timer tick, asking Buildbucket for the build status
[10:04:55.309] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:55.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:172:0) after 1m0s
[10:05:55.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:172:0)
[10:05:55.503] Timer tick, asking Buildbucket for the build status
[10:05:55.915] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:55.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:173:0) after 1m0s
[10:06:56.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:173:0)
[10:06:56.009] Timer tick, asking Buildbucket for the build status
[10:06:56.213] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:56.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:174:0) after 1m0s
[10:07:56.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:174:0)
[10:07:56.279] Timer tick, asking Buildbucket for the build status
[10:07:56.483] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:56.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:175:0) after 1m0s
[10:08:56.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:175:0)
[10:08:56.555] Timer tick, asking Buildbucket for the build status
[10:08:56.655] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:56.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:176:0) after 1m0s
[10:09:57.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:176:0)
[10:09:57.629] Timer tick, asking Buildbucket for the build status
[10:10:05.090] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:05.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:177:0) after 1m0s
[10:11:06.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:177:0)
[10:11:06.666] Timer tick, asking Buildbucket for the build status
[10:11:06.745] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:06.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:178:0) after 1m0s
[10:12:06.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:178:0)
[10:12:06.784] Timer tick, asking Buildbucket for the build status
[10:12:06.867] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:06.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:179:0) after 1m0s
[10:13:06.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:179:0)
[10:13:06.855] Timer tick, asking Buildbucket for the build status
[10:13:07.494] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:07.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:180:0) after 1m0s
[10:14:07.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:180:0)
[10:14:07.544] Timer tick, asking Buildbucket for the build status
[10:14:07.625] Build 8863946526015493984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:07.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029376587944414112:181:0) after 1m0s
[10:14:24.559] Received PubSub notification, asking Buildbucket for the build status
[10:14:24.610] Build 8863946526015493984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:14:24.610] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1605089661158289",
    "created_by": "project:infra",
    "created_ts": "1605078878348878",
    "id": "8863946526015493984",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4319.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"cf81c10c-37fc-4ae0-b85a-5be2ac9ed441\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4319.3\"}}",
    "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-swfn\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1605078892780470",
    "status": "COMPLETED",
    "status_changed_ts": "1605089664175805",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029376587944414112",
      "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/8863946526015493984/+/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:4fcef992aefea010",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1605089664246418",
    "url": "https://ci.chromium.org/b/8863946526015493984",
    "utcnow_ts": "1605089664602335"
  }
}
[10:14:24.610] Invocation finished in 2h59m47.484934588s with status SUCCEEDED