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

• parent_buildername:publish_tarball
• user_agent:recipe

[19:51:27.055] New invocation is queued and will start shortly
[19:51:27.055] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[19:51:29.540] Starting the invocation (attempt 1)
[19:51:29.578] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:51:29.578] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:51:29.578] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9045364635252731312",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"83.0.4103.61\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"a623841c-c199-4361-a01f-46fa90edb261\",\"title\":\"publish_tarball/0\"}]},\"version\":\"83.0.4103.61\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9045364635252731312",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[19:51:30.394] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1589831489742605",
    "id": "8879934571771021952",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"83.0.4103.61\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"a623841c-c199-4361-a01f-46fa90edb261\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"83.0.4103.61\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1589831490148028",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9045364635252731312",
      "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/8879934571771021952/+/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": "1589831490148186",
    "url": "https://ci.chromium.org/b/8879934571771021952",
    "utcnow_ts": "1589831490315411"
  }
}
[19:51:30.394] Task URL: https://ci.chromium.org/b/8879934571771021952
[19:51:30.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:2:0) after 1m0s
[19:52:30.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:2:0)
[19:52:30.413] Timer tick, asking Buildbucket for the build status
[19:52:30.805] Build 8879934571771021952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:30.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:3:0) after 1m0s
[19:53:30.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:3:0)
[19:53:30.885] Timer tick, asking Buildbucket for the build status
[19:53:31.468] Build 8879934571771021952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:31.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:4:0) after 1m0s
[19:54:31.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:4:0)
[19:54:31.482] Timer tick, asking Buildbucket for the build status
[19:54:31.985] Build 8879934571771021952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:31.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:5:0) after 1m0s
[19:55:32.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:5:0)
[19:55:32.005] Timer tick, asking Buildbucket for the build status
[19:55:32.411] Build 8879934571771021952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:32.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:6:0) after 1m0s
[19:56:19.729] Received PubSub notification, asking Buildbucket for the build status
[19:56:19.861] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:32.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:6:0)
[19:56:32.466] Timer tick, asking Buildbucket for the build status
[19:56:32.841] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:32.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:8:0) after 1m0s
[19:57:32.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:8:0)
[19:57:32.872] Timer tick, asking Buildbucket for the build status
[19:57:33.012] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:33.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:9:0) after 1m0s
[19:58:33.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:9:0)
[19:58:33.032] Timer tick, asking Buildbucket for the build status
[19:58:33.246] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:33.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:10:0) after 1m0s
[19:59:33.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:10:0)
[19:59:33.235] Timer tick, asking Buildbucket for the build status
[19:59:33.891] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:33.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:11:0) after 1m0s
[20:00:34.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:11:0)
[20:00:34.071] Timer tick, asking Buildbucket for the build status
[20:00:34.292] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:34.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:12:0) after 1m0s
[20:01:34.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:12:0)
[20:01:34.348] Timer tick, asking Buildbucket for the build status
[20:01:34.561] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:34.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:13:0) after 1m0s
[20:02:34.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:13:0)
[20:02:34.599] Timer tick, asking Buildbucket for the build status
[20:02:34.801] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:34.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:14:0) after 1m0s
[20:03:34.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:14:0)
[20:03:34.827] Timer tick, asking Buildbucket for the build status
[20:03:35.650] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:35.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:15:0) after 1m0s
[20:04:35.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:15:0)
[20:04:35.716] Timer tick, asking Buildbucket for the build status
[20:04:37.679] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:37.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:16:0) after 1m0s
[20:05:37.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:16:0)
[20:05:37.900] Timer tick, asking Buildbucket for the build status
[20:05:38.208] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:38.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:17:0) after 1m0s
[20:06:38.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:17:0)
[20:06:38.274] Timer tick, asking Buildbucket for the build status
[20:06:38.437] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:38.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:18:0) after 1m0s
[20:07:38.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:18:0)
[20:07:38.530] Timer tick, asking Buildbucket for the build status
[20:07:38.615] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:38.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:19:0) after 1m0s
[20:08:38.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:19:0)
[20:08:38.684] Timer tick, asking Buildbucket for the build status
[20:08:39.079] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:39.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:20:0) after 1m0s
[20:09:39.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:20:0)
[20:09:39.269] Timer tick, asking Buildbucket for the build status
[20:09:40.275] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:40.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:21:0) after 1m0s
[20:10:40.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:21:0)
[20:10:40.429] Timer tick, asking Buildbucket for the build status
[20:10:41.263] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:41.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:22:0) after 1m0s
[20:11:41.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:22:0)
[20:11:41.282] Timer tick, asking Buildbucket for the build status
[20:11:41.428] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:41.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:23:0) after 1m0s
[20:12:41.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:23:0)
[20:12:41.499] Timer tick, asking Buildbucket for the build status
[20:12:42.024] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:42.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:24:0) after 1m0s
[20:13:41.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:24:0)
[20:13:41.996] Timer tick, asking Buildbucket for the build status
[20:13:42.236] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:42.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:25:0) after 1m0s
[20:14:42.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:25:0)
[20:14:42.280] Timer tick, asking Buildbucket for the build status
[20:14:43.052] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:43.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:26:0) after 1m0s
[20:15:43.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:26:0)
[20:15:43.085] Timer tick, asking Buildbucket for the build status
[20:15:43.283] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:43.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:27:0) after 1m0s
[20:16:43.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:27:0)
[20:16:43.458] Timer tick, asking Buildbucket for the build status
[20:16:44.382] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:44.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:28:0) after 1m0s
[20:17:44.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:28:0)
[20:17:44.427] Timer tick, asking Buildbucket for the build status
[20:17:45.038] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:45.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:29:0) after 1m0s
[20:18:45.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:29:0)
[20:18:45.079] Timer tick, asking Buildbucket for the build status
[20:18:45.159] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:45.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:30:0) after 1m0s
[20:19:45.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:30:0)
[20:19:45.167] Timer tick, asking Buildbucket for the build status
[20:19:45.285] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:45.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:31:0) after 1m0s
[20:20:45.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:31:0)
[20:20:45.365] Timer tick, asking Buildbucket for the build status
[20:20:49.986] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:49.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:32:0) after 1m0s
[20:21:50.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:32:0)
[20:21:50.027] Timer tick, asking Buildbucket for the build status
[20:21:50.328] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:50.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:33:0) after 1m0s
[20:22:50.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:33:0)
[20:22:50.447] Timer tick, asking Buildbucket for the build status
[20:22:50.962] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:50.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:34:0) after 1m0s
[20:23:51.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:34:0)
[20:23:51.020] Timer tick, asking Buildbucket for the build status
[20:23:51.171] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:51.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:35:0) after 1m0s
[20:24:51.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:35:0)
[20:24:51.207] Timer tick, asking Buildbucket for the build status
[20:24:51.494] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:51.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:36:0) after 1m0s
[20:25:51.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:36:0)
[20:25:51.709] Timer tick, asking Buildbucket for the build status
[20:25:51.974] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:51.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:37:0) after 1m0s
[20:26:52.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:37:0)
[20:26:52.062] Timer tick, asking Buildbucket for the build status
[20:26:52.350] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:52.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:38:0) after 1m0s
[20:27:52.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:38:0)
[20:27:52.706] Timer tick, asking Buildbucket for the build status
[20:27:53.109] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:53.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:39:0) after 1m0s
[20:28:53.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:39:0)
[20:28:53.188] Timer tick, asking Buildbucket for the build status
[20:28:53.321] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:53.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:40:0) after 1m0s
[20:29:53.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:40:0)
[20:29:53.344] Timer tick, asking Buildbucket for the build status
[20:29:53.498] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:53.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:41:0) after 1m0s
[20:30:53.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:41:0)
[20:30:53.595] Timer tick, asking Buildbucket for the build status
[20:30:53.952] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:53.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:42:0) after 1m0s
[20:31:54.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:42:0)
[20:31:54.016] Timer tick, asking Buildbucket for the build status
[20:31:54.210] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:54.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:43:0) after 1m0s
[20:32:54.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:43:0)
[20:32:54.261] Timer tick, asking Buildbucket for the build status
[20:32:54.404] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:54.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:44:0) after 1m0s
[20:33:54.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:44:0)
[20:33:54.433] Timer tick, asking Buildbucket for the build status
[20:33:54.623] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:54.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:45:0) after 1m0s
[20:34:54.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:45:0)
[20:34:54.751] Timer tick, asking Buildbucket for the build status
[20:34:54.955] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:54.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:46:0) after 1m0s
[20:35:55.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:46:0)
[20:35:55.069] Timer tick, asking Buildbucket for the build status
[20:35:55.326] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:55.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:47:0) after 1m0s
[20:36:55.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:47:0)
[20:36:55.336] Timer tick, asking Buildbucket for the build status
[20:36:55.515] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:55.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:48:0) after 1m0s
[20:37:55.605] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:48:0)
[20:37:55.605] Timer tick, asking Buildbucket for the build status
[20:37:56.353] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:56.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:49:0) after 1m0s
[20:38:56.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:49:0)
[20:38:56.405] Timer tick, asking Buildbucket for the build status
[20:38:56.635] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:56.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:50:0) after 1m0s
[20:39:56.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:50:0)
[20:39:56.799] Timer tick, asking Buildbucket for the build status
[20:39:57.288] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:57.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:51:0) after 1m0s
[20:40:57.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:51:0)
[20:40:57.312] Timer tick, asking Buildbucket for the build status
[20:40:57.533] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:57.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:52:0) after 1m0s
[20:41:57.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:52:0)
[20:41:57.509] Timer tick, asking Buildbucket for the build status
[20:41:57.642] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:57.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:53:0) after 1m0s
[20:42:57.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:53:0)
[20:42:57.711] Timer tick, asking Buildbucket for the build status
[20:42:57.886] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:57.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:54:0) after 1m0s
[20:43:57.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:54:0)
[20:43:57.928] Timer tick, asking Buildbucket for the build status
[20:43:58.678] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:58.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:55:0) after 1m0s
[20:44:58.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:55:0)
[20:44:58.752] Timer tick, asking Buildbucket for the build status
[20:44:59.079] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:59.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:56:0) after 1m0s
[20:45:59.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:56:0)
[20:45:59.122] Timer tick, asking Buildbucket for the build status
[20:45:59.332] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:59.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:57:0) after 1m0s
[20:46:59.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:57:0)
[20:46:59.381] Timer tick, asking Buildbucket for the build status
[20:46:59.793] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:59.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:58:0) after 1m0s
[20:47:59.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:58:0)
[20:47:59.871] Timer tick, asking Buildbucket for the build status
[20:48:00.174] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:00.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:59:0) after 1m0s
[20:49:00.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:59:0)
[20:49:00.208] Timer tick, asking Buildbucket for the build status
[20:49:00.868] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:00.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:60:0) after 1m0s
[20:50:02.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:60:0)
[20:50:02.050] Timer tick, asking Buildbucket for the build status
[20:50:02.579] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:02.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:61:0) after 1m0s
[20:51:02.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:61:0)
[20:51:02.610] Timer tick, asking Buildbucket for the build status
[20:51:03.678] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:03.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:62:0) after 1m0s
[20:52:03.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:62:0)
[20:52:03.774] Timer tick, asking Buildbucket for the build status
[20:52:04.032] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:04.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:63:0) after 1m0s
[20:53:04.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:63:0)
[20:53:04.102] Timer tick, asking Buildbucket for the build status
[20:53:04.464] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:04.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:64:0) after 1m0s
[20:54:04.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:64:0)
[20:54:04.513] Timer tick, asking Buildbucket for the build status
[20:54:04.635] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:04.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:65:0) after 1m0s
[20:55:04.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:65:0)
[20:55:04.711] Timer tick, asking Buildbucket for the build status
[20:55:05.615] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:05.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:66:0) after 1m0s
[20:56:05.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:66:0)
[20:56:05.666] Timer tick, asking Buildbucket for the build status
[20:56:06.006] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:06.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:67:0) after 1m0s
[20:57:06.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:67:0)
[20:57:06.040] Timer tick, asking Buildbucket for the build status
[20:57:06.385] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:06.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:68:0) after 1m0s
[20:58:06.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:68:0)
[20:58:06.390] Timer tick, asking Buildbucket for the build status
[20:58:06.825] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:06.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:69:0) after 1m0s
[20:59:06.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:69:0)
[20:59:06.882] Timer tick, asking Buildbucket for the build status
[20:59:07.433] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:07.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:70:0) after 1m0s
[21:00:07.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:70:0)
[21:00:07.460] Timer tick, asking Buildbucket for the build status
[21:00:08.496] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:08.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:71:0) after 1m0s
[21:01:08.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:71:0)
[21:01:08.579] Timer tick, asking Buildbucket for the build status
[21:01:08.816] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:08.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:72:0) after 1m0s
[21:02:08.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:72:0)
[21:02:08.865] Timer tick, asking Buildbucket for the build status
[21:02:09.093] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:09.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:73:0) after 1m0s
[21:03:09.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:73:0)
[21:03:09.127] Timer tick, asking Buildbucket for the build status
[21:03:09.297] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:09.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:74:0) after 1m0s
[21:04:09.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:74:0)
[21:04:09.377] Timer tick, asking Buildbucket for the build status
[21:04:09.633] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:09.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:75:0) after 1m0s
[21:05:09.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:75:0)
[21:05:09.666] Timer tick, asking Buildbucket for the build status
[21:05:10.411] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:10.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:76:0) after 1m0s
[21:06:10.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:76:0)
[21:06:10.492] Timer tick, asking Buildbucket for the build status
[21:06:11.866] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:11.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:77:0) after 1m0s
[21:07:12.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:77:0)
[21:07:12.067] Timer tick, asking Buildbucket for the build status
[21:07:12.720] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:12.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:78:0) after 1m0s
[21:08:12.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:78:0)
[21:08:12.876] Timer tick, asking Buildbucket for the build status
[21:08:13.174] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:13.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:79:0) after 1m0s
[21:09:13.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:79:0)
[21:09:13.224] Timer tick, asking Buildbucket for the build status
[21:09:13.375] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:13.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:80:0) after 1m0s
[21:10:13.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:80:0)
[21:10:13.417] Timer tick, asking Buildbucket for the build status
[21:10:14.357] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:14.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:81:0) after 1m0s
[21:11:14.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:81:0)
[21:11:14.337] Timer tick, asking Buildbucket for the build status
[21:11:14.998] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:14.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:82:0) after 1m0s
[21:12:15.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:82:0)
[21:12:15.002] Timer tick, asking Buildbucket for the build status
[21:12:15.237] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:15.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:83:0) after 1m0s
[21:13:15.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:83:0)
[21:13:15.298] Timer tick, asking Buildbucket for the build status
[21:13:15.521] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:15.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:84:0) after 1m0s
[21:14:15.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:84:0)
[21:14:15.547] Timer tick, asking Buildbucket for the build status
[21:14:16.341] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:16.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:85:0) after 1m0s
[21:15:16.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:85:0)
[21:15:16.406] Timer tick, asking Buildbucket for the build status
[21:15:16.575] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:16.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:86:0) after 1m0s
[21:16:16.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:86:0)
[21:16:16.571] Timer tick, asking Buildbucket for the build status
[21:16:16.764] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:16.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:87:0) after 1m0s
[21:17:16.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:87:0)
[21:17:16.811] Timer tick, asking Buildbucket for the build status
[21:17:17.116] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:17.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:88:0) after 1m0s
[21:18:17.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:88:0)
[21:18:17.154] Timer tick, asking Buildbucket for the build status
[21:18:17.550] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:17.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:89:0) after 1m0s
[21:19:17.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:89:0)
[21:19:17.695] Timer tick, asking Buildbucket for the build status
[21:19:17.924] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:17.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:90:0) after 1m0s
[21:20:17.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:90:0)
[21:20:17.954] Timer tick, asking Buildbucket for the build status
[21:20:18.315] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:18.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:91:0) after 1m0s
[21:21:18.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:91:0)
[21:21:18.414] Timer tick, asking Buildbucket for the build status
[21:21:18.982] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:18.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:92:0) after 1m0s
[21:22:19.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:92:0)
[21:22:19.005] Timer tick, asking Buildbucket for the build status
[21:22:19.308] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:19.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:93:0) after 1m0s
[21:23:19.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:93:0)
[21:23:19.342] Timer tick, asking Buildbucket for the build status
[21:23:23.836] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:23.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:94:0) after 1m0s
[21:24:23.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:94:0)
[21:24:23.880] Timer tick, asking Buildbucket for the build status
[21:24:24.308] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:24.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:95:0) after 1m0s
[21:25:24.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:95:0)
[21:25:24.341] Timer tick, asking Buildbucket for the build status
[21:25:24.800] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:24.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:96:0) after 1m0s
[21:26:24.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:96:0)
[21:26:24.866] Timer tick, asking Buildbucket for the build status
[21:26:25.287] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:25.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:97:0) after 1m0s
[21:27:25.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:97:0)
[21:27:25.337] Timer tick, asking Buildbucket for the build status
[21:27:25.648] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:25.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:98:0) after 1m0s
[21:28:25.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:98:0)
[21:28:25.668] Timer tick, asking Buildbucket for the build status
[21:28:25.891] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:25.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:99:0) after 1m0s
[21:29:26.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:99:0)
[21:29:26.060] Timer tick, asking Buildbucket for the build status
[21:29:26.647] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:26.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:100:0) after 1m0s
[21:30:26.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:100:0)
[21:30:26.707] Timer tick, asking Buildbucket for the build status
[21:30:26.948] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:26.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:101:0) after 1m0s
[21:31:27.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:101:0)
[21:31:27.057] Timer tick, asking Buildbucket for the build status
[21:31:27.436] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:27.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:102:0) after 1m0s
[21:32:27.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:102:0)
[21:32:27.533] Timer tick, asking Buildbucket for the build status
[21:32:28.165] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:28.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:103:0) after 1m0s
[21:33:28.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:103:0)
[21:33:28.190] Timer tick, asking Buildbucket for the build status
[21:33:28.419] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:28.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:104:0) after 1m0s
[21:34:28.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:104:0)
[21:34:28.518] Timer tick, asking Buildbucket for the build status
[21:34:28.714] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:28.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:105:0) after 1m0s
[21:35:28.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:105:0)
[21:35:28.741] Timer tick, asking Buildbucket for the build status
[21:35:29.576] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:29.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:106:0) after 1m0s
[21:36:29.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:106:0)
[21:36:29.588] Timer tick, asking Buildbucket for the build status
[21:36:29.905] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:29.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:107:0) after 1m0s
[21:37:29.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:107:0)
[21:37:29.936] Timer tick, asking Buildbucket for the build status
[21:37:30.098] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:30.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:108:0) after 1m0s
[21:38:30.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:108:0)
[21:38:30.127] Timer tick, asking Buildbucket for the build status
[21:38:30.377] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:30.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:109:0) after 1m0s
[21:39:30.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:109:0)
[21:39:30.435] Timer tick, asking Buildbucket for the build status
[21:39:30.613] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:30.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:110:0) after 1m0s
[21:40:30.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:110:0)
[21:40:30.648] Timer tick, asking Buildbucket for the build status
[21:40:30.893] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:30.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:111:0) after 1m0s
[21:41:30.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:111:0)
[21:41:30.893] Timer tick, asking Buildbucket for the build status
[21:41:31.868] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:31.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:112:0) after 1m0s
[21:42:31.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:112:0)
[21:42:31.941] Timer tick, asking Buildbucket for the build status
[21:42:32.142] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:32.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:113:0) after 1m0s
[21:43:32.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:113:0)
[21:43:32.166] Timer tick, asking Buildbucket for the build status
[21:43:32.871] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:32.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:114:0) after 1m0s
[21:44:32.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:114:0)
[21:44:32.881] Timer tick, asking Buildbucket for the build status
[21:44:33.211] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:33.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:115:0) after 1m0s
[21:45:33.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:115:0)
[21:45:33.572] Timer tick, asking Buildbucket for the build status
[21:45:39.069] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:39.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:116:0) after 1m0s
[21:46:39.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:116:0)
[21:46:39.248] Timer tick, asking Buildbucket for the build status
[21:46:39.468] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:39.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:117:0) after 1m0s
[21:47:39.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:117:0)
[21:47:39.523] Timer tick, asking Buildbucket for the build status
[21:47:39.794] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:39.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:118:0) after 1m0s
[21:48:39.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:118:0)
[21:48:39.880] Timer tick, asking Buildbucket for the build status
[21:48:40.355] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:40.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:119:0) after 1m0s
[21:49:40.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:119:0)
[21:49:40.414] Timer tick, asking Buildbucket for the build status
[21:49:40.875] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:40.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:120:0) after 1m0s
[21:50:40.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:120:0)
[21:50:40.961] Timer tick, asking Buildbucket for the build status
[21:50:41.254] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:41.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:121:0) after 1m0s
[21:51:41.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:121:0)
[21:51:41.343] Timer tick, asking Buildbucket for the build status
[21:51:41.536] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:41.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:122:0) after 1m0s
[21:52:41.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:122:0)
[21:52:41.713] Timer tick, asking Buildbucket for the build status
[21:52:42.056] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:42.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:123:0) after 1m0s
[21:53:42.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:123:0)
[21:53:42.089] Timer tick, asking Buildbucket for the build status
[21:53:42.300] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:42.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:124:0) after 1m0s
[21:54:42.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:124:0)
[21:54:42.319] Timer tick, asking Buildbucket for the build status
[21:54:43.222] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:43.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:125:0) after 1m0s
[21:55:43.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:125:0)
[21:55:43.265] Timer tick, asking Buildbucket for the build status
[21:55:44.285] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:44.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:126:0) after 1m0s
[21:56:44.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:126:0)
[21:56:44.364] Timer tick, asking Buildbucket for the build status
[21:56:44.560] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:44.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:127:0) after 1m0s
[21:57:44.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:127:0)
[21:57:44.653] Timer tick, asking Buildbucket for the build status
[21:57:44.801] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:44.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:128:0) after 1m0s
[21:58:44.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:128:0)
[21:58:44.873] Timer tick, asking Buildbucket for the build status
[21:58:45.236] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:45.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:129:0) after 1m0s
[21:59:45.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:129:0)
[21:59:45.268] Timer tick, asking Buildbucket for the build status
[21:59:45.837] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:45.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:130:0) after 1m0s
[22:00:45.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:130:0)
[22:00:45.836] Timer tick, asking Buildbucket for the build status
[22:00:46.212] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:46.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:131:0) after 1m0s
[22:01:46.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:131:0)
[22:01:46.189] Timer tick, asking Buildbucket for the build status
[22:01:47.339] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:47.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:132:0) after 1m0s
[22:02:47.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:132:0)
[22:02:47.397] Timer tick, asking Buildbucket for the build status
[22:02:47.686] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:47.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:133:0) after 1m0s
[22:03:47.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:133:0)
[22:03:47.691] Timer tick, asking Buildbucket for the build status
[22:03:47.874] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:47.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:134:0) after 1m0s
[22:04:48.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:134:0)
[22:04:48.008] Timer tick, asking Buildbucket for the build status
[22:04:48.171] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:48.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:135:0) after 1m0s
[22:05:48.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:135:0)
[22:05:48.232] Timer tick, asking Buildbucket for the build status
[22:05:48.500] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:48.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:136:0) after 1m0s
[22:06:48.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:136:0)
[22:06:48.654] Timer tick, asking Buildbucket for the build status
[22:06:48.860] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:48.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:137:0) after 1m0s
[22:07:48.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:137:0)
[22:07:48.855] Timer tick, asking Buildbucket for the build status
[22:07:49.173] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:49.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:138:0) after 1m0s
[22:08:49.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:138:0)
[22:08:49.194] Timer tick, asking Buildbucket for the build status
[22:08:49.404] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:49.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:139:0) after 1m0s
[22:09:49.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:139:0)
[22:09:49.434] Timer tick, asking Buildbucket for the build status
[22:09:49.645] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:49.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:140:0) after 1m0s
[22:10:49.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:140:0)
[22:10:49.763] Timer tick, asking Buildbucket for the build status
[22:10:49.957] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:49.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:141:0) after 1m0s
[22:11:49.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:141:0)
[22:11:49.991] Timer tick, asking Buildbucket for the build status
[22:11:50.156] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:50.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:142:0) after 1m0s
[22:12:50.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:142:0)
[22:12:50.277] Timer tick, asking Buildbucket for the build status
[22:12:50.522] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:50.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:143:0) after 1m0s
[22:13:50.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:143:0)
[22:13:50.500] Timer tick, asking Buildbucket for the build status
[22:13:50.707] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:50.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:144:0) after 1m0s
[22:14:50.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:144:0)
[22:14:50.739] Timer tick, asking Buildbucket for the build status
[22:14:50.905] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:50.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:145:0) after 1m0s
[22:15:50.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:145:0)
[22:15:50.996] Timer tick, asking Buildbucket for the build status
[22:15:51.228] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:51.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:146:0) after 1m0s
[22:16:51.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:146:0)
[22:16:51.352] Timer tick, asking Buildbucket for the build status
[22:16:51.823] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:51.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:147:0) after 1m0s
[22:17:51.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:147:0)
[22:17:51.994] Timer tick, asking Buildbucket for the build status
[22:17:52.231] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:52.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:148:0) after 1m0s
[22:18:52.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:148:0)
[22:18:52.256] Timer tick, asking Buildbucket for the build status
[22:18:52.400] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:52.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:149:0) after 1m0s
[22:19:52.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:149:0)
[22:19:52.459] Timer tick, asking Buildbucket for the build status
[22:19:52.646] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:52.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:150:0) after 1m0s
[22:20:52.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:150:0)
[22:20:52.668] Timer tick, asking Buildbucket for the build status
[22:20:52.958] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:52.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:151:0) after 1m0s
[22:21:53.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:151:0)
[22:21:53.005] Timer tick, asking Buildbucket for the build status
[22:21:53.519] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:53.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:152:0) after 1m0s
[22:22:53.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:152:0)
[22:22:53.559] Timer tick, asking Buildbucket for the build status
[22:22:53.699] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:53.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:153:0) after 1m0s
[22:23:53.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:153:0)
[22:23:53.773] Timer tick, asking Buildbucket for the build status
[22:23:54.572] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:54.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:154:0) after 1m0s
[22:24:54.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:154:0)
[22:24:54.592] Timer tick, asking Buildbucket for the build status
[22:24:54.827] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:54.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:155:0) after 1m0s
[22:25:54.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:155:0)
[22:25:54.875] Timer tick, asking Buildbucket for the build status
[22:25:55.115] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:55.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:156:0) after 1m0s
[22:26:55.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:156:0)
[22:26:55.152] Timer tick, asking Buildbucket for the build status
[22:26:55.386] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:55.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:157:0) after 1m0s
[22:27:55.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:157:0)
[22:27:55.435] Timer tick, asking Buildbucket for the build status
[22:27:55.551] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:55.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:158:0) after 1m0s
[22:28:55.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:158:0)
[22:28:55.592] Timer tick, asking Buildbucket for the build status
[22:28:55.771] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:55.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:159:0) after 1m0s
[22:29:55.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:159:0)
[22:29:55.819] Timer tick, asking Buildbucket for the build status
[22:29:55.955] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:55.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:160:0) after 1m0s
[22:30:55.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:160:0)
[22:30:55.956] Timer tick, asking Buildbucket for the build status
[22:30:56.957] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:56.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:161:0) after 1m0s
[22:31:57.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:161:0)
[22:31:57.003] Timer tick, asking Buildbucket for the build status
[22:31:57.417] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:57.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:162:0) after 1m0s
[22:32:57.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:162:0)
[22:32:57.526] Timer tick, asking Buildbucket for the build status
[22:32:58.181] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:58.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:163:0) after 1m0s
[22:33:58.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:163:0)
[22:33:58.209] Timer tick, asking Buildbucket for the build status
[22:33:58.554] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:58.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:164:0) after 1m0s
[22:34:58.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:164:0)
[22:34:58.614] Timer tick, asking Buildbucket for the build status
[22:34:59.634] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:59.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:165:0) after 1m0s
[22:35:59.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:165:0)
[22:35:59.670] Timer tick, asking Buildbucket for the build status
[22:36:01.852] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:01.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:166:0) after 1m0s
[22:37:01.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:166:0)
[22:37:01.965] Timer tick, asking Buildbucket for the build status
[22:37:02.152] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:02.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:167:0) after 1m0s
[22:38:02.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:167:0)
[22:38:02.202] Timer tick, asking Buildbucket for the build status
[22:38:02.605] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:02.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:168:0) after 1m0s
[22:39:02.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:168:0)
[22:39:02.670] Timer tick, asking Buildbucket for the build status
[22:39:02.870] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:02.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:169:0) after 1m0s
[22:40:02.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:169:0)
[22:40:02.939] Timer tick, asking Buildbucket for the build status
[22:40:03.105] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:03.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:170:0) after 1m0s
[22:41:03.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:170:0)
[22:41:03.256] Timer tick, asking Buildbucket for the build status
[22:41:03.567] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:03.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:171:0) after 1m0s
[22:42:03.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:171:0)
[22:42:03.646] Timer tick, asking Buildbucket for the build status
[22:42:03.951] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:03.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:172:0) after 1m0s
[22:43:04.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:172:0)
[22:43:04.014] Timer tick, asking Buildbucket for the build status
[22:43:04.267] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:04.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:173:0) after 1m0s
[22:44:04.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:173:0)
[22:44:04.294] Timer tick, asking Buildbucket for the build status
[22:44:04.471] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:04.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:174:0) after 1m0s
[22:45:04.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:174:0)
[22:45:04.520] Timer tick, asking Buildbucket for the build status
[22:45:05.683] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:05.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:175:0) after 1m0s
[22:46:05.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:175:0)
[22:46:05.708] Timer tick, asking Buildbucket for the build status
[22:46:06.264] Build 8879934571771021952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:06.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045364635252731312:176:0) after 1m0s
[22:46:35.776] Received PubSub notification, asking Buildbucket for the build status
[22:46:36.199] Build 8879934571771021952: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:46:36.200] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1589841994979517",
    "created_by": "project:infra",
    "created_ts": "1589831489742605",
    "id": "8879934571771021952",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"83.0.4103.61\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"a623841c-c199-4361-a01f-46fa90edb261\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"83.0.4103.61\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"83.0.4103.61\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"a623841c-c199-4361-a01f-46fa90edb261\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-ny7m\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"83.0.4103.61\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"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-ny7m\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5088-a734129\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1589831779018288",
    "status": "COMPLETED",
    "status_changed_ts": "1589841995499212",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9045364635252731312",
      "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/8879934571771021952/+/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:4c4228e8d2718a10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1589841995518213",
    "url": "https://ci.chromium.org/b/8879934571771021952",
    "utcnow_ts": "1589841996179884"
  }
}
[22:46:36.201] Invocation finished in 2h55m9.162948234s with status SUCCEEDED