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

• parent_buildername:publish_tarball
• user_agent:recipe

[12:35:56.459] New invocation is queued and will start shortly
[12:35:56.459] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:35:57.571] Starting the invocation (attempt 1)
[12:35:57.649] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:35:57.649] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:35:57.649] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9052096210687998448",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"82.0.4078.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"c60d168f-2da8-421e-85a1-2eddb5b79979\",\"title\":\"publish_tarball/0\"}]},\"version\":\"82.0.4078.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:9052096210687998448",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:35:58.315] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1583411757740172",
    "id": "8886666148673896368",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4078.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c60d168f-2da8-421e-85a1-2eddb5b79979\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4078.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1583411757915464",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9052096210687998448",
      "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/8886666148673896368/+/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": "1583411757915568",
    "url": "https://ci.chromium.org/b/8886666148673896368",
    "utcnow_ts": "1583411758307182"
  }
}
[12:35:58.315] Task URL: https://ci.chromium.org/b/8886666148673896368
[12:35:58.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:2:0) after 1m0s
[12:36:58.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:2:0)
[12:36:58.339] Timer tick, asking Buildbucket for the build status
[12:36:58.444] Build 8886666148673896368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:36:58.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:3:0) after 1m0s
[12:37:58.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:3:0)
[12:37:58.464] Timer tick, asking Buildbucket for the build status
[12:37:58.876] Build 8886666148673896368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:37:58.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:4:0) after 1m0s
[12:38:58.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:4:0)
[12:38:58.931] Timer tick, asking Buildbucket for the build status
[12:38:59.049] Build 8886666148673896368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:59.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:5:0) after 1m0s
[12:39:23.006] Received PubSub notification, asking Buildbucket for the build status
[12:39:23.190] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:59.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:5:0)
[12:39:59.193] Timer tick, asking Buildbucket for the build status
[12:39:59.510] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:59.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:7:0) after 1m0s
[12:40:59.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:7:0)
[12:40:59.572] Timer tick, asking Buildbucket for the build status
[12:40:59.648] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:59.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:8:0) after 1m0s
[12:41:59.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:8:0)
[12:41:59.659] Timer tick, asking Buildbucket for the build status
[12:41:59.735] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:59.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:9:0) after 1m0s
[12:42:59.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:9:0)
[12:42:59.767] Timer tick, asking Buildbucket for the build status
[12:42:59.990] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:59.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:10:0) after 1m0s
[12:44:00.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:10:0)
[12:44:00.016] Timer tick, asking Buildbucket for the build status
[12:44:00.344] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:00.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:11:0) after 1m0s
[12:45:00.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:11:0)
[12:45:00.316] Timer tick, asking Buildbucket for the build status
[12:45:00.705] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:00.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:12:0) after 1m0s
[12:46:00.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:12:0)
[12:46:00.774] Timer tick, asking Buildbucket for the build status
[12:46:00.981] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:00.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:13:0) after 1m0s
[12:47:01.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:13:0)
[12:47:01.033] Timer tick, asking Buildbucket for the build status
[12:47:01.143] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:01.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:14:0) after 1m0s
[12:48:01.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:14:0)
[12:48:01.197] Timer tick, asking Buildbucket for the build status
[12:48:01.419] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:01.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:15:0) after 1m0s
[12:49:01.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:15:0)
[12:49:01.440] Timer tick, asking Buildbucket for the build status
[12:49:01.782] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:01.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:16:0) after 1m0s
[12:50:01.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:16:0)
[12:50:01.806] Timer tick, asking Buildbucket for the build status
[12:50:02.167] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:02.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:17:0) after 1m0s
[12:51:02.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:17:0)
[12:51:02.280] Timer tick, asking Buildbucket for the build status
[12:51:02.631] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:02.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:18:0) after 1m0s
[12:52:02.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:18:0)
[12:52:02.664] Timer tick, asking Buildbucket for the build status
[12:52:02.762] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:02.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:19:0) after 1m0s
[12:53:02.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:19:0)
[12:53:02.765] Timer tick, asking Buildbucket for the build status
[12:53:02.880] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:02.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:20:0) after 1m0s
[12:54:02.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:20:0)
[12:54:02.954] Timer tick, asking Buildbucket for the build status
[12:54:03.249] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:03.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:21:0) after 1m0s
[12:55:03.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:21:0)
[12:55:03.273] Timer tick, asking Buildbucket for the build status
[12:55:03.326] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:03.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:22:0) after 1m0s
[12:56:03.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:22:0)
[12:56:03.359] Timer tick, asking Buildbucket for the build status
[12:56:03.485] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:03.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:23:0) after 1m0s
[12:57:03.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:23:0)
[12:57:03.556] Timer tick, asking Buildbucket for the build status
[12:57:03.833] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:03.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:24:0) after 1m0s
[12:58:03.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:24:0)
[12:58:03.863] Timer tick, asking Buildbucket for the build status
[12:58:03.990] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:03.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:25:0) after 1m0s
[12:59:03.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:25:0)
[12:59:03.977] Timer tick, asking Buildbucket for the build status
[12:59:04.304] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:04.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:26:0) after 1m0s
[13:00:04.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:26:0)
[13:00:04.375] Timer tick, asking Buildbucket for the build status
[13:00:04.799] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:04.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:27:0) after 1m0s
[13:01:04.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:27:0)
[13:01:04.861] Timer tick, asking Buildbucket for the build status
[13:01:05.050] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:05.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:28:0) after 1m0s
[13:02:05.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:28:0)
[13:02:05.417] Timer tick, asking Buildbucket for the build status
[13:02:05.476] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:05.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:29:0) after 1m0s
[13:03:05.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:29:0)
[13:03:05.579] Timer tick, asking Buildbucket for the build status
[13:03:05.983] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:05.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:30:0) after 1m0s
[13:04:06.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:30:0)
[13:04:06.104] Timer tick, asking Buildbucket for the build status
[13:04:06.257] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:06.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:31:0) after 1m0s
[13:05:06.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:31:0)
[13:05:06.366] Timer tick, asking Buildbucket for the build status
[13:05:06.460] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:06.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:32:0) after 1m0s
[13:06:06.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:32:0)
[13:06:06.709] Timer tick, asking Buildbucket for the build status
[13:06:06.854] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:06.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:33:0) after 1m0s
[13:07:06.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:33:0)
[13:07:06.909] Timer tick, asking Buildbucket for the build status
[13:07:07.031] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:07.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:34:0) after 1m0s
[13:08:07.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:34:0)
[13:08:07.077] Timer tick, asking Buildbucket for the build status
[13:08:07.316] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:07.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:35:0) after 1m0s
[13:09:07.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:35:0)
[13:09:07.408] Timer tick, asking Buildbucket for the build status
[13:09:07.873] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:07.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:36:0) after 1m0s
[13:10:07.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:36:0)
[13:10:07.899] Timer tick, asking Buildbucket for the build status
[13:10:07.999] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:07.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:37:0) after 1m0s
[13:11:08.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:37:0)
[13:11:08.101] Timer tick, asking Buildbucket for the build status
[13:11:09.444] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:09.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:38:0) after 1m0s
[13:12:09.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:38:0)
[13:12:09.506] Timer tick, asking Buildbucket for the build status
[13:12:09.635] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:09.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:39:0) after 1m0s
[13:13:09.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:39:0)
[13:13:09.766] Timer tick, asking Buildbucket for the build status
[13:13:09.863] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:09.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:40:0) after 1m0s
[13:14:09.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:40:0)
[13:14:09.907] Timer tick, asking Buildbucket for the build status
[13:14:10.023] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:10.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:41:0) after 1m0s
[13:15:10.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:41:0)
[13:15:10.337] Timer tick, asking Buildbucket for the build status
[13:15:10.574] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:10.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:42:0) after 1m0s
[13:16:10.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:42:0)
[13:16:10.650] Timer tick, asking Buildbucket for the build status
[13:16:11.055] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:11.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:43:0) after 1m0s
[13:17:11.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:43:0)
[13:17:11.092] Timer tick, asking Buildbucket for the build status
[13:17:11.322] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:11.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:44:0) after 1m0s
[13:18:11.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:44:0)
[13:18:11.565] Timer tick, asking Buildbucket for the build status
[13:18:11.899] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:11.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:45:0) after 1m0s
[13:19:12.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:45:0)
[13:19:12.196] Timer tick, asking Buildbucket for the build status
[13:19:12.552] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:12.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:46:0) after 1m0s
[13:20:12.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:46:0)
[13:20:12.757] Timer tick, asking Buildbucket for the build status
[13:20:12.894] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:12.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:47:0) after 1m0s
[13:21:12.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:47:0)
[13:21:12.911] Timer tick, asking Buildbucket for the build status
[13:21:13.456] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:13.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:48:0) after 1m0s
[13:22:13.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:48:0)
[13:22:13.924] Timer tick, asking Buildbucket for the build status
[13:22:15.105] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:15.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:49:0) after 1m0s
[13:23:15.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:49:0)
[13:23:15.428] Timer tick, asking Buildbucket for the build status
[13:23:15.540] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:15.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:50:0) after 1m0s
[13:24:15.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:50:0)
[13:24:15.686] Timer tick, asking Buildbucket for the build status
[13:24:15.788] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:15.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:51:0) after 1m0s
[13:25:15.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:51:0)
[13:25:15.841] Timer tick, asking Buildbucket for the build status
[13:25:16.335] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:16.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:52:0) after 1m0s
[13:26:16.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:52:0)
[13:26:16.443] Timer tick, asking Buildbucket for the build status
[13:26:16.559] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:16.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:53:0) after 1m0s
[13:27:16.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:53:0)
[13:27:16.972] Timer tick, asking Buildbucket for the build status
[13:27:17.095] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:17.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:54:0) after 1m0s
[13:28:17.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:54:0)
[13:28:17.294] Timer tick, asking Buildbucket for the build status
[13:28:17.378] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:17.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:55:0) after 1m0s
[13:29:17.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:55:0)
[13:29:17.533] Timer tick, asking Buildbucket for the build status
[13:29:17.641] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:17.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:56:0) after 1m0s
[13:30:17.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:56:0)
[13:30:17.852] Timer tick, asking Buildbucket for the build status
[13:30:18.066] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:18.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:57:0) after 1m0s
[13:31:18.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:57:0)
[13:31:18.297] Timer tick, asking Buildbucket for the build status
[13:31:18.546] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:18.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:58:0) after 1m0s
[13:32:18.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:58:0)
[13:32:18.772] Timer tick, asking Buildbucket for the build status
[13:32:18.912] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:18.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:59:0) after 1m0s
[13:33:19.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:59:0)
[13:33:19.177] Timer tick, asking Buildbucket for the build status
[13:33:19.541] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:19.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:60:0) after 1m0s
[13:34:19.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:60:0)
[13:34:19.596] Timer tick, asking Buildbucket for the build status
[13:34:20.001] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:20.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:61:0) after 1m0s
[13:35:20.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:61:0)
[13:35:20.015] Timer tick, asking Buildbucket for the build status
[13:35:20.369] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:20.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:62:0) after 1m0s
[13:36:20.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:62:0)
[13:36:20.396] Timer tick, asking Buildbucket for the build status
[13:36:20.950] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:20.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:63:0) after 1m0s
[13:37:21.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:63:0)
[13:37:21.000] Timer tick, asking Buildbucket for the build status
[13:37:21.139] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:21.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:64:0) after 1m0s
[13:38:21.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:64:0)
[13:38:21.241] Timer tick, asking Buildbucket for the build status
[13:38:21.570] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:21.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:65:0) after 1m0s
[13:39:21.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:65:0)
[13:39:21.614] Timer tick, asking Buildbucket for the build status
[13:39:22.210] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:22.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:66:0) after 1m0s
[13:40:22.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:66:0)
[13:40:22.245] Timer tick, asking Buildbucket for the build status
[13:40:22.337] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:22.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:67:0) after 1m0s
[13:41:22.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:67:0)
[13:41:22.504] Timer tick, asking Buildbucket for the build status
[13:41:22.786] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:22.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:68:0) after 1m0s
[13:42:22.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:68:0)
[13:42:22.809] Timer tick, asking Buildbucket for the build status
[13:42:22.957] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:22.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:69:0) after 1m0s
[13:43:23.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:69:0)
[13:43:23.086] Timer tick, asking Buildbucket for the build status
[13:43:23.773] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:23.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:70:0) after 1m0s
[13:44:23.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:70:0)
[13:44:23.864] Timer tick, asking Buildbucket for the build status
[13:44:23.957] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:23.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:71:0) after 1m0s
[13:45:23.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:71:0)
[13:45:23.990] Timer tick, asking Buildbucket for the build status
[13:45:24.227] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:24.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:72:0) after 1m0s
[13:46:24.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:72:0)
[13:46:24.264] Timer tick, asking Buildbucket for the build status
[13:46:24.546] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:24.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:73:0) after 1m0s
[13:47:24.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:73:0)
[13:47:24.616] Timer tick, asking Buildbucket for the build status
[13:47:24.952] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:24.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:74:0) after 1m0s
[13:48:24.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:74:0)
[13:48:24.959] Timer tick, asking Buildbucket for the build status
[13:48:25.102] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:25.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:75:0) after 1m0s
[13:49:25.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:75:0)
[13:49:25.188] Timer tick, asking Buildbucket for the build status
[13:49:25.611] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:25.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:76:0) after 1m0s
[13:50:25.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:76:0)
[13:50:25.750] Timer tick, asking Buildbucket for the build status
[13:50:25.866] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:25.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:77:0) after 1m0s
[13:51:25.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:77:0)
[13:51:25.907] Timer tick, asking Buildbucket for the build status
[13:51:26.018] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:26.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:78:0) after 1m0s
[13:52:26.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:78:0)
[13:52:26.204] Timer tick, asking Buildbucket for the build status
[13:52:26.319] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:26.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:79:0) after 1m0s
[13:53:26.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:79:0)
[13:53:26.440] Timer tick, asking Buildbucket for the build status
[13:53:26.553] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:26.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:80:0) after 1m0s
[13:54:26.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:80:0)
[13:54:26.603] Timer tick, asking Buildbucket for the build status
[13:54:26.700] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:26.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:81:0) after 1m0s
[13:55:26.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:81:0)
[13:55:26.807] Timer tick, asking Buildbucket for the build status
[13:55:26.901] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:26.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:82:0) after 1m0s
[13:56:27.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:82:0)
[13:56:27.170] Timer tick, asking Buildbucket for the build status
[13:56:27.399] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:27.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:83:0) after 1m0s
[13:57:27.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:83:0)
[13:57:27.569] Timer tick, asking Buildbucket for the build status
[13:57:27.916] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:27.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:84:0) after 1m0s
[13:58:29.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:84:0)
[13:58:29.205] Timer tick, asking Buildbucket for the build status
[13:58:29.312] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:29.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:85:0) after 1m0s
[13:59:29.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:85:0)
[13:59:29.831] Timer tick, asking Buildbucket for the build status
[13:59:29.942] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:29.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:86:0) after 1m0s
[14:00:30.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:86:0)
[14:00:30.099] Timer tick, asking Buildbucket for the build status
[14:00:30.259] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:30.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:87:0) after 1m0s
[14:01:30.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:87:0)
[14:01:30.601] Timer tick, asking Buildbucket for the build status
[14:01:31.691] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:31.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:88:0) after 1m0s
[14:02:31.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:88:0)
[14:02:31.714] Timer tick, asking Buildbucket for the build status
[14:02:31.949] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:31.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:89:0) after 1m0s
[14:03:32.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:89:0)
[14:03:32.000] Timer tick, asking Buildbucket for the build status
[14:03:32.261] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:32.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:90:0) after 1m0s
[14:04:32.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:90:0)
[14:04:32.251] Timer tick, asking Buildbucket for the build status
[14:04:32.464] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:32.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:91:0) after 1m0s
[14:05:32.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:91:0)
[14:05:32.506] Timer tick, asking Buildbucket for the build status
[14:05:32.642] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:32.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:92:0) after 1m0s
[14:06:32.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:92:0)
[14:06:32.715] Timer tick, asking Buildbucket for the build status
[14:06:32.833] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:32.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:93:0) after 1m0s
[14:07:32.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:93:0)
[14:07:32.874] Timer tick, asking Buildbucket for the build status
[14:07:33.017] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:33.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:94:0) after 1m0s
[14:08:33.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:94:0)
[14:08:33.039] Timer tick, asking Buildbucket for the build status
[14:08:33.176] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:33.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:95:0) after 1m0s
[14:09:33.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:95:0)
[14:09:33.198] Timer tick, asking Buildbucket for the build status
[14:09:33.661] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:33.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:96:0) after 1m0s
[14:10:33.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:96:0)
[14:10:33.668] Timer tick, asking Buildbucket for the build status
[14:10:33.785] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:33.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:97:0) after 1m0s
[14:11:33.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:97:0)
[14:11:33.812] Timer tick, asking Buildbucket for the build status
[14:11:33.983] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:33.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:98:0) after 1m0s
[14:12:34.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:98:0)
[14:12:34.069] Timer tick, asking Buildbucket for the build status
[14:12:34.260] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:34.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:99:0) after 1m0s
[14:13:34.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:99:0)
[14:13:34.283] Timer tick, asking Buildbucket for the build status
[14:13:34.392] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:34.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:100:0) after 1m0s
[14:14:34.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:100:0)
[14:14:34.518] Timer tick, asking Buildbucket for the build status
[14:14:34.609] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:34.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:101:0) after 1m0s
[14:15:34.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:101:0)
[14:15:34.627] Timer tick, asking Buildbucket for the build status
[14:15:35.013] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:35.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:102:0) after 1m0s
[14:16:35.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:102:0)
[14:16:35.098] Timer tick, asking Buildbucket for the build status
[14:16:35.226] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:35.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:103:0) after 1m0s
[14:17:35.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:103:0)
[14:17:35.247] Timer tick, asking Buildbucket for the build status
[14:17:35.380] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:35.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:104:0) after 1m0s
[14:18:35.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:104:0)
[14:18:35.430] Timer tick, asking Buildbucket for the build status
[14:18:35.851] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:35.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:105:0) after 1m0s
[14:19:35.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:105:0)
[14:19:35.882] Timer tick, asking Buildbucket for the build status
[14:19:36.009] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:36.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:106:0) after 1m0s
[14:20:36.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:106:0)
[14:20:36.020] Timer tick, asking Buildbucket for the build status
[14:20:36.172] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:36.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:107:0) after 1m0s
[14:21:36.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:107:0)
[14:21:36.309] Timer tick, asking Buildbucket for the build status
[14:21:36.803] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:36.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:108:0) after 1m0s
[14:22:36.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:108:0)
[14:22:36.913] Timer tick, asking Buildbucket for the build status
[14:22:37.025] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:37.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:109:0) after 1m0s
[14:23:37.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:109:0)
[14:23:37.128] Timer tick, asking Buildbucket for the build status
[14:23:37.239] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:37.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:110:0) after 1m0s
[14:24:37.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:110:0)
[14:24:37.294] Timer tick, asking Buildbucket for the build status
[14:24:37.400] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:37.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:111:0) after 1m0s
[14:25:37.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:111:0)
[14:25:37.432] Timer tick, asking Buildbucket for the build status
[14:25:37.791] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:37.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:112:0) after 1m0s
[14:26:37.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:112:0)
[14:26:37.897] Timer tick, asking Buildbucket for the build status
[14:26:38.213] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:38.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:113:0) after 1m0s
[14:27:38.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:113:0)
[14:27:38.331] Timer tick, asking Buildbucket for the build status
[14:27:38.443] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:38.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:114:0) after 1m0s
[14:28:38.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:114:0)
[14:28:38.522] Timer tick, asking Buildbucket for the build status
[14:28:38.758] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:38.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:115:0) after 1m0s
[14:29:38.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:115:0)
[14:29:38.909] Timer tick, asking Buildbucket for the build status
[14:29:39.116] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:39.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:116:0) after 1m0s
[14:30:39.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:116:0)
[14:30:39.168] Timer tick, asking Buildbucket for the build status
[14:30:39.579] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:39.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:117:0) after 1m0s
[14:31:39.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:117:0)
[14:31:39.621] Timer tick, asking Buildbucket for the build status
[14:31:39.909] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:39.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:118:0) after 1m0s
[14:32:40.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:118:0)
[14:32:40.086] Timer tick, asking Buildbucket for the build status
[14:32:40.301] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:40.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:119:0) after 1m0s
[14:33:40.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:119:0)
[14:33:40.293] Timer tick, asking Buildbucket for the build status
[14:33:40.408] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:40.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:120:0) after 1m0s
[14:34:40.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:120:0)
[14:34:40.529] Timer tick, asking Buildbucket for the build status
[14:34:40.837] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:40.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:121:0) after 1m0s
[14:35:40.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:121:0)
[14:35:40.879] Timer tick, asking Buildbucket for the build status
[14:35:41.029] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:41.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:122:0) after 1m0s
[14:36:41.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:122:0)
[14:36:41.222] Timer tick, asking Buildbucket for the build status
[14:36:41.571] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:41.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:123:0) after 1m0s
[14:37:41.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:123:0)
[14:37:41.600] Timer tick, asking Buildbucket for the build status
[14:37:41.719] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:41.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:124:0) after 1m0s
[14:38:41.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:124:0)
[14:38:41.778] Timer tick, asking Buildbucket for the build status
[14:38:42.002] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:42.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:125:0) after 1m0s
[14:39:42.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:125:0)
[14:39:42.016] Timer tick, asking Buildbucket for the build status
[14:39:42.264] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:42.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:126:0) after 1m0s
[14:40:42.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:126:0)
[14:40:42.344] Timer tick, asking Buildbucket for the build status
[14:40:42.460] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:42.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:127:0) after 1m0s
[14:41:42.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:127:0)
[14:41:42.443] Timer tick, asking Buildbucket for the build status
[14:41:42.871] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:42.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:128:0) after 1m0s
[14:42:42.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:128:0)
[14:42:42.894] Timer tick, asking Buildbucket for the build status
[14:42:43.028] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:43.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:129:0) after 1m0s
[14:43:43.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:129:0)
[14:43:43.127] Timer tick, asking Buildbucket for the build status
[14:43:43.240] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:43.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:130:0) after 1m0s
[14:44:43.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:130:0)
[14:44:43.327] Timer tick, asking Buildbucket for the build status
[14:44:43.471] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:43.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:131:0) after 1m0s
[14:45:43.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:131:0)
[14:45:43.556] Timer tick, asking Buildbucket for the build status
[14:45:43.843] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:43.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:132:0) after 1m0s
[14:46:43.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:132:0)
[14:46:43.907] Timer tick, asking Buildbucket for the build status
[14:46:44.009] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:44.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:133:0) after 1m0s
[14:47:44.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:133:0)
[14:47:44.035] Timer tick, asking Buildbucket for the build status
[14:47:44.380] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:44.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:134:0) after 1m0s
[14:48:44.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:134:0)
[14:48:44.594] Timer tick, asking Buildbucket for the build status
[14:48:44.772] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:44.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:135:0) after 1m0s
[14:49:44.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:135:0)
[14:49:44.796] Timer tick, asking Buildbucket for the build status
[14:49:45.113] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:45.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:136:0) after 1m0s
[14:50:45.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:136:0)
[14:50:45.171] Timer tick, asking Buildbucket for the build status
[14:50:45.277] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:45.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:137:0) after 1m0s
[14:51:45.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:137:0)
[14:51:45.357] Timer tick, asking Buildbucket for the build status
[14:51:45.662] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:45.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:138:0) after 1m0s
[14:52:45.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:138:0)
[14:52:45.763] Timer tick, asking Buildbucket for the build status
[14:52:45.892] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:45.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:139:0) after 1m0s
[14:53:45.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:139:0)
[14:53:45.969] Timer tick, asking Buildbucket for the build status
[14:53:46.073] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:46.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:140:0) after 1m0s
[14:54:46.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:140:0)
[14:54:46.167] Timer tick, asking Buildbucket for the build status
[14:54:46.546] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:46.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:141:0) after 1m0s
[14:55:46.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:141:0)
[14:55:46.656] Timer tick, asking Buildbucket for the build status
[14:55:46.740] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:46.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:142:0) after 1m0s
[14:56:46.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:142:0)
[14:56:46.777] Timer tick, asking Buildbucket for the build status
[14:56:47.117] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:47.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:143:0) after 1m0s
[14:57:47.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:143:0)
[14:57:47.172] Timer tick, asking Buildbucket for the build status
[14:57:47.275] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:47.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:144:0) after 1m0s
[14:58:47.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:144:0)
[14:58:47.339] Timer tick, asking Buildbucket for the build status
[14:58:47.907] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:47.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:145:0) after 1m0s
[14:59:47.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:145:0)
[14:59:47.910] Timer tick, asking Buildbucket for the build status
[14:59:48.120] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:48.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:146:0) after 1m0s
[15:00:48.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:146:0)
[15:00:48.208] Timer tick, asking Buildbucket for the build status
[15:00:48.313] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:48.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:147:0) after 1m0s
[15:01:48.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:147:0)
[15:01:48.410] Timer tick, asking Buildbucket for the build status
[15:01:48.600] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:48.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:148:0) after 1m0s
[15:02:48.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:148:0)
[15:02:48.589] Timer tick, asking Buildbucket for the build status
[15:02:48.848] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:48.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:149:0) after 1m0s
[15:03:48.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:149:0)
[15:03:48.980] Timer tick, asking Buildbucket for the build status
[15:03:49.282] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:49.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:150:0) after 1m0s
[15:04:49.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:150:0)
[15:04:49.293] Timer tick, asking Buildbucket for the build status
[15:04:49.666] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:49.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:151:0) after 1m0s
[15:05:49.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:151:0)
[15:05:49.717] Timer tick, asking Buildbucket for the build status
[15:05:49.828] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:49.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:152:0) after 1m0s
[15:06:49.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:152:0)
[15:06:49.854] Timer tick, asking Buildbucket for the build status
[15:06:49.972] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:49.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:153:0) after 1m0s
[15:07:50.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:153:0)
[15:07:50.086] Timer tick, asking Buildbucket for the build status
[15:07:50.336] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:50.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:154:0) after 1m0s
[15:08:50.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:154:0)
[15:08:50.445] Timer tick, asking Buildbucket for the build status
[15:08:50.567] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:50.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:155:0) after 1m0s
[15:09:50.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:155:0)
[15:09:50.603] Timer tick, asking Buildbucket for the build status
[15:09:50.791] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:50.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:156:0) after 1m0s
[15:10:50.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:156:0)
[15:10:50.854] Timer tick, asking Buildbucket for the build status
[15:10:50.942] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:50.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:157:0) after 1m0s
[15:11:51.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:157:0)
[15:11:51.021] Timer tick, asking Buildbucket for the build status
[15:11:51.239] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:51.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:158:0) after 1m0s
[15:12:51.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:158:0)
[15:12:51.292] Timer tick, asking Buildbucket for the build status
[15:12:51.420] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:51.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:159:0) after 1m0s
[15:13:51.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:159:0)
[15:13:51.493] Timer tick, asking Buildbucket for the build status
[15:13:51.619] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:51.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:160:0) after 1m0s
[15:14:51.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:160:0)
[15:14:51.653] Timer tick, asking Buildbucket for the build status
[15:14:52.311] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:52.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:161:0) after 1m0s
[15:15:52.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:161:0)
[15:15:52.320] Timer tick, asking Buildbucket for the build status
[15:15:52.492] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:52.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:162:0) after 1m0s
[15:16:52.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:162:0)
[15:16:52.553] Timer tick, asking Buildbucket for the build status
[15:16:52.681] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:52.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:163:0) after 1m0s
[15:17:52.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:163:0)
[15:17:52.701] Timer tick, asking Buildbucket for the build status
[15:17:52.824] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:52.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:164:0) after 1m0s
[15:18:52.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:164:0)
[15:18:52.861] Timer tick, asking Buildbucket for the build status
[15:18:52.987] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:52.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:165:0) after 1m0s
[15:19:52.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:165:0)
[15:19:52.998] Timer tick, asking Buildbucket for the build status
[15:19:53.101] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:53.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:166:0) after 1m0s
[15:20:53.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:166:0)
[15:20:53.162] Timer tick, asking Buildbucket for the build status
[15:20:53.317] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:53.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:167:0) after 1m0s
[15:21:53.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:167:0)
[15:21:53.343] Timer tick, asking Buildbucket for the build status
[15:21:53.525] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:53.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:168:0) after 1m0s
[15:22:53.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:168:0)
[15:22:53.567] Timer tick, asking Buildbucket for the build status
[15:22:53.700] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:53.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:169:0) after 1m0s
[15:23:53.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:169:0)
[15:23:53.733] Timer tick, asking Buildbucket for the build status
[15:23:53.978] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:53.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:170:0) after 1m0s
[15:24:54.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:170:0)
[15:24:54.088] Timer tick, asking Buildbucket for the build status
[15:24:54.373] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:54.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:171:0) after 1m0s
[15:25:54.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:171:0)
[15:25:54.408] Timer tick, asking Buildbucket for the build status
[15:25:54.511] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:54.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:172:0) after 1m0s
[15:26:54.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:172:0)
[15:26:54.564] Timer tick, asking Buildbucket for the build status
[15:26:54.654] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:54.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:173:0) after 1m0s
[15:27:54.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:173:0)
[15:27:54.788] Timer tick, asking Buildbucket for the build status
[15:27:55.298] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:55.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:174:0) after 1m0s
[15:28:55.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:174:0)
[15:28:55.370] Timer tick, asking Buildbucket for the build status
[15:28:55.464] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:55.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:175:0) after 1m0s
[15:29:55.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:175:0)
[15:29:55.558] Timer tick, asking Buildbucket for the build status
[15:29:55.895] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:55.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:176:0) after 1m0s
[15:30:55.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:176:0)
[15:30:55.954] Timer tick, asking Buildbucket for the build status
[15:30:56.366] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:56.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:177:0) after 1m0s
[15:31:56.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:177:0)
[15:31:56.466] Timer tick, asking Buildbucket for the build status
[15:31:56.686] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:56.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:178:0) after 1m0s
[15:32:56.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:178:0)
[15:32:56.711] Timer tick, asking Buildbucket for the build status
[15:32:57.101] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:57.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:179:0) after 1m0s
[15:33:57.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:179:0)
[15:33:57.152] Timer tick, asking Buildbucket for the build status
[15:33:57.234] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:57.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:180:0) after 1m0s
[15:34:57.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:180:0)
[15:34:57.332] Timer tick, asking Buildbucket for the build status
[15:34:57.436] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:57.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:181:0) after 1m0s
[15:35:57.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:181:0)
[15:35:57.442] Timer tick, asking Buildbucket for the build status
[15:35:57.647] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:57.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:182:0) after 1m0s
[15:36:57.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:182:0)
[15:36:57.718] Timer tick, asking Buildbucket for the build status
[15:36:58.062] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:58.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:183:0) after 1m0s
[15:37:58.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:183:0)
[15:37:58.165] Timer tick, asking Buildbucket for the build status
[15:37:58.296] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:58.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:184:0) after 1m0s
[15:38:58.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:184:0)
[15:38:58.334] Timer tick, asking Buildbucket for the build status
[15:38:58.446] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:58.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:185:0) after 1m0s
[15:39:58.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:185:0)
[15:39:58.513] Timer tick, asking Buildbucket for the build status
[15:39:58.646] Build 8886666148673896368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:58.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9052096210687998448:186:0) after 1m0s
[15:40:34.758] Received PubSub notification, asking Buildbucket for the build status
[15:40:34.883] Build 8886666148673896368: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[15:40:34.883] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "cancelation_reason": "TIMEOUT",
    "completed_ts": "1583422833204154",
    "created_by": "project:infra",
    "created_ts": "1583411757740172",
    "id": "8886666148673896368",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4078.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c60d168f-2da8-421e-85a1-2eddb5b79979\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4078.0\"}}",
    "project": "infra",
    "result": "CANCELED",
    "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\": \"82.0.4078.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c60d168f-2da8-421e-85a1-2eddb5b79979\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-skcl\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"82.0.4078.0\"}, \"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-skcl\"], \"image\": [\"chrome-xenial-20030100-2bddf837f90\"], \"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\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1583411962281781",
    "status": "COMPLETED",
    "status_changed_ts": "1583422833964044",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9052096210687998448",
      "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/8886666148673896368/+/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:4ac3838c98b78b10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1583422834243691",
    "url": "https://ci.chromium.org/b/8886666148673896368",
    "utcnow_ts": "1583422834874551"
  }
}
[15:40:34.884] Invocation finished in 3h4m38.447551801s with status FAILED