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

• parent_buildername:publish_tarball
• user_agent:recipe

[15:47:20.312] New invocation is queued and will start shortly
[15:47:20.312] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[15:47:21.385] Starting the invocation (attempt 1)
[15:47:21.419] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:47:21.421] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:47:21.421] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9026535824462867760",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4353.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"68f1c45e-9b39-42e9-ad5a-3f901d0682f2\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4353.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:9026535824462867760",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[15:47:21.814] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1607788041500416",
    "id": "8861105762554322240",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4353.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"68f1c45e-9b39-42e9-ad5a-3f901d0682f2\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4353.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1607788041642784",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026535824462867760",
      "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/8861105762554322240/+/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": "1607788041642906",
    "url": "https://ci.chromium.org/b/8861105762554322240",
    "utcnow_ts": "1607788041807794"
  }
}
[15:47:21.814] Task URL: https://ci.chromium.org/b/8861105762554322240
[15:47:21.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:2:0) after 1m0s
[15:48:11.812] Received PubSub notification, asking Buildbucket for the build status
[15:48:11.878] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:22.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:2:0)
[15:48:22.074] Timer tick, asking Buildbucket for the build status
[15:48:22.144] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:22.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:4:0) after 1m0s
[15:49:24.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:4:0)
[15:49:24.833] Timer tick, asking Buildbucket for the build status
[15:49:25.018] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:25.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:5:0) after 1m0s
[15:50:25.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:5:0)
[15:50:25.029] Timer tick, asking Buildbucket for the build status
[15:50:25.244] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:25.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:6:0) after 1m0s
[15:51:25.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:6:0)
[15:51:25.262] Timer tick, asking Buildbucket for the build status
[15:51:25.332] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:25.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:7:0) after 1m0s
[15:52:25.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:7:0)
[15:52:25.350] Timer tick, asking Buildbucket for the build status
[15:52:25.610] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:25.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:8:0) after 1m0s
[15:53:25.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:8:0)
[15:53:25.616] Timer tick, asking Buildbucket for the build status
[15:53:25.671] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:25.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:9:0) after 1m0s
[15:54:25.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:9:0)
[15:54:25.729] Timer tick, asking Buildbucket for the build status
[15:54:25.791] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:25.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:10:0) after 1m0s
[15:55:25.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:10:0)
[15:55:25.858] Timer tick, asking Buildbucket for the build status
[15:55:25.918] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:25.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:11:0) after 1m0s
[15:56:25.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:11:0)
[15:56:25.946] Timer tick, asking Buildbucket for the build status
[15:56:26.142] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:26.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:12:0) after 1m0s
[15:57:26.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:12:0)
[15:57:26.134] Timer tick, asking Buildbucket for the build status
[15:57:26.429] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:26.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:13:0) after 1m0s
[15:58:26.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:13:0)
[15:58:26.533] Timer tick, asking Buildbucket for the build status
[15:58:26.585] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:26.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:14:0) after 1m0s
[15:59:26.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:14:0)
[15:59:26.563] Timer tick, asking Buildbucket for the build status
[15:59:26.627] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:26.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:15:0) after 1m0s
[16:00:26.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:15:0)
[16:00:26.668] Timer tick, asking Buildbucket for the build status
[16:00:26.926] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:26.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:16:0) after 1m0s
[16:01:26.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:16:0)
[16:01:26.972] Timer tick, asking Buildbucket for the build status
[16:01:27.105] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:27.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:17:0) after 1m0s
[16:02:27.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:17:0)
[16:02:27.155] Timer tick, asking Buildbucket for the build status
[16:02:27.744] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:27.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:18:0) after 1m0s
[16:03:27.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:18:0)
[16:03:27.826] Timer tick, asking Buildbucket for the build status
[16:03:27.893] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:27.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:19:0) after 1m0s
[16:04:28.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:19:0)
[16:04:28.053] Timer tick, asking Buildbucket for the build status
[16:04:28.150] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:28.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:20:0) after 1m0s
[16:05:28.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:20:0)
[16:05:28.171] Timer tick, asking Buildbucket for the build status
[16:05:28.538] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:28.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:21:0) after 1m0s
[16:06:28.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:21:0)
[16:06:28.604] Timer tick, asking Buildbucket for the build status
[16:06:28.663] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:28.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:22:0) after 1m0s
[16:07:28.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:22:0)
[16:07:28.709] Timer tick, asking Buildbucket for the build status
[16:07:29.016] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:29.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:23:0) after 1m0s
[16:08:29.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:23:0)
[16:08:29.147] Timer tick, asking Buildbucket for the build status
[16:08:29.219] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:29.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:24:0) after 1m0s
[16:09:29.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:24:0)
[16:09:29.275] Timer tick, asking Buildbucket for the build status
[16:09:29.343] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:29.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:25:0) after 1m0s
[16:10:29.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:25:0)
[16:10:29.420] Timer tick, asking Buildbucket for the build status
[16:10:29.486] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:29.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:26:0) after 1m0s
[16:11:29.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:26:0)
[16:11:29.511] Timer tick, asking Buildbucket for the build status
[16:11:29.706] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:29.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:27:0) after 1m0s
[16:12:29.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:27:0)
[16:12:29.734] Timer tick, asking Buildbucket for the build status
[16:12:29.844] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:29.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:28:0) after 1m0s
[16:13:29.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:28:0)
[16:13:29.835] Timer tick, asking Buildbucket for the build status
[16:13:29.935] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:29.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:29:0) after 1m0s
[16:14:29.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:29:0)
[16:14:29.968] Timer tick, asking Buildbucket for the build status
[16:14:30.227] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:30.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:30:0) after 1m0s
[16:15:30.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:30:0)
[16:15:30.262] Timer tick, asking Buildbucket for the build status
[16:15:30.362] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:30.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:31:0) after 1m0s
[16:16:30.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:31:0)
[16:16:30.379] Timer tick, asking Buildbucket for the build status
[16:16:30.456] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:30.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:32:0) after 1m0s
[16:17:30.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:32:0)
[16:17:30.483] Timer tick, asking Buildbucket for the build status
[16:17:30.589] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:30.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:33:0) after 1m0s
[16:18:30.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:33:0)
[16:18:30.686] Timer tick, asking Buildbucket for the build status
[16:18:30.743] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:30.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:34:0) after 1m0s
[16:19:30.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:34:0)
[16:19:30.909] Timer tick, asking Buildbucket for the build status
[16:19:31.007] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:31.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:35:0) after 1m0s
[16:20:31.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:35:0)
[16:20:31.152] Timer tick, asking Buildbucket for the build status
[16:20:31.238] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:31.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:36:0) after 1m0s
[16:21:31.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:36:0)
[16:21:31.262] Timer tick, asking Buildbucket for the build status
[16:21:31.333] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:31.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:37:0) after 1m0s
[16:22:31.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:37:0)
[16:22:31.353] Timer tick, asking Buildbucket for the build status
[16:22:31.459] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:31.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:38:0) after 1m0s
[16:23:31.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:38:0)
[16:23:31.583] Timer tick, asking Buildbucket for the build status
[16:23:31.803] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:31.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:39:0) after 1m0s
[16:24:31.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:39:0)
[16:24:31.966] Timer tick, asking Buildbucket for the build status
[16:24:32.062] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:32.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:40:0) after 1m0s
[16:25:32.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:40:0)
[16:25:32.105] Timer tick, asking Buildbucket for the build status
[16:25:32.234] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:32.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:41:0) after 1m0s
[16:26:32.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:41:0)
[16:26:32.253] Timer tick, asking Buildbucket for the build status
[16:26:32.316] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:32.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:42:0) after 1m0s
[16:27:32.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:42:0)
[16:27:32.351] Timer tick, asking Buildbucket for the build status
[16:27:32.443] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:32.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:43:0) after 1m0s
[16:28:32.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:43:0)
[16:28:32.509] Timer tick, asking Buildbucket for the build status
[16:28:32.747] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:32.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:44:0) after 1m0s
[16:29:32.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:44:0)
[16:29:32.911] Timer tick, asking Buildbucket for the build status
[16:29:33.148] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:33.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:45:0) after 1m0s
[16:30:33.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:45:0)
[16:30:33.166] Timer tick, asking Buildbucket for the build status
[16:30:33.252] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:33.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:46:0) after 1m0s
[16:31:33.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:46:0)
[16:31:33.340] Timer tick, asking Buildbucket for the build status
[16:31:33.543] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:33.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:47:0) after 1m0s
[16:32:33.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:47:0)
[16:32:33.560] Timer tick, asking Buildbucket for the build status
[16:32:33.734] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:33.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:48:0) after 1m0s
[16:33:33.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:48:0)
[16:33:33.851] Timer tick, asking Buildbucket for the build status
[16:33:33.911] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:33.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:49:0) after 1m0s
[16:34:33.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:49:0)
[16:34:33.975] Timer tick, asking Buildbucket for the build status
[16:34:34.107] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:34.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:50:0) after 1m0s
[16:35:34.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:50:0)
[16:35:34.125] Timer tick, asking Buildbucket for the build status
[16:35:34.184] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:34.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:51:0) after 1m0s
[16:36:34.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:51:0)
[16:36:34.202] Timer tick, asking Buildbucket for the build status
[16:36:34.261] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:34.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:52:0) after 1m0s
[16:37:34.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:52:0)
[16:37:34.300] Timer tick, asking Buildbucket for the build status
[16:37:34.399] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:34.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:53:0) after 1m0s
[16:38:34.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:53:0)
[16:38:34.402] Timer tick, asking Buildbucket for the build status
[16:38:34.529] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:34.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:54:0) after 1m0s
[16:39:34.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:54:0)
[16:39:34.586] Timer tick, asking Buildbucket for the build status
[16:39:34.645] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:34.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:55:0) after 1m0s
[16:40:34.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:55:0)
[16:40:34.682] Timer tick, asking Buildbucket for the build status
[16:40:34.762] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:34.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:56:0) after 1m0s
[16:41:34.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:56:0)
[16:41:34.798] Timer tick, asking Buildbucket for the build status
[16:41:34.858] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:34.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:57:0) after 1m0s
[16:42:34.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:57:0)
[16:42:34.885] Timer tick, asking Buildbucket for the build status
[16:42:34.993] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:34.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:58:0) after 1m0s
[16:43:35.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:58:0)
[16:43:35.011] Timer tick, asking Buildbucket for the build status
[16:43:35.072] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:35.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:59:0) after 1m0s
[16:44:35.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:59:0)
[16:44:35.136] Timer tick, asking Buildbucket for the build status
[16:44:35.205] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:35.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:60:0) after 1m0s
[16:45:35.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:60:0)
[16:45:35.333] Timer tick, asking Buildbucket for the build status
[16:45:35.400] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:35.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:61:0) after 1m0s
[16:46:35.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:61:0)
[16:46:35.513] Timer tick, asking Buildbucket for the build status
[16:46:35.611] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:35.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:62:0) after 1m0s
[16:47:36.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:62:0)
[16:47:36.683] Timer tick, asking Buildbucket for the build status
[16:47:36.774] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:36.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:63:0) after 1m0s
[16:48:37.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:63:0)
[16:48:37.119] Timer tick, asking Buildbucket for the build status
[16:48:37.172] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:37.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:64:0) after 1m0s
[16:49:37.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:64:0)
[16:49:37.312] Timer tick, asking Buildbucket for the build status
[16:49:37.393] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:37.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:65:0) after 1m0s
[16:50:37.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:65:0)
[16:50:37.652] Timer tick, asking Buildbucket for the build status
[16:50:37.722] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:37.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:66:0) after 1m0s
[16:51:37.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:66:0)
[16:51:37.762] Timer tick, asking Buildbucket for the build status
[16:51:37.814] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:37.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:67:0) after 1m0s
[16:52:38.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:67:0)
[16:52:38.088] Timer tick, asking Buildbucket for the build status
[16:52:39.253] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:39.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:68:0) after 1m0s
[16:53:39.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:68:0)
[16:53:39.275] Timer tick, asking Buildbucket for the build status
[16:53:39.423] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:39.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:69:0) after 1m0s
[16:54:39.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:69:0)
[16:54:39.442] Timer tick, asking Buildbucket for the build status
[16:54:39.508] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:39.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:70:0) after 1m0s
[16:55:39.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:70:0)
[16:55:39.602] Timer tick, asking Buildbucket for the build status
[16:55:39.662] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:39.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:71:0) after 1m0s
[16:56:39.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:71:0)
[16:56:39.773] Timer tick, asking Buildbucket for the build status
[16:56:40.031] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:40.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:72:0) after 1m0s
[16:57:40.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:72:0)
[16:57:40.148] Timer tick, asking Buildbucket for the build status
[16:57:40.231] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:40.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:73:0) after 1m0s
[16:58:43.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:73:0)
[16:58:43.103] Timer tick, asking Buildbucket for the build status
[16:58:43.335] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:43.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:74:0) after 1m0s
[16:59:44.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:74:0)
[16:59:44.103] Timer tick, asking Buildbucket for the build status
[16:59:44.156] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:44.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:75:0) after 1m0s
[17:00:44.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:75:0)
[17:00:44.139] Timer tick, asking Buildbucket for the build status
[17:00:44.324] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:44.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:76:0) after 1m0s
[17:01:44.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:76:0)
[17:01:44.350] Timer tick, asking Buildbucket for the build status
[17:01:44.410] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:44.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:77:0) after 1m0s
[17:02:44.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:77:0)
[17:02:44.554] Timer tick, asking Buildbucket for the build status
[17:02:44.842] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:44.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:78:0) after 1m0s
[17:03:44.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:78:0)
[17:03:44.932] Timer tick, asking Buildbucket for the build status
[17:03:45.112] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:45.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:79:0) after 1m0s
[17:04:45.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:79:0)
[17:04:45.294] Timer tick, asking Buildbucket for the build status
[17:04:45.366] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:45.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:80:0) after 1m0s
[17:05:45.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:80:0)
[17:05:45.475] Timer tick, asking Buildbucket for the build status
[17:05:45.537] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:45.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:81:0) after 1m0s
[17:06:45.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:81:0)
[17:06:45.837] Timer tick, asking Buildbucket for the build status
[17:06:45.893] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:45.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:82:0) after 1m0s
[17:07:45.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:82:0)
[17:07:45.970] Timer tick, asking Buildbucket for the build status
[17:07:46.079] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:46.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:83:0) after 1m0s
[17:08:46.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:83:0)
[17:08:46.282] Timer tick, asking Buildbucket for the build status
[17:08:46.346] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:46.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:84:0) after 1m0s
[17:09:46.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:84:0)
[17:09:46.402] Timer tick, asking Buildbucket for the build status
[17:09:46.467] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:46.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:85:0) after 1m0s
[17:10:46.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:85:0)
[17:10:46.481] Timer tick, asking Buildbucket for the build status
[17:10:46.806] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:46.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:86:0) after 1m0s
[17:11:47.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:86:0)
[17:11:47.036] Timer tick, asking Buildbucket for the build status
[17:11:47.094] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:47.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:87:0) after 1m0s
[17:12:47.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:87:0)
[17:12:47.235] Timer tick, asking Buildbucket for the build status
[17:12:47.337] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:47.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:88:0) after 1m0s
[17:13:47.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:88:0)
[17:13:47.417] Timer tick, asking Buildbucket for the build status
[17:13:47.577] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:47.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:89:0) after 1m0s
[17:14:47.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:89:0)
[17:14:47.682] Timer tick, asking Buildbucket for the build status
[17:14:47.740] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:47.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:90:0) after 1m0s
[17:15:47.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:90:0)
[17:15:47.777] Timer tick, asking Buildbucket for the build status
[17:15:47.842] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:47.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:91:0) after 1m0s
[17:16:47.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:91:0)
[17:16:47.896] Timer tick, asking Buildbucket for the build status
[17:16:47.962] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:47.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:92:0) after 1m0s
[17:17:48.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:92:0)
[17:17:48.000] Timer tick, asking Buildbucket for the build status
[17:17:48.151] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:48.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:93:0) after 1m0s
[17:18:48.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:93:0)
[17:18:48.213] Timer tick, asking Buildbucket for the build status
[17:18:48.478] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:48.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:94:0) after 1m0s
[17:19:48.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:94:0)
[17:19:48.575] Timer tick, asking Buildbucket for the build status
[17:19:48.633] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:48.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:95:0) after 1m0s
[17:20:48.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:95:0)
[17:20:48.703] Timer tick, asking Buildbucket for the build status
[17:20:48.760] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:48.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:96:0) after 1m0s
[17:21:48.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:96:0)
[17:21:48.851] Timer tick, asking Buildbucket for the build status
[17:21:48.904] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:48.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:97:0) after 1m0s
[17:22:48.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:97:0)
[17:22:48.994] Timer tick, asking Buildbucket for the build status
[17:22:49.319] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:49.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:98:0) after 1m0s
[17:23:49.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:98:0)
[17:23:49.377] Timer tick, asking Buildbucket for the build status
[17:23:49.531] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:49.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:99:0) after 1m0s
[17:24:49.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:99:0)
[17:24:49.556] Timer tick, asking Buildbucket for the build status
[17:24:49.617] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:49.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:100:0) after 1m0s
[17:25:49.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:100:0)
[17:25:49.639] Timer tick, asking Buildbucket for the build status
[17:25:49.720] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:49.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:101:0) after 1m0s
[17:26:49.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:101:0)
[17:26:49.759] Timer tick, asking Buildbucket for the build status
[17:26:49.935] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:49.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:102:0) after 1m0s
[17:27:49.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:102:0)
[17:27:49.945] Timer tick, asking Buildbucket for the build status
[17:27:50.035] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:50.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:103:0) after 1m0s
[17:28:50.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:103:0)
[17:28:50.023] Timer tick, asking Buildbucket for the build status
[17:28:50.080] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:50.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:104:0) after 1m0s
[17:29:50.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:104:0)
[17:29:50.108] Timer tick, asking Buildbucket for the build status
[17:29:50.163] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:50.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:105:0) after 1m0s
[17:30:50.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:105:0)
[17:30:50.182] Timer tick, asking Buildbucket for the build status
[17:30:50.408] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:50.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:106:0) after 1m0s
[17:31:50.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:106:0)
[17:31:50.469] Timer tick, asking Buildbucket for the build status
[17:31:50.711] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:50.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:107:0) after 1m0s
[17:32:50.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:107:0)
[17:32:50.739] Timer tick, asking Buildbucket for the build status
[17:32:50.847] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:50.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:108:0) after 1m0s
[17:33:50.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:108:0)
[17:33:50.875] Timer tick, asking Buildbucket for the build status
[17:33:51.507] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:51.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:109:0) after 1m0s
[17:34:51.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:109:0)
[17:34:51.527] Timer tick, asking Buildbucket for the build status
[17:34:51.579] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:51.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:110:0) after 1m0s
[17:35:51.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:110:0)
[17:35:51.591] Timer tick, asking Buildbucket for the build status
[17:35:51.808] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:51.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:111:0) after 1m0s
[17:36:51.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:111:0)
[17:36:51.835] Timer tick, asking Buildbucket for the build status
[17:36:52.124] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:52.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:112:0) after 1m0s
[17:37:52.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:112:0)
[17:37:52.248] Timer tick, asking Buildbucket for the build status
[17:37:52.578] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:52.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:113:0) after 1m0s
[17:38:52.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:113:0)
[17:38:52.647] Timer tick, asking Buildbucket for the build status
[17:38:52.713] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:52.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:114:0) after 1m0s
[17:39:52.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:114:0)
[17:39:52.828] Timer tick, asking Buildbucket for the build status
[17:39:52.908] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:52.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:115:0) after 1m0s
[17:40:52.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:115:0)
[17:40:52.928] Timer tick, asking Buildbucket for the build status
[17:40:52.993] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:52.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:116:0) after 1m0s
[17:41:53.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:116:0)
[17:41:53.014] Timer tick, asking Buildbucket for the build status
[17:41:53.074] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:53.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:117:0) after 1m0s
[17:42:53.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:117:0)
[17:42:53.143] Timer tick, asking Buildbucket for the build status
[17:42:53.234] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:53.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:118:0) after 1m0s
[17:43:53.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:118:0)
[17:43:53.276] Timer tick, asking Buildbucket for the build status
[17:43:53.363] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:53.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:119:0) after 1m0s
[17:44:53.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:119:0)
[17:44:53.387] Timer tick, asking Buildbucket for the build status
[17:44:53.444] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:53.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:120:0) after 1m0s
[17:45:53.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:120:0)
[17:45:53.465] Timer tick, asking Buildbucket for the build status
[17:45:53.565] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:53.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:121:0) after 1m0s
[17:46:53.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:121:0)
[17:46:53.554] Timer tick, asking Buildbucket for the build status
[17:46:53.620] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:53.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:122:0) after 1m0s
[17:47:53.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:122:0)
[17:47:53.730] Timer tick, asking Buildbucket for the build status
[17:47:54.044] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:54.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:123:0) after 1m0s
[17:48:54.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:123:0)
[17:48:54.181] Timer tick, asking Buildbucket for the build status
[17:48:54.513] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:54.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:124:0) after 1m0s
[17:49:54.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:124:0)
[17:49:54.666] Timer tick, asking Buildbucket for the build status
[17:49:54.723] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:54.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:125:0) after 1m0s
[17:50:54.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:125:0)
[17:50:54.768] Timer tick, asking Buildbucket for the build status
[17:50:54.822] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:54.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:126:0) after 1m0s
[17:51:54.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:126:0)
[17:51:54.821] Timer tick, asking Buildbucket for the build status
[17:51:54.933] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:54.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:127:0) after 1m0s
[17:52:54.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:127:0)
[17:52:54.953] Timer tick, asking Buildbucket for the build status
[17:52:55.067] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:55.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:128:0) after 1m0s
[17:53:55.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:128:0)
[17:53:55.187] Timer tick, asking Buildbucket for the build status
[17:53:55.263] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:55.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:129:0) after 1m0s
[17:54:55.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:129:0)
[17:54:55.286] Timer tick, asking Buildbucket for the build status
[17:54:55.344] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:55.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:130:0) after 1m0s
[17:55:55.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:130:0)
[17:55:55.466] Timer tick, asking Buildbucket for the build status
[17:55:55.524] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:55.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:131:0) after 1m0s
[17:56:55.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:131:0)
[17:56:55.622] Timer tick, asking Buildbucket for the build status
[17:56:55.692] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:55.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:132:0) after 1m0s
[17:57:55.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:132:0)
[17:57:55.736] Timer tick, asking Buildbucket for the build status
[17:57:56.228] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:56.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:133:0) after 1m0s
[17:58:56.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:133:0)
[17:58:56.290] Timer tick, asking Buildbucket for the build status
[17:58:56.359] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:56.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:134:0) after 1m0s
[17:59:56.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:134:0)
[17:59:56.379] Timer tick, asking Buildbucket for the build status
[17:59:56.431] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:56.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:135:0) after 1m0s
[18:00:56.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:135:0)
[18:00:56.420] Timer tick, asking Buildbucket for the build status
[18:00:56.495] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:56.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:136:0) after 1m0s
[18:01:56.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:136:0)
[18:01:56.620] Timer tick, asking Buildbucket for the build status
[18:01:56.681] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:56.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:137:0) after 1m0s
[18:02:56.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:137:0)
[18:02:56.803] Timer tick, asking Buildbucket for the build status
[18:02:56.903] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:56.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:138:0) after 1m0s
[18:03:57.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:138:0)
[18:03:57.057] Timer tick, asking Buildbucket for the build status
[18:03:57.122] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:57.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:139:0) after 1m0s
[18:04:57.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:139:0)
[18:04:57.217] Timer tick, asking Buildbucket for the build status
[18:04:57.435] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:57.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:140:0) after 1m0s
[18:05:57.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:140:0)
[18:05:57.490] Timer tick, asking Buildbucket for the build status
[18:05:57.634] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:57.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:141:0) after 1m0s
[18:06:57.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:141:0)
[18:06:57.756] Timer tick, asking Buildbucket for the build status
[18:06:57.819] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:57.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:142:0) after 1m0s
[18:07:57.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:142:0)
[18:07:57.960] Timer tick, asking Buildbucket for the build status
[18:07:58.210] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:58.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:143:0) after 1m0s
[18:08:58.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:143:0)
[18:08:58.229] Timer tick, asking Buildbucket for the build status
[18:08:58.291] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:58.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:144:0) after 1m0s
[18:09:58.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:144:0)
[18:09:58.365] Timer tick, asking Buildbucket for the build status
[18:09:58.425] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:58.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:145:0) after 1m0s
[18:10:58.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:145:0)
[18:10:58.436] Timer tick, asking Buildbucket for the build status
[18:10:58.525] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:58.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:146:0) after 1m0s
[18:11:58.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:146:0)
[18:11:58.578] Timer tick, asking Buildbucket for the build status
[18:11:58.638] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:58.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:147:0) after 1m0s
[18:12:58.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:147:0)
[18:12:58.671] Timer tick, asking Buildbucket for the build status
[18:12:58.804] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:58.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:148:0) after 1m0s
[18:13:58.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:148:0)
[18:13:58.807] Timer tick, asking Buildbucket for the build status
[18:13:58.934] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:58.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:149:0) after 1m0s
[18:14:58.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:149:0)
[18:14:58.955] Timer tick, asking Buildbucket for the build status
[18:14:59.017] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:59.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:150:0) after 1m0s
[18:15:59.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:150:0)
[18:15:59.127] Timer tick, asking Buildbucket for the build status
[18:15:59.184] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:59.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:151:0) after 1m0s
[18:16:59.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:151:0)
[18:16:59.253] Timer tick, asking Buildbucket for the build status
[18:16:59.311] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:59.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:152:0) after 1m0s
[18:17:59.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:152:0)
[18:17:59.375] Timer tick, asking Buildbucket for the build status
[18:17:59.474] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:59.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:153:0) after 1m0s
[18:18:59.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:153:0)
[18:18:59.548] Timer tick, asking Buildbucket for the build status
[18:18:59.613] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:59.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:154:0) after 1m0s
[18:19:59.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:154:0)
[18:19:59.637] Timer tick, asking Buildbucket for the build status
[18:19:59.686] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:59.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:155:0) after 1m0s
[18:20:59.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:155:0)
[18:20:59.840] Timer tick, asking Buildbucket for the build status
[18:20:59.971] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:59.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:156:0) after 1m0s
[18:22:00.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:156:0)
[18:22:00.002] Timer tick, asking Buildbucket for the build status
[18:22:00.402] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:00.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:157:0) after 1m0s
[18:23:00.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:157:0)
[18:23:00.447] Timer tick, asking Buildbucket for the build status
[18:23:00.551] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:00.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:158:0) after 1m0s
[18:24:00.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:158:0)
[18:24:00.964] Timer tick, asking Buildbucket for the build status
[18:24:01.060] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:01.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:159:0) after 1m0s
[18:25:01.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:159:0)
[18:25:01.319] Timer tick, asking Buildbucket for the build status
[18:25:01.841] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:01.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:160:0) after 1m0s
[18:26:02.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:160:0)
[18:26:02.112] Timer tick, asking Buildbucket for the build status
[18:26:02.186] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:02.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:161:0) after 1m0s
[18:27:02.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:161:0)
[18:27:02.330] Timer tick, asking Buildbucket for the build status
[18:27:02.391] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:02.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:162:0) after 1m0s
[18:28:02.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:162:0)
[18:28:02.448] Timer tick, asking Buildbucket for the build status
[18:28:02.638] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:02.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:163:0) after 1m0s
[18:29:02.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:163:0)
[18:29:02.677] Timer tick, asking Buildbucket for the build status
[18:29:02.763] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:02.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:164:0) after 1m0s
[18:30:02.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:164:0)
[18:30:02.783] Timer tick, asking Buildbucket for the build status
[18:30:03.071] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:03.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:165:0) after 1m0s
[18:31:03.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:165:0)
[18:31:03.180] Timer tick, asking Buildbucket for the build status
[18:31:03.508] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:03.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:166:0) after 1m0s
[18:32:03.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:166:0)
[18:32:03.567] Timer tick, asking Buildbucket for the build status
[18:32:03.629] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:03.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:167:0) after 1m0s
[18:33:03.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:167:0)
[18:33:03.737] Timer tick, asking Buildbucket for the build status
[18:33:03.940] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:03.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:168:0) after 1m0s
[18:34:04.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:168:0)
[18:34:04.045] Timer tick, asking Buildbucket for the build status
[18:34:04.113] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:04.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:169:0) after 1m0s
[18:35:04.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:169:0)
[18:35:04.137] Timer tick, asking Buildbucket for the build status
[18:35:04.663] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:04.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:170:0) after 1m0s
[18:36:04.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:170:0)
[18:36:04.744] Timer tick, asking Buildbucket for the build status
[18:36:04.804] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:04.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:171:0) after 1m0s
[18:37:04.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:171:0)
[18:37:04.824] Timer tick, asking Buildbucket for the build status
[18:37:05.045] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:05.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:172:0) after 1m0s
[18:38:05.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:172:0)
[18:38:05.063] Timer tick, asking Buildbucket for the build status
[18:38:05.205] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:05.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:173:0) after 1m0s
[18:39:05.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:173:0)
[18:39:05.246] Timer tick, asking Buildbucket for the build status
[18:39:05.335] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:05.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:174:0) after 1m0s
[18:40:05.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:174:0)
[18:40:05.359] Timer tick, asking Buildbucket for the build status
[18:40:05.482] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:05.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:175:0) after 1m0s
[18:41:05.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:175:0)
[18:41:05.547] Timer tick, asking Buildbucket for the build status
[18:41:05.729] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:05.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:176:0) after 1m0s
[18:42:05.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:176:0)
[18:42:05.755] Timer tick, asking Buildbucket for the build status
[18:42:05.823] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:05.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:177:0) after 1m0s
[18:43:05.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:177:0)
[18:43:05.897] Timer tick, asking Buildbucket for the build status
[18:43:05.988] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:05.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:178:0) after 1m0s
[18:44:06.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:178:0)
[18:44:06.078] Timer tick, asking Buildbucket for the build status
[18:44:06.133] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:06.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:179:0) after 1m0s
[18:45:06.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:179:0)
[18:45:06.167] Timer tick, asking Buildbucket for the build status
[18:45:06.230] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:06.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:180:0) after 1m0s
[18:46:06.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:180:0)
[18:46:06.348] Timer tick, asking Buildbucket for the build status
[18:46:06.676] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:06.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:181:0) after 1m0s
[18:47:06.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:181:0)
[18:47:06.749] Timer tick, asking Buildbucket for the build status
[18:47:06.813] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:06.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:182:0) after 1m0s
[18:48:06.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:182:0)
[18:48:06.903] Timer tick, asking Buildbucket for the build status
[18:48:07.009] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:07.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:183:0) after 1m0s
[18:49:07.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:183:0)
[18:49:07.031] Timer tick, asking Buildbucket for the build status
[18:49:07.252] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:07.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:184:0) after 1m0s
[18:50:07.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:184:0)
[18:50:07.277] Timer tick, asking Buildbucket for the build status
[18:50:07.367] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:07.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:185:0) after 1m0s
[18:51:07.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:185:0)
[18:51:07.421] Timer tick, asking Buildbucket for the build status
[18:51:07.480] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:07.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:186:0) after 1m0s
[18:52:07.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:186:0)
[18:52:07.569] Timer tick, asking Buildbucket for the build status
[18:52:07.640] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:07.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:187:0) after 1m0s
[18:53:07.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:187:0)
[18:53:07.741] Timer tick, asking Buildbucket for the build status
[18:53:07.855] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:07.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:188:0) after 1m0s
[18:54:07.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:188:0)
[18:54:07.937] Timer tick, asking Buildbucket for the build status
[18:54:07.996] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:07.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:189:0) after 1m0s
[18:55:07.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:189:0)
[18:55:07.981] Timer tick, asking Buildbucket for the build status
[18:55:08.051] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:08.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:190:0) after 1m0s
[18:56:08.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:190:0)
[18:56:08.079] Timer tick, asking Buildbucket for the build status
[18:56:08.236] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:08.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:191:0) after 1m0s
[18:57:08.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:191:0)
[18:57:08.292] Timer tick, asking Buildbucket for the build status
[18:57:08.357] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:08.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:192:0) after 1m0s
[18:58:08.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:192:0)
[18:58:08.451] Timer tick, asking Buildbucket for the build status
[18:58:08.596] Build 8861105762554322240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:08.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026535824462867760:193:0) after 1m0s
[18:58:21.189] Received PubSub notification, asking Buildbucket for the build status
[18:58:21.244] Build 8861105762554322240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:58:21.244] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1607799499982868",
    "created_by": "project:infra",
    "created_ts": "1607788041500416",
    "id": "8861105762554322240",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4353.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"68f1c45e-9b39-42e9-ad5a-3f901d0682f2\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4353.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-zhca\"], \"image\": [\"chrome-xenial-20120600-c5cfbe11ce8\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1607788091207497",
    "status": "COMPLETED",
    "status_changed_ts": "1607799500483031",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026535824462867760",
      "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/8861105762554322240/+/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:5070741efd83f310",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1607799500784919",
    "url": "https://ci.chromium.org/b/8861105762554322240",
    "utcnow_ts": "1607799501238036"
  }
}
[18:58:21.244] Invocation finished in 3h11m0.948729461s with status SUCCEEDED