buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.infra.cron"
  builder: "Build From Tarball"
>

• publish_tarball/0 (5 years ago by chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com)

{
  "version": "88.0.4304.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[11:33:09.603] New invocation is queued and will start shortly
[11:33:09.603] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:33:10.697] Starting the invocation (attempt 1)
[11:33:10.732] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:33:10.732] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:33:10.732] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9030809873414358400",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4304.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"5a3ea796-fdd4-4ab5-94c2-9af19aefcd3d\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4304.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:9030809873414358400",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[11:33:11.147] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1603711990825798",
    "id": "8865379811466514896",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4304.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5a3ea796-fdd4-4ab5-94c2-9af19aefcd3d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4304.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1603711991007595",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9030809873414358400",
      "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/8865379811466514896/+/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": "1603711991007753",
    "url": "https://ci.chromium.org/b/8865379811466514896",
    "utcnow_ts": "1603711991138323"
  }
}
[11:33:11.147] Task URL: https://ci.chromium.org/b/8865379811466514896
[11:33:11.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:2:0) after 1m0s
[11:34:05.057] Received PubSub notification, asking Buildbucket for the build status
[11:34:05.426] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:11.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:2:0)
[11:34:11.141] Timer tick, asking Buildbucket for the build status
[11:34:11.259] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:11.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:4:0) after 1m0s
[11:35:11.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:4:0)
[11:35:11.322] Timer tick, asking Buildbucket for the build status
[11:35:11.522] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:11.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:5:0) after 1m0s
[11:36:11.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:5:0)
[11:36:11.579] Timer tick, asking Buildbucket for the build status
[11:36:11.660] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:11.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:6:0) after 1m0s
[11:37:11.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:6:0)
[11:37:11.722] Timer tick, asking Buildbucket for the build status
[11:37:11.842] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:11.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:7:0) after 1m0s
[11:38:11.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:7:0)
[11:38:11.862] Timer tick, asking Buildbucket for the build status
[11:38:11.944] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:11.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:8:0) after 1m0s
[11:39:11.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:8:0)
[11:39:11.971] Timer tick, asking Buildbucket for the build status
[11:39:12.086] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:12.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:9:0) after 1m0s
[11:40:12.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:9:0)
[11:40:12.137] Timer tick, asking Buildbucket for the build status
[11:40:12.242] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:12.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:10:0) after 1m0s
[11:41:12.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:10:0)
[11:41:12.437] Timer tick, asking Buildbucket for the build status
[11:41:12.545] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:12.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:11:0) after 1m0s
[11:42:12.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:11:0)
[11:42:12.664] Timer tick, asking Buildbucket for the build status
[11:42:12.741] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:12.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:12:0) after 1m0s
[11:43:12.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:12:0)
[11:43:12.924] Timer tick, asking Buildbucket for the build status
[11:43:13.057] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:13.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:13:0) after 1m0s
[11:44:13.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:13:0)
[11:44:13.163] Timer tick, asking Buildbucket for the build status
[11:44:13.476] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:13.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:14:0) after 1m0s
[11:45:13.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:14:0)
[11:45:13.563] Timer tick, asking Buildbucket for the build status
[11:45:13.827] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:13.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:15:0) after 1m0s
[11:46:13.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:15:0)
[11:46:13.851] Timer tick, asking Buildbucket for the build status
[11:46:13.934] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:13.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:16:0) after 1m0s
[11:47:13.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:16:0)
[11:47:13.955] Timer tick, asking Buildbucket for the build status
[11:47:14.120] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:14.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:17:0) after 1m0s
[11:48:14.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:17:0)
[11:48:14.174] Timer tick, asking Buildbucket for the build status
[11:48:14.380] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:14.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:18:0) after 1m0s
[11:49:14.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:18:0)
[11:49:14.434] Timer tick, asking Buildbucket for the build status
[11:49:14.503] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:14.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:19:0) after 1m0s
[11:50:14.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:19:0)
[11:50:14.529] Timer tick, asking Buildbucket for the build status
[11:50:14.603] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:14.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:20:0) after 1m0s
[11:51:14.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:20:0)
[11:51:14.656] Timer tick, asking Buildbucket for the build status
[11:51:14.864] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:14.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:21:0) after 1m0s
[11:52:14.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:21:0)
[11:52:14.923] Timer tick, asking Buildbucket for the build status
[11:52:15.112] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:15.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:22:0) after 1m0s
[11:53:15.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:22:0)
[11:53:15.244] Timer tick, asking Buildbucket for the build status
[11:53:15.353] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:15.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:23:0) after 1m0s
[11:54:15.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:23:0)
[11:54:15.487] Timer tick, asking Buildbucket for the build status
[11:54:15.565] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:15.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:24:0) after 1m0s
[11:55:15.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:24:0)
[11:55:15.676] Timer tick, asking Buildbucket for the build status
[11:55:15.767] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:15.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:25:0) after 1m0s
[11:56:15.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:25:0)
[11:56:15.784] Timer tick, asking Buildbucket for the build status
[11:56:15.857] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:15.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:26:0) after 1m0s
[11:57:15.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:26:0)
[11:57:15.916] Timer tick, asking Buildbucket for the build status
[11:57:16.312] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:16.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:27:0) after 1m0s
[11:58:17.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:27:0)
[11:58:17.566] Timer tick, asking Buildbucket for the build status
[11:58:17.698] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:17.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:28:0) after 1m0s
[11:59:18.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:28:0)
[11:59:18.192] Timer tick, asking Buildbucket for the build status
[11:59:18.259] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:18.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:29:0) after 1m0s
[12:00:18.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:29:0)
[12:00:18.412] Timer tick, asking Buildbucket for the build status
[12:00:18.507] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:18.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:30:0) after 1m0s
[12:01:20.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:30:0)
[12:01:20.463] Timer tick, asking Buildbucket for the build status
[12:01:20.550] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:20.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:31:0) after 1m0s
[12:02:20.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:31:0)
[12:02:20.590] Timer tick, asking Buildbucket for the build status
[12:02:20.675] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:20.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:32:0) after 1m0s
[12:03:20.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:32:0)
[12:03:20.739] Timer tick, asking Buildbucket for the build status
[12:03:21.009] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:21.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:33:0) after 1m0s
[12:04:21.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:33:0)
[12:04:21.075] Timer tick, asking Buildbucket for the build status
[12:04:21.140] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:21.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:34:0) after 1m0s
[12:05:21.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:34:0)
[12:05:21.165] Timer tick, asking Buildbucket for the build status
[12:05:21.408] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:21.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:35:0) after 1m0s
[12:06:21.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:35:0)
[12:06:21.477] Timer tick, asking Buildbucket for the build status
[12:06:21.546] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:21.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:36:0) after 1m0s
[12:07:21.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:36:0)
[12:07:21.623] Timer tick, asking Buildbucket for the build status
[12:07:21.708] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:21.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:37:0) after 1m0s
[12:08:21.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:37:0)
[12:08:21.836] Timer tick, asking Buildbucket for the build status
[12:08:21.983] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:21.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:38:0) after 1m0s
[12:09:22.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:38:0)
[12:09:22.074] Timer tick, asking Buildbucket for the build status
[12:09:22.149] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:22.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:39:0) after 1m0s
[12:10:22.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:39:0)
[12:10:22.175] Timer tick, asking Buildbucket for the build status
[12:10:22.445] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:22.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:40:0) after 1m0s
[12:11:22.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:40:0)
[12:11:22.554] Timer tick, asking Buildbucket for the build status
[12:11:22.633] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:22.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:41:0) after 1m0s
[12:12:22.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:41:0)
[12:12:22.769] Timer tick, asking Buildbucket for the build status
[12:12:22.853] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:22.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:42:0) after 1m0s
[12:13:22.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:42:0)
[12:13:22.833] Timer tick, asking Buildbucket for the build status
[12:13:22.958] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:22.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:43:0) after 1m0s
[12:14:23.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:43:0)
[12:14:23.021] Timer tick, asking Buildbucket for the build status
[12:14:23.116] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:23.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:44:0) after 1m0s
[12:15:23.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:44:0)
[12:15:23.143] Timer tick, asking Buildbucket for the build status
[12:15:23.214] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:23.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:45:0) after 1m0s
[12:16:23.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:45:0)
[12:16:23.293] Timer tick, asking Buildbucket for the build status
[12:16:23.359] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:23.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:46:0) after 1m0s
[12:17:23.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:46:0)
[12:17:23.443] Timer tick, asking Buildbucket for the build status
[12:17:23.552] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:23.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:47:0) after 1m0s
[12:18:24.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:47:0)
[12:18:24.256] Timer tick, asking Buildbucket for the build status
[12:18:24.365] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:24.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:48:0) after 1m0s
[12:19:24.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:48:0)
[12:19:24.436] Timer tick, asking Buildbucket for the build status
[12:19:24.501] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:24.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:49:0) after 1m0s
[12:20:24.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:49:0)
[12:20:24.603] Timer tick, asking Buildbucket for the build status
[12:20:24.700] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:24.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:50:0) after 1m0s
[12:21:24.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:50:0)
[12:21:24.932] Timer tick, asking Buildbucket for the build status
[12:21:24.999] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:24.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:51:0) after 1m0s
[12:22:25.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:51:0)
[12:22:25.046] Timer tick, asking Buildbucket for the build status
[12:22:25.116] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:25.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:52:0) after 1m0s
[12:23:25.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:52:0)
[12:23:25.203] Timer tick, asking Buildbucket for the build status
[12:23:25.347] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:25.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:53:0) after 1m0s
[12:24:25.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:53:0)
[12:24:25.337] Timer tick, asking Buildbucket for the build status
[12:24:25.404] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:25.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:54:0) after 1m0s
[12:25:27.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:54:0)
[12:25:27.338] Timer tick, asking Buildbucket for the build status
[12:25:27.612] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:27.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:55:0) after 1m0s
[12:26:27.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:55:0)
[12:26:27.931] Timer tick, asking Buildbucket for the build status
[12:26:28.001] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:28.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:56:0) after 1m0s
[12:27:28.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:56:0)
[12:27:28.158] Timer tick, asking Buildbucket for the build status
[12:27:28.228] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:28.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:57:0) after 1m0s
[12:28:28.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:57:0)
[12:28:28.343] Timer tick, asking Buildbucket for the build status
[12:28:28.463] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:28.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:58:0) after 1m0s
[12:29:28.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:58:0)
[12:29:28.527] Timer tick, asking Buildbucket for the build status
[12:29:28.597] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:28.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:59:0) after 1m0s
[12:30:28.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:59:0)
[12:30:28.705] Timer tick, asking Buildbucket for the build status
[12:30:28.763] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:28.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:60:0) after 1m0s
[12:31:29.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:60:0)
[12:31:29.086] Timer tick, asking Buildbucket for the build status
[12:31:29.307] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:29.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:61:0) after 1m0s
[12:32:29.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:61:0)
[12:32:29.352] Timer tick, asking Buildbucket for the build status
[12:32:29.410] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:29.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:62:0) after 1m0s
[12:33:44.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:62:0)
[12:33:44.584] Timer tick, asking Buildbucket for the build status
[12:33:44.793] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:44.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:63:0) after 1m0s
[12:34:44.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:63:0)
[12:34:44.871] Timer tick, asking Buildbucket for the build status
[12:34:44.947] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:44.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:64:0) after 1m0s
[12:35:45.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:64:0)
[12:35:45.018] Timer tick, asking Buildbucket for the build status
[12:35:45.208] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:45.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:65:0) after 1m0s
[12:36:45.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:65:0)
[12:36:45.310] Timer tick, asking Buildbucket for the build status
[12:36:45.392] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:45.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:66:0) after 1m0s
[12:37:45.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:66:0)
[12:37:45.484] Timer tick, asking Buildbucket for the build status
[12:37:45.609] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:45.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:67:0) after 1m0s
[12:38:45.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:67:0)
[12:38:45.641] Timer tick, asking Buildbucket for the build status
[12:38:45.749] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:45.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:68:0) after 1m0s
[12:39:45.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:68:0)
[12:39:45.731] Timer tick, asking Buildbucket for the build status
[12:39:45.886] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:45.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:69:0) after 1m0s
[12:40:45.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:69:0)
[12:40:45.911] Timer tick, asking Buildbucket for the build status
[12:40:46.228] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:46.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:70:0) after 1m0s
[12:41:46.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:70:0)
[12:41:46.248] Timer tick, asking Buildbucket for the build status
[12:41:46.308] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:46.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:71:0) after 1m0s
[12:42:46.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:71:0)
[12:42:46.314] Timer tick, asking Buildbucket for the build status
[12:42:46.873] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:46.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:72:0) after 1m0s
[12:43:46.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:72:0)
[12:43:46.895] Timer tick, asking Buildbucket for the build status
[12:43:46.992] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:46.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:73:0) after 1m0s
[12:44:47.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:73:0)
[12:44:47.042] Timer tick, asking Buildbucket for the build status
[12:44:47.129] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:47.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:74:0) after 1m0s
[12:45:47.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:74:0)
[12:45:47.156] Timer tick, asking Buildbucket for the build status
[12:45:47.229] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:47.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:75:0) after 1m0s
[12:46:47.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:75:0)
[12:46:47.258] Timer tick, asking Buildbucket for the build status
[12:46:47.349] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:47.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:76:0) after 1m0s
[12:47:47.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:76:0)
[12:47:47.495] Timer tick, asking Buildbucket for the build status
[12:47:47.578] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:47.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:77:0) after 1m0s
[12:48:47.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:77:0)
[12:48:47.710] Timer tick, asking Buildbucket for the build status
[12:48:47.813] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:47.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:78:0) after 1m0s
[12:49:47.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:78:0)
[12:49:47.807] Timer tick, asking Buildbucket for the build status
[12:49:47.879] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:47.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:79:0) after 1m0s
[12:50:47.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:79:0)
[12:50:47.931] Timer tick, asking Buildbucket for the build status
[12:50:48.020] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:48.020] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:80:0) after 1m0s
[12:51:48.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:80:0)
[12:51:48.158] Timer tick, asking Buildbucket for the build status
[12:51:48.229] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:48.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:81:0) after 1m0s
[12:52:48.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:81:0)
[12:52:48.317] Timer tick, asking Buildbucket for the build status
[12:52:48.394] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:48.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:82:0) after 1m0s
[12:53:48.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:82:0)
[12:53:48.441] Timer tick, asking Buildbucket for the build status
[12:53:48.553] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:48.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:83:0) after 1m0s
[12:54:48.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:83:0)
[12:54:48.581] Timer tick, asking Buildbucket for the build status
[12:54:48.748] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:48.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:84:0) after 1m0s
[12:55:48.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:84:0)
[12:55:48.757] Timer tick, asking Buildbucket for the build status
[12:55:48.813] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:48.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:85:0) after 1m0s
[12:56:48.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:85:0)
[12:56:48.834] Timer tick, asking Buildbucket for the build status
[12:56:48.918] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:48.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:86:0) after 1m0s
[12:57:48.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:86:0)
[12:57:48.984] Timer tick, asking Buildbucket for the build status
[12:57:49.065] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:49.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:87:0) after 1m0s
[12:58:49.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:87:0)
[12:58:49.134] Timer tick, asking Buildbucket for the build status
[12:58:49.243] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:49.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:88:0) after 1m0s
[12:59:49.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:88:0)
[12:59:49.339] Timer tick, asking Buildbucket for the build status
[12:59:49.400] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:49.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:89:0) after 1m0s
[13:00:49.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:89:0)
[13:00:49.482] Timer tick, asking Buildbucket for the build status
[13:00:49.627] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:49.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:90:0) after 1m0s
[13:01:49.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:90:0)
[13:01:49.754] Timer tick, asking Buildbucket for the build status
[13:01:49.903] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:49.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:91:0) after 1m0s
[13:02:49.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:91:0)
[13:02:49.988] Timer tick, asking Buildbucket for the build status
[13:02:50.097] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:50.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:92:0) after 1m0s
[13:03:50.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:92:0)
[13:03:50.199] Timer tick, asking Buildbucket for the build status
[13:03:50.311] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:50.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:93:0) after 1m0s
[13:04:50.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:93:0)
[13:04:50.432] Timer tick, asking Buildbucket for the build status
[13:04:50.538] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:50.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:94:0) after 1m0s
[13:05:50.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:94:0)
[13:05:50.656] Timer tick, asking Buildbucket for the build status
[13:05:50.779] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:50.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:95:0) after 1m0s
[13:06:50.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:95:0)
[13:06:50.963] Timer tick, asking Buildbucket for the build status
[13:06:51.082] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:51.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:96:0) after 1m0s
[13:07:51.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:96:0)
[13:07:51.110] Timer tick, asking Buildbucket for the build status
[13:07:51.376] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:51.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:97:0) after 1m0s
[13:08:51.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:97:0)
[13:08:51.402] Timer tick, asking Buildbucket for the build status
[13:08:51.937] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:51.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:98:0) after 1m0s
[13:09:51.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:98:0)
[13:09:51.968] Timer tick, asking Buildbucket for the build status
[13:09:52.046] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:52.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:99:0) after 1m0s
[13:10:52.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:99:0)
[13:10:52.163] Timer tick, asking Buildbucket for the build status
[13:10:52.254] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:52.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:100:0) after 1m0s
[13:11:52.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:100:0)
[13:11:52.263] Timer tick, asking Buildbucket for the build status
[13:11:52.325] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:52.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:101:0) after 1m0s
[13:12:52.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:101:0)
[13:12:52.424] Timer tick, asking Buildbucket for the build status
[13:12:52.487] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:52.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:102:0) after 1m0s
[13:13:52.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:102:0)
[13:13:52.593] Timer tick, asking Buildbucket for the build status
[13:13:52.746] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:52.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:103:0) after 1m0s
[13:14:52.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:103:0)
[13:14:52.793] Timer tick, asking Buildbucket for the build status
[13:14:52.875] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:52.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:104:0) after 1m0s
[13:15:52.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:104:0)
[13:15:52.940] Timer tick, asking Buildbucket for the build status
[13:15:53.204] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:53.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:105:0) after 1m0s
[13:16:53.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:105:0)
[13:16:53.226] Timer tick, asking Buildbucket for the build status
[13:16:53.305] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:53.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:106:0) after 1m0s
[13:17:53.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:106:0)
[13:17:53.441] Timer tick, asking Buildbucket for the build status
[13:17:53.584] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:53.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:107:0) after 1m0s
[13:18:53.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:107:0)
[13:18:53.604] Timer tick, asking Buildbucket for the build status
[13:18:53.744] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:53.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:108:0) after 1m0s
[13:19:53.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:108:0)
[13:19:53.795] Timer tick, asking Buildbucket for the build status
[13:19:53.864] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:53.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:109:0) after 1m0s
[13:20:53.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:109:0)
[13:20:53.909] Timer tick, asking Buildbucket for the build status
[13:20:53.972] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:53.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:110:0) after 1m0s
[13:21:53.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:110:0)
[13:21:53.995] Timer tick, asking Buildbucket for the build status
[13:21:54.055] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:54.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:111:0) after 1m0s
[13:22:54.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:111:0)
[13:22:54.131] Timer tick, asking Buildbucket for the build status
[13:22:54.203] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:54.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:112:0) after 1m0s
[13:23:54.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:112:0)
[13:23:54.242] Timer tick, asking Buildbucket for the build status
[13:23:54.360] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:54.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:113:0) after 1m0s
[13:24:54.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:113:0)
[13:24:54.417] Timer tick, asking Buildbucket for the build status
[13:24:54.472] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:54.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:114:0) after 1m0s
[13:25:54.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:114:0)
[13:25:54.496] Timer tick, asking Buildbucket for the build status
[13:25:54.840] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:54.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:115:0) after 1m0s
[13:26:54.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:115:0)
[13:26:54.925] Timer tick, asking Buildbucket for the build status
[13:26:55.068] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:55.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:116:0) after 1m0s
[13:27:55.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:116:0)
[13:27:55.097] Timer tick, asking Buildbucket for the build status
[13:27:55.178] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:55.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:117:0) after 1m0s
[13:28:55.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:117:0)
[13:28:55.197] Timer tick, asking Buildbucket for the build status
[13:28:55.315] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:55.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:118:0) after 1m0s
[13:29:55.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:118:0)
[13:29:55.367] Timer tick, asking Buildbucket for the build status
[13:29:55.485] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:55.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:119:0) after 1m0s
[13:30:55.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:119:0)
[13:30:55.550] Timer tick, asking Buildbucket for the build status
[13:30:55.622] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:55.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:120:0) after 1m0s
[13:31:56.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:120:0)
[13:31:56.127] Timer tick, asking Buildbucket for the build status
[13:31:56.183] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:56.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:121:0) after 1m0s
[13:32:56.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:121:0)
[13:32:56.294] Timer tick, asking Buildbucket for the build status
[13:32:56.361] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:56.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:122:0) after 1m0s
[13:33:56.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:122:0)
[13:33:56.626] Timer tick, asking Buildbucket for the build status
[13:33:56.875] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:56.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:123:0) after 1m0s
[13:34:57.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:123:0)
[13:34:57.088] Timer tick, asking Buildbucket for the build status
[13:34:57.144] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:57.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:124:0) after 1m0s
[13:35:57.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:124:0)
[13:35:57.266] Timer tick, asking Buildbucket for the build status
[13:35:57.343] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:57.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:125:0) after 1m0s
[13:36:57.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:125:0)
[13:36:57.392] Timer tick, asking Buildbucket for the build status
[13:36:57.450] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:57.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:126:0) after 1m0s
[13:37:57.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:126:0)
[13:37:57.615] Timer tick, asking Buildbucket for the build status
[13:37:57.762] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:57.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:127:0) after 1m0s
[13:38:57.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:127:0)
[13:38:57.855] Timer tick, asking Buildbucket for the build status
[13:38:57.984] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:57.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:128:0) after 1m0s
[13:39:58.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:128:0)
[13:39:58.090] Timer tick, asking Buildbucket for the build status
[13:39:58.168] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:58.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:129:0) after 1m0s
[13:40:58.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:129:0)
[13:40:58.229] Timer tick, asking Buildbucket for the build status
[13:40:58.292] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:58.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:130:0) after 1m0s
[13:41:58.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:130:0)
[13:41:58.408] Timer tick, asking Buildbucket for the build status
[13:41:58.637] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:58.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:131:0) after 1m0s
[13:42:58.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:131:0)
[13:42:58.758] Timer tick, asking Buildbucket for the build status
[13:42:58.820] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:58.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:132:0) after 1m0s
[13:43:58.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:132:0)
[13:43:58.879] Timer tick, asking Buildbucket for the build status
[13:43:59.009] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:59.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:133:0) after 1m0s
[13:44:59.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:133:0)
[13:44:59.056] Timer tick, asking Buildbucket for the build status
[13:44:59.133] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:59.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:134:0) after 1m0s
[13:45:59.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:134:0)
[13:45:59.164] Timer tick, asking Buildbucket for the build status
[13:45:59.262] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:59.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:135:0) after 1m0s
[13:46:59.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:135:0)
[13:46:59.288] Timer tick, asking Buildbucket for the build status
[13:46:59.432] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:59.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:136:0) after 1m0s
[13:47:59.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:136:0)
[13:47:59.617] Timer tick, asking Buildbucket for the build status
[13:47:59.694] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:59.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:137:0) after 1m0s
[13:48:59.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:137:0)
[13:48:59.720] Timer tick, asking Buildbucket for the build status
[13:48:59.831] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:59.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:138:0) after 1m0s
[13:49:59.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:138:0)
[13:49:59.858] Timer tick, asking Buildbucket for the build status
[13:49:59.918] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:59.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:139:0) after 1m0s
[13:50:59.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:139:0)
[13:50:59.964] Timer tick, asking Buildbucket for the build status
[13:51:00.382] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:00.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:140:0) after 1m0s
[13:52:00.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:140:0)
[13:52:00.511] Timer tick, asking Buildbucket for the build status
[13:52:00.812] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:00.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:141:0) after 1m0s
[13:53:01.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:141:0)
[13:53:01.581] Timer tick, asking Buildbucket for the build status
[13:53:01.750] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:01.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:142:0) after 1m0s
[13:54:02.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:142:0)
[13:54:02.767] Timer tick, asking Buildbucket for the build status
[13:54:02.891] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:02.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:143:0) after 1m0s
[13:55:02.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:143:0)
[13:55:02.995] Timer tick, asking Buildbucket for the build status
[13:55:03.122] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:03.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:144:0) after 1m0s
[13:56:03.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:144:0)
[13:56:03.148] Timer tick, asking Buildbucket for the build status
[13:56:03.323] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:03.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:145:0) after 1m0s
[13:57:03.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:145:0)
[13:57:03.370] Timer tick, asking Buildbucket for the build status
[13:57:03.438] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:03.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:146:0) after 1m0s
[13:58:03.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:146:0)
[13:58:03.460] Timer tick, asking Buildbucket for the build status
[13:58:03.518] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:03.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:147:0) after 1m0s
[13:59:03.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:147:0)
[13:59:03.640] Timer tick, asking Buildbucket for the build status
[13:59:04.177] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:04.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:148:0) after 1m0s
[14:00:04.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:148:0)
[14:00:04.199] Timer tick, asking Buildbucket for the build status
[14:00:04.273] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:04.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:149:0) after 1m0s
[14:01:04.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:149:0)
[14:01:04.383] Timer tick, asking Buildbucket for the build status
[14:01:04.648] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:04.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:150:0) after 1m0s
[14:02:04.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:150:0)
[14:02:04.783] Timer tick, asking Buildbucket for the build status
[14:02:05.898] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:05.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:151:0) after 1m0s
[14:03:06.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:151:0)
[14:03:06.007] Timer tick, asking Buildbucket for the build status
[14:03:06.201] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:06.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:152:0) after 1m0s
[14:04:06.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:152:0)
[14:04:06.493] Timer tick, asking Buildbucket for the build status
[14:04:06.634] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:06.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:153:0) after 1m0s
[14:05:06.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:153:0)
[14:05:06.739] Timer tick, asking Buildbucket for the build status
[14:05:06.823] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:06.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:154:0) after 1m0s
[14:06:06.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:154:0)
[14:06:06.985] Timer tick, asking Buildbucket for the build status
[14:06:07.117] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:07.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:155:0) after 1m0s
[14:07:07.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:155:0)
[14:07:07.152] Timer tick, asking Buildbucket for the build status
[14:07:07.271] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:07.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:156:0) after 1m0s
[14:08:07.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:156:0)
[14:08:07.298] Timer tick, asking Buildbucket for the build status
[14:08:07.374] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:07.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:157:0) after 1m0s
[14:09:07.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:157:0)
[14:09:07.425] Timer tick, asking Buildbucket for the build status
[14:09:07.631] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:07.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:158:0) after 1m0s
[14:10:07.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:158:0)
[14:10:07.782] Timer tick, asking Buildbucket for the build status
[14:10:08.248] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:08.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:159:0) after 1m0s
[14:11:08.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:159:0)
[14:11:08.580] Timer tick, asking Buildbucket for the build status
[14:11:08.900] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:08.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:160:0) after 1m0s
[14:12:08.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:160:0)
[14:12:08.996] Timer tick, asking Buildbucket for the build status
[14:12:09.058] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:09.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:161:0) after 1m0s
[14:13:09.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:161:0)
[14:13:09.163] Timer tick, asking Buildbucket for the build status
[14:13:09.329] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:09.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:162:0) after 1m0s
[14:14:09.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:162:0)
[14:14:09.437] Timer tick, asking Buildbucket for the build status
[14:14:09.836] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:09.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:163:0) after 1m0s
[14:15:09.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:163:0)
[14:15:09.895] Timer tick, asking Buildbucket for the build status
[14:15:09.961] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:09.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:164:0) after 1m0s
[14:16:10.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:164:0)
[14:16:10.037] Timer tick, asking Buildbucket for the build status
[14:16:10.128] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:10.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:165:0) after 1m0s
[14:17:10.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:165:0)
[14:17:10.162] Timer tick, asking Buildbucket for the build status
[14:17:10.247] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:10.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:166:0) after 1m0s
[14:18:10.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:166:0)
[14:18:10.336] Timer tick, asking Buildbucket for the build status
[14:18:10.438] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:10.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:167:0) after 1m0s
[14:19:10.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:167:0)
[14:19:10.493] Timer tick, asking Buildbucket for the build status
[14:19:10.603] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:10.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:168:0) after 1m0s
[14:20:10.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:168:0)
[14:20:10.636] Timer tick, asking Buildbucket for the build status
[14:20:10.703] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:10.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:169:0) after 1m0s
[14:21:10.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:169:0)
[14:21:10.730] Timer tick, asking Buildbucket for the build status
[14:21:10.794] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:10.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:170:0) after 1m0s
[14:22:10.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:170:0)
[14:22:10.860] Timer tick, asking Buildbucket for the build status
[14:22:10.927] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:10.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:171:0) after 1m0s
[14:23:10.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:171:0)
[14:23:10.962] Timer tick, asking Buildbucket for the build status
[14:23:11.031] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:11.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:172:0) after 1m0s
[14:24:11.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:172:0)
[14:24:11.104] Timer tick, asking Buildbucket for the build status
[14:24:11.225] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:11.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:173:0) after 1m0s
[14:25:11.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:173:0)
[14:25:11.406] Timer tick, asking Buildbucket for the build status
[14:25:11.505] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:11.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:174:0) after 1m0s
[14:26:11.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:174:0)
[14:26:11.632] Timer tick, asking Buildbucket for the build status
[14:26:11.697] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:11.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:175:0) after 1m0s
[14:27:11.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:175:0)
[14:27:11.810] Timer tick, asking Buildbucket for the build status
[14:27:11.873] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:11.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:176:0) after 1m0s
[14:28:11.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:176:0)
[14:28:11.944] Timer tick, asking Buildbucket for the build status
[14:28:12.007] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:12.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:177:0) after 1m0s
[14:29:12.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:177:0)
[14:29:12.094] Timer tick, asking Buildbucket for the build status
[14:29:12.252] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:12.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:178:0) after 1m0s
[14:30:12.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:178:0)
[14:30:12.306] Timer tick, asking Buildbucket for the build status
[14:30:12.665] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:12.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:179:0) after 1m0s
[14:31:12.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:179:0)
[14:31:12.785] Timer tick, asking Buildbucket for the build status
[14:31:12.946] Build 8865379811466514896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:12.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9030809873414358400:180:0) after 1m0s
[14:31:33.314] Received PubSub notification, asking Buildbucket for the build status
[14:31:33.382] Build 8865379811466514896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:31:33.382] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1603722692680196",
    "created_by": "project:infra",
    "created_ts": "1603711990825798",
    "id": "8865379811466514896",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4304.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5a3ea796-fdd4-4ab5-94c2-9af19aefcd3d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4304.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-myi0\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"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\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1603712043790207",
    "status": "COMPLETED",
    "status_changed_ts": "1603722693084644",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9030809873414358400",
      "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/8865379811466514896/+/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:4f7d8085ad45b610",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1603722693098252",
    "url": "https://ci.chromium.org/b/8865379811466514896",
    "utcnow_ts": "1603722693375366"
  }
}
[14:31:33.383] Invocation finished in 2h58m23.798040984s with status SUCCEEDED