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

• parent_buildername:publish_tarball
• user_agent:recipe

[17:08:40.220] New invocation is queued and will start shortly
[17:08:40.220] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[17:08:42.345] Starting the invocation (attempt 1)
[17:08:42.391] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:08:42.391] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:08:42.391] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9036224582916659344",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"87.0.4246.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"771f3b7f-92a9-448f-bacc-ddcffe0a2f4b\",\"title\":\"publish_tarball/0\"}]},\"version\":\"87.0.4246.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:9036224582916659344",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[17:08:42.833] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1598548122472497",
    "id": "8870794519888883904",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4246.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"771f3b7f-92a9-448f-bacc-ddcffe0a2f4b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4246.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1598548122631889",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036224582916659344",
      "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/8870794519888883904/+/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": "1598548122632077",
    "url": "https://ci.chromium.org/b/8870794519888883904",
    "utcnow_ts": "1598548122824036"
  }
}
[17:08:42.833] Task URL: https://ci.chromium.org/b/8870794519888883904
[17:08:42.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:2:0) after 1m0s
[17:08:47.533] Received PubSub notification, asking Buildbucket for the build status
[17:08:47.648] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:44.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:2:0)
[17:09:44.084] Timer tick, asking Buildbucket for the build status
[17:09:44.328] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:44.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:4:0) after 1m0s
[17:10:44.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:4:0)
[17:10:44.591] Timer tick, asking Buildbucket for the build status
[17:10:44.735] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:44.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:5:0) after 1m0s
[17:11:44.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:5:0)
[17:11:44.992] Timer tick, asking Buildbucket for the build status
[17:11:45.611] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:45.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:6:0) after 1m0s
[17:12:45.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:6:0)
[17:12:45.778] Timer tick, asking Buildbucket for the build status
[17:12:45.915] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:45.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:7:0) after 1m0s
[17:13:46.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:7:0)
[17:13:46.096] Timer tick, asking Buildbucket for the build status
[17:13:46.240] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:46.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:8:0) after 1m0s
[17:14:46.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:8:0)
[17:14:46.256] Timer tick, asking Buildbucket for the build status
[17:14:46.586] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:46.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:9:0) after 1m0s
[17:15:46.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:9:0)
[17:15:46.697] Timer tick, asking Buildbucket for the build status
[17:15:46.907] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:46.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:10:0) after 1m0s
[17:16:46.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:10:0)
[17:16:46.956] Timer tick, asking Buildbucket for the build status
[17:16:47.189] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:47.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:11:0) after 1m0s
[17:17:47.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:11:0)
[17:17:47.264] Timer tick, asking Buildbucket for the build status
[17:17:47.530] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:47.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:12:0) after 1m0s
[17:18:47.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:12:0)
[17:18:47.632] Timer tick, asking Buildbucket for the build status
[17:18:47.802] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:47.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:13:0) after 1m0s
[17:19:47.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:13:0)
[17:19:47.948] Timer tick, asking Buildbucket for the build status
[17:19:48.032] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:48.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:14:0) after 1m0s
[17:20:48.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:14:0)
[17:20:48.287] Timer tick, asking Buildbucket for the build status
[17:20:48.647] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:48.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:15:0) after 1m0s
[17:21:48.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:15:0)
[17:21:48.807] Timer tick, asking Buildbucket for the build status
[17:21:48.892] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:48.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:16:0) after 1m0s
[17:22:49.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:16:0)
[17:22:49.040] Timer tick, asking Buildbucket for the build status
[17:22:51.874] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:51.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:17:0) after 1m0s
[17:23:51.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:17:0)
[17:23:51.902] Timer tick, asking Buildbucket for the build status
[17:23:52.083] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:52.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:18:0) after 1m0s
[17:24:52.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:18:0)
[17:24:52.419] Timer tick, asking Buildbucket for the build status
[17:24:52.526] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:52.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:19:0) after 1m0s
[17:25:52.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:19:0)
[17:25:52.817] Timer tick, asking Buildbucket for the build status
[17:25:52.984] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:52.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:20:0) after 1m0s
[17:26:53.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:20:0)
[17:26:53.130] Timer tick, asking Buildbucket for the build status
[17:26:53.491] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:53.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:21:0) after 1m0s
[17:27:53.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:21:0)
[17:27:53.522] Timer tick, asking Buildbucket for the build status
[17:27:53.606] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:53.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:22:0) after 1m0s
[17:28:53.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:22:0)
[17:28:53.724] Timer tick, asking Buildbucket for the build status
[17:28:53.850] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:53.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:23:0) after 1m0s
[17:29:53.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:23:0)
[17:29:53.916] Timer tick, asking Buildbucket for the build status
[17:29:54.076] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:54.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:24:0) after 1m0s
[17:30:54.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:24:0)
[17:30:54.099] Timer tick, asking Buildbucket for the build status
[17:30:54.247] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:54.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:25:0) after 1m0s
[17:31:54.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:25:0)
[17:31:54.235] Timer tick, asking Buildbucket for the build status
[17:31:54.307] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:54.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:26:0) after 1m0s
[17:32:54.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:26:0)
[17:32:54.397] Timer tick, asking Buildbucket for the build status
[17:32:54.470] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:54.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:27:0) after 1m0s
[17:33:54.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:27:0)
[17:33:54.632] Timer tick, asking Buildbucket for the build status
[17:33:54.780] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:54.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:28:0) after 1m0s
[17:34:54.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:28:0)
[17:34:54.927] Timer tick, asking Buildbucket for the build status
[17:34:55.007] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:55.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:29:0) after 1m0s
[17:35:55.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:29:0)
[17:35:55.113] Timer tick, asking Buildbucket for the build status
[17:35:55.175] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:55.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:30:0) after 1m0s
[17:36:55.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:30:0)
[17:36:55.467] Timer tick, asking Buildbucket for the build status
[17:36:55.545] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:55.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:31:0) after 1m0s
[17:37:55.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:31:0)
[17:37:55.749] Timer tick, asking Buildbucket for the build status
[17:37:55.825] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:55.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:32:0) after 1m0s
[17:38:56.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:32:0)
[17:38:56.031] Timer tick, asking Buildbucket for the build status
[17:38:56.403] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:56.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:33:0) after 1m0s
[17:39:56.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:33:0)
[17:39:56.534] Timer tick, asking Buildbucket for the build status
[17:39:56.600] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:56.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:34:0) after 1m0s
[17:40:56.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:34:0)
[17:40:56.711] Timer tick, asking Buildbucket for the build status
[17:40:56.904] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:56.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:35:0) after 1m0s
[17:41:57.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:35:0)
[17:41:57.222] Timer tick, asking Buildbucket for the build status
[17:41:57.443] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:57.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:36:0) after 1m0s
[17:42:57.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:36:0)
[17:42:57.481] Timer tick, asking Buildbucket for the build status
[17:42:57.547] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:57.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:37:0) after 1m0s
[17:43:57.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:37:0)
[17:43:57.696] Timer tick, asking Buildbucket for the build status
[17:43:57.858] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:57.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:38:0) after 1m0s
[17:44:58.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:38:0)
[17:44:58.051] Timer tick, asking Buildbucket for the build status
[17:44:58.137] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:58.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:39:0) after 1m0s
[17:45:58.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:39:0)
[17:45:58.167] Timer tick, asking Buildbucket for the build status
[17:45:58.329] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:58.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:40:0) after 1m0s
[17:46:58.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:40:0)
[17:46:58.383] Timer tick, asking Buildbucket for the build status
[17:46:58.555] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:58.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:41:0) after 1m0s
[17:47:58.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:41:0)
[17:47:58.780] Timer tick, asking Buildbucket for the build status
[17:47:59.023] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:59.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:42:0) after 1m0s
[17:48:59.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:42:0)
[17:48:59.210] Timer tick, asking Buildbucket for the build status
[17:48:59.337] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:59.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:43:0) after 1m0s
[17:49:59.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:43:0)
[17:49:59.479] Timer tick, asking Buildbucket for the build status
[17:49:59.534] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:59.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:44:0) after 1m0s
[17:50:59.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:44:0)
[17:50:59.571] Timer tick, asking Buildbucket for the build status
[17:50:59.666] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:59.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:45:0) after 1m0s
[17:51:59.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:45:0)
[17:51:59.721] Timer tick, asking Buildbucket for the build status
[17:51:59.790] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:59.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:46:0) after 1m0s
[17:52:59.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:46:0)
[17:52:59.819] Timer tick, asking Buildbucket for the build status
[17:52:59.890] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:59.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:47:0) after 1m0s
[17:53:59.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:47:0)
[17:53:59.954] Timer tick, asking Buildbucket for the build status
[17:54:00.403] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:00.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:48:0) after 1m0s
[17:55:00.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:48:0)
[17:55:00.858] Timer tick, asking Buildbucket for the build status
[17:55:00.928] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:00.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:49:0) after 1m0s
[17:56:01.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:49:0)
[17:56:01.018] Timer tick, asking Buildbucket for the build status
[17:56:01.118] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:01.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:50:0) after 1m0s
[17:57:03.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:50:0)
[17:57:03.101] Timer tick, asking Buildbucket for the build status
[17:57:03.186] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:03.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:51:0) after 1m0s
[17:58:03.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:51:0)
[17:58:03.203] Timer tick, asking Buildbucket for the build status
[17:58:03.275] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:03.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:52:0) after 1m0s
[17:59:03.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:52:0)
[17:59:03.310] Timer tick, asking Buildbucket for the build status
[17:59:03.451] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:03.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:53:0) after 1m0s
[18:00:05.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:53:0)
[18:00:05.750] Timer tick, asking Buildbucket for the build status
[18:00:05.833] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:05.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:54:0) after 1m0s
[18:01:06.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:54:0)
[18:01:06.265] Timer tick, asking Buildbucket for the build status
[18:01:06.494] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:06.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:55:0) after 1m0s
[18:02:06.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:55:0)
[18:02:06.582] Timer tick, asking Buildbucket for the build status
[18:02:06.689] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:06.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:56:0) after 1m0s
[18:03:06.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:56:0)
[18:03:06.744] Timer tick, asking Buildbucket for the build status
[18:03:06.825] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:06.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:57:0) after 1m0s
[18:04:06.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:57:0)
[18:04:06.922] Timer tick, asking Buildbucket for the build status
[18:04:07.339] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:07.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:58:0) after 1m0s
[18:05:07.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:58:0)
[18:05:07.326] Timer tick, asking Buildbucket for the build status
[18:05:07.397] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:07.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:59:0) after 1m0s
[18:06:07.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:59:0)
[18:06:07.502] Timer tick, asking Buildbucket for the build status
[18:06:07.564] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:07.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:60:0) after 1m0s
[18:07:07.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:60:0)
[18:07:07.590] Timer tick, asking Buildbucket for the build status
[18:07:07.668] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:07.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:61:0) after 1m0s
[18:08:07.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:61:0)
[18:08:07.716] Timer tick, asking Buildbucket for the build status
[18:08:07.826] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:07.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:62:0) after 1m0s
[18:09:07.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:62:0)
[18:09:07.853] Timer tick, asking Buildbucket for the build status
[18:09:07.994] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:07.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:63:0) after 1m0s
[18:10:08.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:63:0)
[18:10:08.091] Timer tick, asking Buildbucket for the build status
[18:10:09.589] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:09.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:64:0) after 1m0s
[18:11:09.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:64:0)
[18:11:09.614] Timer tick, asking Buildbucket for the build status
[18:11:09.700] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:09.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:65:0) after 1m0s
[18:12:09.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:65:0)
[18:12:09.722] Timer tick, asking Buildbucket for the build status
[18:12:10.570] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:10.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:66:0) after 1m0s
[18:13:10.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:66:0)
[18:13:10.647] Timer tick, asking Buildbucket for the build status
[18:13:10.887] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:10.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:67:0) after 1m0s
[18:14:10.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:67:0)
[18:14:10.930] Timer tick, asking Buildbucket for the build status
[18:14:11.374] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:11.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:68:0) after 1m0s
[18:15:11.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:68:0)
[18:15:11.491] Timer tick, asking Buildbucket for the build status
[18:15:11.780] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:11.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:69:0) after 1m0s
[18:16:11.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:69:0)
[18:16:11.804] Timer tick, asking Buildbucket for the build status
[18:16:11.948] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:11.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:70:0) after 1m0s
[18:17:11.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:70:0)
[18:17:11.978] Timer tick, asking Buildbucket for the build status
[18:17:12.077] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:12.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:71:0) after 1m0s
[18:18:12.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:71:0)
[18:18:12.238] Timer tick, asking Buildbucket for the build status
[18:18:12.329] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:12.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:72:0) after 1m0s
[18:19:12.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:72:0)
[18:19:12.359] Timer tick, asking Buildbucket for the build status
[18:19:12.725] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:12.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:73:0) after 1m0s
[18:20:12.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:73:0)
[18:20:12.758] Timer tick, asking Buildbucket for the build status
[18:20:12.819] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:12.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:74:0) after 1m0s
[18:21:12.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:74:0)
[18:21:12.846] Timer tick, asking Buildbucket for the build status
[18:21:12.939] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:12.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:75:0) after 1m0s
[18:22:12.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:75:0)
[18:22:12.963] Timer tick, asking Buildbucket for the build status
[18:22:13.044] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:13.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:76:0) after 1m0s
[18:23:13.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:76:0)
[18:23:13.055] Timer tick, asking Buildbucket for the build status
[18:23:13.127] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:13.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:77:0) after 1m0s
[18:24:13.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:77:0)
[18:24:13.175] Timer tick, asking Buildbucket for the build status
[18:24:13.688] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:13.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:78:0) after 1m0s
[18:25:13.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:78:0)
[18:25:13.791] Timer tick, asking Buildbucket for the build status
[18:25:13.859] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:13.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:79:0) after 1m0s
[18:26:13.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:79:0)
[18:26:13.986] Timer tick, asking Buildbucket for the build status
[18:26:14.105] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:14.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:80:0) after 1m0s
[18:27:14.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:80:0)
[18:27:14.221] Timer tick, asking Buildbucket for the build status
[18:27:14.293] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:14.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:81:0) after 1m0s
[18:28:14.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:81:0)
[18:28:14.374] Timer tick, asking Buildbucket for the build status
[18:28:14.483] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:14.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:82:0) after 1m0s
[18:29:14.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:82:0)
[18:29:14.528] Timer tick, asking Buildbucket for the build status
[18:29:14.737] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:14.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:83:0) after 1m0s
[18:30:14.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:83:0)
[18:30:14.737] Timer tick, asking Buildbucket for the build status
[18:30:15.561] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:15.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:84:0) after 1m0s
[18:31:15.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:84:0)
[18:31:15.709] Timer tick, asking Buildbucket for the build status
[18:31:15.852] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:15.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:85:0) after 1m0s
[18:32:15.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:85:0)
[18:32:15.911] Timer tick, asking Buildbucket for the build status
[18:32:15.984] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:15.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:86:0) after 1m0s
[18:33:16.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:86:0)
[18:33:16.081] Timer tick, asking Buildbucket for the build status
[18:33:16.140] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:16.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:87:0) after 1m0s
[18:34:16.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:87:0)
[18:34:16.128] Timer tick, asking Buildbucket for the build status
[18:34:16.400] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:16.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:88:0) after 1m0s
[18:35:16.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:88:0)
[18:35:16.454] Timer tick, asking Buildbucket for the build status
[18:35:16.529] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:16.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:89:0) after 1m0s
[18:36:16.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:89:0)
[18:36:16.623] Timer tick, asking Buildbucket for the build status
[18:36:18.365] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:18.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:90:0) after 1m0s
[18:37:18.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:90:0)
[18:37:18.858] Timer tick, asking Buildbucket for the build status
[18:37:18.943] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:18.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:91:0) after 1m0s
[18:38:19.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:91:0)
[18:38:19.095] Timer tick, asking Buildbucket for the build status
[18:38:19.162] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:19.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:92:0) after 1m0s
[18:39:19.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:92:0)
[18:39:19.202] Timer tick, asking Buildbucket for the build status
[18:39:19.386] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:19.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:93:0) after 1m0s
[18:40:19.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:93:0)
[18:40:19.485] Timer tick, asking Buildbucket for the build status
[18:40:19.737] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:19.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:94:0) after 1m0s
[18:41:19.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:94:0)
[18:41:19.777] Timer tick, asking Buildbucket for the build status
[18:41:20.075] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:20.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:95:0) after 1m0s
[18:42:20.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:95:0)
[18:42:20.196] Timer tick, asking Buildbucket for the build status
[18:42:20.279] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:20.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:96:0) after 1m0s
[18:43:20.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:96:0)
[18:43:20.342] Timer tick, asking Buildbucket for the build status
[18:43:20.419] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:20.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:97:0) after 1m0s
[18:44:20.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:97:0)
[18:44:20.441] Timer tick, asking Buildbucket for the build status
[18:44:20.583] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:20.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:98:0) after 1m0s
[18:45:20.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:98:0)
[18:45:20.627] Timer tick, asking Buildbucket for the build status
[18:45:20.752] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:20.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:99:0) after 1m0s
[18:46:20.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:99:0)
[18:46:20.862] Timer tick, asking Buildbucket for the build status
[18:46:20.975] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:20.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:100:0) after 1m0s
[18:47:21.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:100:0)
[18:47:21.049] Timer tick, asking Buildbucket for the build status
[18:47:21.182] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:21.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:101:0) after 1m0s
[18:48:21.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:101:0)
[18:48:21.270] Timer tick, asking Buildbucket for the build status
[18:48:21.391] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:21.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:102:0) after 1m0s
[18:49:21.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:102:0)
[18:49:21.426] Timer tick, asking Buildbucket for the build status
[18:49:21.555] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:21.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:103:0) after 1m0s
[18:50:21.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:103:0)
[18:50:21.622] Timer tick, asking Buildbucket for the build status
[18:50:21.889] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:21.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:104:0) after 1m0s
[18:51:21.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:104:0)
[18:51:21.974] Timer tick, asking Buildbucket for the build status
[18:51:23.888] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:23.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:105:0) after 1m0s
[18:52:23.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:105:0)
[18:52:23.932] Timer tick, asking Buildbucket for the build status
[18:52:24.005] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:24.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:106:0) after 1m0s
[18:53:24.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:106:0)
[18:53:24.106] Timer tick, asking Buildbucket for the build status
[18:53:24.167] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:24.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:107:0) after 1m0s
[18:54:24.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:107:0)
[18:54:24.171] Timer tick, asking Buildbucket for the build status
[18:54:24.352] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:24.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:108:0) after 1m0s
[18:55:24.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:108:0)
[18:55:24.475] Timer tick, asking Buildbucket for the build status
[18:55:24.566] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:24.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:109:0) after 1m0s
[18:56:24.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:109:0)
[18:56:24.624] Timer tick, asking Buildbucket for the build status
[18:56:24.721] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:24.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:110:0) after 1m0s
[18:57:24.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:110:0)
[18:57:24.756] Timer tick, asking Buildbucket for the build status
[18:57:24.953] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:24.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:111:0) after 1m0s
[18:58:25.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:111:0)
[18:58:25.037] Timer tick, asking Buildbucket for the build status
[18:58:25.244] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:25.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:112:0) after 1m0s
[18:59:25.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:112:0)
[18:59:25.306] Timer tick, asking Buildbucket for the build status
[18:59:25.567] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:25.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:113:0) after 1m0s
[19:00:25.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:113:0)
[19:00:25.617] Timer tick, asking Buildbucket for the build status
[19:00:25.685] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:25.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:114:0) after 1m0s
[19:01:25.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:114:0)
[19:01:25.733] Timer tick, asking Buildbucket for the build status
[19:01:25.926] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:25.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:115:0) after 1m0s
[19:02:25.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:115:0)
[19:02:25.957] Timer tick, asking Buildbucket for the build status
[19:02:26.458] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:26.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:116:0) after 1m0s
[19:03:26.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:116:0)
[19:03:26.498] Timer tick, asking Buildbucket for the build status
[19:03:26.574] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:26.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:117:0) after 1m0s
[19:04:26.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:117:0)
[19:04:26.613] Timer tick, asking Buildbucket for the build status
[19:04:26.976] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:26.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:118:0) after 1m0s
[19:05:27.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:118:0)
[19:05:27.015] Timer tick, asking Buildbucket for the build status
[19:05:27.103] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:27.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:119:0) after 1m0s
[19:06:27.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:119:0)
[19:06:27.129] Timer tick, asking Buildbucket for the build status
[19:06:27.239] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:27.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:120:0) after 1m0s
[19:07:27.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:120:0)
[19:07:27.316] Timer tick, asking Buildbucket for the build status
[19:07:27.384] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:27.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:121:0) after 1m0s
[19:08:27.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:121:0)
[19:08:27.416] Timer tick, asking Buildbucket for the build status
[19:08:27.493] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:27.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:122:0) after 1m0s
[19:09:27.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:122:0)
[19:09:27.533] Timer tick, asking Buildbucket for the build status
[19:09:27.684] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:27.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:123:0) after 1m0s
[19:10:27.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:123:0)
[19:10:27.756] Timer tick, asking Buildbucket for the build status
[19:10:27.837] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:27.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:124:0) after 1m0s
[19:11:27.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:124:0)
[19:11:27.838] Timer tick, asking Buildbucket for the build status
[19:11:28.025] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:28.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:125:0) after 1m0s
[19:12:28.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:125:0)
[19:12:28.013] Timer tick, asking Buildbucket for the build status
[19:12:28.082] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:28.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:126:0) after 1m0s
[19:13:28.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:126:0)
[19:13:28.131] Timer tick, asking Buildbucket for the build status
[19:13:28.210] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:28.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:127:0) after 1m0s
[19:14:28.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:127:0)
[19:14:28.287] Timer tick, asking Buildbucket for the build status
[19:14:28.782] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:28.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:128:0) after 1m0s
[19:15:28.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:128:0)
[19:15:28.797] Timer tick, asking Buildbucket for the build status
[19:15:28.869] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:28.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:129:0) after 1m0s
[19:16:28.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:129:0)
[19:16:28.895] Timer tick, asking Buildbucket for the build status
[19:16:29.016] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:29.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:130:0) after 1m0s
[19:17:29.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:130:0)
[19:17:29.073] Timer tick, asking Buildbucket for the build status
[19:17:29.147] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:29.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:131:0) after 1m0s
[19:18:29.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:131:0)
[19:18:29.246] Timer tick, asking Buildbucket for the build status
[19:18:29.327] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:29.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:132:0) after 1m0s
[19:19:29.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:132:0)
[19:19:29.387] Timer tick, asking Buildbucket for the build status
[19:19:29.492] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:29.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:133:0) after 1m0s
[19:20:29.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:133:0)
[19:20:29.520] Timer tick, asking Buildbucket for the build status
[19:20:29.985] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:29.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:134:0) after 1m0s
[19:21:30.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:134:0)
[19:21:30.060] Timer tick, asking Buildbucket for the build status
[19:21:30.148] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:30.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:135:0) after 1m0s
[19:22:30.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:135:0)
[19:22:30.187] Timer tick, asking Buildbucket for the build status
[19:22:30.261] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:30.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:136:0) after 1m0s
[19:23:30.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:136:0)
[19:23:30.320] Timer tick, asking Buildbucket for the build status
[19:23:30.403] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:30.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:137:0) after 1m0s
[19:24:30.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:137:0)
[19:24:30.454] Timer tick, asking Buildbucket for the build status
[19:24:30.571] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:30.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:138:0) after 1m0s
[19:25:30.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:138:0)
[19:25:30.625] Timer tick, asking Buildbucket for the build status
[19:25:30.704] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:30.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:139:0) after 1m0s
[19:26:30.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:139:0)
[19:26:30.925] Timer tick, asking Buildbucket for the build status
[19:26:31.274] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:31.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:140:0) after 1m0s
[19:27:31.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:140:0)
[19:27:31.302] Timer tick, asking Buildbucket for the build status
[19:27:31.492] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:31.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:141:0) after 1m0s
[19:28:31.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:141:0)
[19:28:31.523] Timer tick, asking Buildbucket for the build status
[19:28:31.799] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:31.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:142:0) after 1m0s
[19:29:31.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:142:0)
[19:29:31.816] Timer tick, asking Buildbucket for the build status
[19:29:31.922] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:31.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:143:0) after 1m0s
[19:30:32.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:143:0)
[19:30:32.016] Timer tick, asking Buildbucket for the build status
[19:30:32.120] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:32.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:144:0) after 1m0s
[19:31:32.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:144:0)
[19:31:32.221] Timer tick, asking Buildbucket for the build status
[19:31:32.292] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:32.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:145:0) after 1m0s
[19:32:32.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:145:0)
[19:32:32.326] Timer tick, asking Buildbucket for the build status
[19:32:32.705] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:32.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:146:0) after 1m0s
[19:33:32.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:146:0)
[19:33:32.840] Timer tick, asking Buildbucket for the build status
[19:33:33.491] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:33.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:147:0) after 1m0s
[19:34:33.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:147:0)
[19:34:33.515] Timer tick, asking Buildbucket for the build status
[19:34:33.744] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:33.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:148:0) after 1m0s
[19:35:33.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:148:0)
[19:35:33.816] Timer tick, asking Buildbucket for the build status
[19:35:34.212] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:34.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:149:0) after 1m0s
[19:36:34.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:149:0)
[19:36:34.233] Timer tick, asking Buildbucket for the build status
[19:36:34.315] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:34.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:150:0) after 1m0s
[19:37:34.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:150:0)
[19:37:34.447] Timer tick, asking Buildbucket for the build status
[19:37:34.694] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:34.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:151:0) after 1m0s
[19:38:34.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:151:0)
[19:38:34.794] Timer tick, asking Buildbucket for the build status
[19:38:34.862] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:34.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:152:0) after 1m0s
[19:39:35.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:152:0)
[19:39:35.013] Timer tick, asking Buildbucket for the build status
[19:39:35.328] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:35.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:153:0) after 1m0s
[19:40:35.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:153:0)
[19:40:35.433] Timer tick, asking Buildbucket for the build status
[19:40:35.523] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:35.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:154:0) after 1m0s
[19:41:35.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:154:0)
[19:41:35.606] Timer tick, asking Buildbucket for the build status
[19:41:35.906] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:35.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:155:0) after 1m0s
[19:42:36.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:155:0)
[19:42:36.063] Timer tick, asking Buildbucket for the build status
[19:42:36.132] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:36.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:156:0) after 1m0s
[19:43:36.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:156:0)
[19:43:36.222] Timer tick, asking Buildbucket for the build status
[19:43:36.305] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:36.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:157:0) after 1m0s
[19:44:36.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:157:0)
[19:44:36.339] Timer tick, asking Buildbucket for the build status
[19:44:36.823] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:36.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:158:0) after 1m0s
[19:45:36.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:158:0)
[19:45:36.860] Timer tick, asking Buildbucket for the build status
[19:45:36.978] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:36.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:159:0) after 1m0s
[19:46:37.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:159:0)
[19:46:37.019] Timer tick, asking Buildbucket for the build status
[19:46:37.122] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:37.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:160:0) after 1m0s
[19:47:37.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:160:0)
[19:47:37.192] Timer tick, asking Buildbucket for the build status
[19:47:37.282] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:37.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:161:0) after 1m0s
[19:48:37.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:161:0)
[19:48:37.325] Timer tick, asking Buildbucket for the build status
[19:48:37.430] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:37.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:162:0) after 1m0s
[19:49:37.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:162:0)
[19:49:37.489] Timer tick, asking Buildbucket for the build status
[19:49:37.611] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:37.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:163:0) after 1m0s
[19:50:37.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:163:0)
[19:50:37.736] Timer tick, asking Buildbucket for the build status
[19:50:40.812] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:40.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:164:0) after 1m0s
[19:51:40.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:164:0)
[19:51:40.810] Timer tick, asking Buildbucket for the build status
[19:51:40.888] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:40.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:165:0) after 1m0s
[19:52:40.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:165:0)
[19:52:40.982] Timer tick, asking Buildbucket for the build status
[19:52:41.038] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:41.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:166:0) after 1m0s
[19:53:41.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:166:0)
[19:53:41.271] Timer tick, asking Buildbucket for the build status
[19:53:41.347] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:41.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:167:0) after 1m0s
[19:54:41.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:167:0)
[19:54:41.476] Timer tick, asking Buildbucket for the build status
[19:54:42.045] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:42.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:168:0) after 1m0s
[19:55:42.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:168:0)
[19:55:42.123] Timer tick, asking Buildbucket for the build status
[19:55:42.201] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:42.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:169:0) after 1m0s
[19:56:42.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:169:0)
[19:56:42.278] Timer tick, asking Buildbucket for the build status
[19:56:42.366] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:42.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:170:0) after 1m0s
[19:57:42.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:170:0)
[19:57:42.412] Timer tick, asking Buildbucket for the build status
[19:57:42.609] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:42.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:171:0) after 1m0s
[19:58:42.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:171:0)
[19:58:42.683] Timer tick, asking Buildbucket for the build status
[19:58:42.748] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:42.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:172:0) after 1m0s
[19:59:42.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:172:0)
[19:59:42.829] Timer tick, asking Buildbucket for the build status
[19:59:43.144] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:43.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:173:0) after 1m0s
[20:00:43.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:173:0)
[20:00:43.203] Timer tick, asking Buildbucket for the build status
[20:00:43.907] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:43.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:174:0) after 1m0s
[20:01:44.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:174:0)
[20:01:44.046] Timer tick, asking Buildbucket for the build status
[20:01:44.114] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:44.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:175:0) after 1m0s
[20:02:44.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:175:0)
[20:02:44.225] Timer tick, asking Buildbucket for the build status
[20:02:44.417] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:44.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:176:0) after 1m0s
[20:03:44.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:176:0)
[20:03:44.594] Timer tick, asking Buildbucket for the build status
[20:03:44.747] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:44.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:177:0) after 1m0s
[20:04:44.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:177:0)
[20:04:44.777] Timer tick, asking Buildbucket for the build status
[20:04:45.203] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:45.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:178:0) after 1m0s
[20:05:45.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:178:0)
[20:05:45.291] Timer tick, asking Buildbucket for the build status
[20:05:45.522] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:45.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:179:0) after 1m0s
[20:06:45.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:179:0)
[20:06:45.511] Timer tick, asking Buildbucket for the build status
[20:06:45.634] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:45.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:180:0) after 1m0s
[20:07:45.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:180:0)
[20:07:45.850] Timer tick, asking Buildbucket for the build status
[20:07:45.928] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:45.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:181:0) after 1m0s
[20:08:46.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:181:0)
[20:08:46.183] Timer tick, asking Buildbucket for the build status
[20:08:46.751] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:46.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:182:0) after 1m0s
[20:09:46.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:182:0)
[20:09:46.856] Timer tick, asking Buildbucket for the build status
[20:09:47.027] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:47.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:183:0) after 1m0s
[20:10:47.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:183:0)
[20:10:47.074] Timer tick, asking Buildbucket for the build status
[20:10:47.153] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:47.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:184:0) after 1m0s
[20:11:47.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:184:0)
[20:11:47.261] Timer tick, asking Buildbucket for the build status
[20:11:47.338] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:47.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:185:0) after 1m0s
[20:12:47.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:185:0)
[20:12:47.398] Timer tick, asking Buildbucket for the build status
[20:12:47.547] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:47.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:186:0) after 1m0s
[20:13:47.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:186:0)
[20:13:47.613] Timer tick, asking Buildbucket for the build status
[20:13:47.726] Build 8870794519888883904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:47.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036224582916659344:187:0) after 1m0s
[20:14:22.620] Received PubSub notification, asking Buildbucket for the build status
[20:14:22.679] Build 8870794519888883904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:14:22.679] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1598559261442124",
    "created_by": "project:infra",
    "created_ts": "1598548122472497",
    "id": "8870794519888883904",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4246.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"771f3b7f-92a9-448f-bacc-ddcffe0a2f4b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4246.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-i3k5\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1598548126763289",
    "status": "COMPLETED",
    "status_changed_ts": "1598559262212865",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036224582916659344",
      "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/8870794519888883904/+/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:4e49b626269f2e10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1598559262226423",
    "url": "https://ci.chromium.org/b/8870794519888883904",
    "utcnow_ts": "1598559262671173"
  }
}
[20:14:22.679] Invocation finished in 3h5m42.477275545s with status SUCCEEDED