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

• parent_buildername:publish_tarball
• user_agent:recipe

[10:16:18.942] New invocation is queued and will start shortly
[10:16:18.942] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:16:20.753] Starting the invocation (attempt 1)
[10:16:20.799] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:16:20.799] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:16:20.799] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9049115295255606448",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"84.0.4107.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"fc64a7b7-ec65-4580-859e-93443d05695e\",\"title\":\"publish_tarball/0\"}]},\"version\":\"84.0.4107.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:9049115295255606448",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[10:16:21.555] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1586254580907796",
    "id": "8883685232529499920",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4107.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"fc64a7b7-ec65-4580-859e-93443d05695e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4107.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1586254581184282",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9049115295255606448",
      "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/8883685232529499920/+/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": "1586254581184402",
    "url": "https://ci.chromium.org/b/8883685232529499920",
    "utcnow_ts": "1586254581533779"
  }
}
[10:16:21.555] Task URL: https://ci.chromium.org/b/8883685232529499920
[10:16:21.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:2:0) after 1m0s
[10:17:21.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:2:0)
[10:17:21.643] Timer tick, asking Buildbucket for the build status
[10:17:21.906] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:21.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:3:0) after 1m0s
[10:18:22.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:3:0)
[10:18:22.001] Timer tick, asking Buildbucket for the build status
[10:18:22.301] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:22.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:4:0) after 1m0s
[10:19:22.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:4:0)
[10:19:22.439] Timer tick, asking Buildbucket for the build status
[10:19:22.830] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:22.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:5:0) after 1m0s
[10:20:22.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:5:0)
[10:20:22.908] Timer tick, asking Buildbucket for the build status
[10:20:23.135] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:23.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:6:0) after 1m0s
[10:21:23.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:6:0)
[10:21:23.230] Timer tick, asking Buildbucket for the build status
[10:21:23.503] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:23.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:7:0) after 1m0s
[10:22:23.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:7:0)
[10:22:23.524] Timer tick, asking Buildbucket for the build status
[10:22:23.741] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:23.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:8:0) after 1m0s
[10:23:23.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:8:0)
[10:23:23.769] Timer tick, asking Buildbucket for the build status
[10:23:25.329] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:25.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:9:0) after 1m0s
[10:24:25.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:9:0)
[10:24:25.442] Timer tick, asking Buildbucket for the build status
[10:24:25.579] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:25.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:10:0) after 1m0s
[10:25:25.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:10:0)
[10:25:25.642] Timer tick, asking Buildbucket for the build status
[10:25:25.950] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:25.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:11:0) after 1m0s
[10:26:25.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:11:0)
[10:26:25.999] Timer tick, asking Buildbucket for the build status
[10:26:26.376] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:26.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:12:0) after 1m0s
[10:27:26.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:12:0)
[10:27:26.419] Timer tick, asking Buildbucket for the build status
[10:27:26.568] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:26.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:13:0) after 1m0s
[10:28:26.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:13:0)
[10:28:26.648] Timer tick, asking Buildbucket for the build status
[10:28:26.892] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:26.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:14:0) after 1m0s
[10:29:26.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:14:0)
[10:29:26.904] Timer tick, asking Buildbucket for the build status
[10:29:27.054] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:27.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:15:0) after 1m0s
[10:30:27.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:15:0)
[10:30:27.078] Timer tick, asking Buildbucket for the build status
[10:30:27.386] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:27.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:16:0) after 1m0s
[10:31:27.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:16:0)
[10:31:27.473] Timer tick, asking Buildbucket for the build status
[10:31:27.598] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:27.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:17:0) after 1m0s
[10:32:27.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:17:0)
[10:32:27.694] Timer tick, asking Buildbucket for the build status
[10:32:27.916] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:27.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:18:0) after 1m0s
[10:33:27.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:18:0)
[10:33:27.989] Timer tick, asking Buildbucket for the build status
[10:33:28.132] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:28.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:19:0) after 1m0s
[10:34:28.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:19:0)
[10:34:28.153] Timer tick, asking Buildbucket for the build status
[10:34:28.236] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:28.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:20:0) after 1m0s
[10:35:28.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:20:0)
[10:35:28.261] Timer tick, asking Buildbucket for the build status
[10:35:28.354] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:28.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:21:0) after 1m0s
[10:36:28.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:21:0)
[10:36:28.408] Timer tick, asking Buildbucket for the build status
[10:36:28.506] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:28.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:22:0) after 1m0s
[10:37:28.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:22:0)
[10:37:28.652] Timer tick, asking Buildbucket for the build status
[10:37:28.878] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:28.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:23:0) after 1m0s
[10:38:29.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:23:0)
[10:38:29.014] Timer tick, asking Buildbucket for the build status
[10:38:29.142] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:29.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:24:0) after 1m0s
[10:39:29.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:24:0)
[10:39:29.189] Timer tick, asking Buildbucket for the build status
[10:39:29.377] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:29.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:25:0) after 1m0s
[10:40:29.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:25:0)
[10:40:29.441] Timer tick, asking Buildbucket for the build status
[10:40:29.860] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:29.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:26:0) after 1m0s
[10:41:30.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:26:0)
[10:41:30.097] Timer tick, asking Buildbucket for the build status
[10:41:30.371] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:30.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:27:0) after 1m0s
[10:42:30.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:27:0)
[10:42:30.451] Timer tick, asking Buildbucket for the build status
[10:42:31.196] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:31.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:28:0) after 1m0s
[10:43:31.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:28:0)
[10:43:31.255] Timer tick, asking Buildbucket for the build status
[10:43:31.566] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:31.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:29:0) after 1m0s
[10:44:31.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:29:0)
[10:44:31.594] Timer tick, asking Buildbucket for the build status
[10:44:31.804] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:31.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:30:0) after 1m0s
[10:45:31.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:30:0)
[10:45:31.875] Timer tick, asking Buildbucket for the build status
[10:45:31.970] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:31.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:31:0) after 1m0s
[10:46:32.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:31:0)
[10:46:32.059] Timer tick, asking Buildbucket for the build status
[10:46:32.345] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:32.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:32:0) after 1m0s
[10:47:32.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:32:0)
[10:47:32.370] Timer tick, asking Buildbucket for the build status
[10:47:32.746] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:32.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:33:0) after 1m0s
[10:48:32.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:33:0)
[10:48:32.925] Timer tick, asking Buildbucket for the build status
[10:48:33.102] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:33.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:34:0) after 1m0s
[10:49:33.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:34:0)
[10:49:33.179] Timer tick, asking Buildbucket for the build status
[10:49:33.628] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:33.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:35:0) after 1m0s
[10:50:33.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:35:0)
[10:50:33.661] Timer tick, asking Buildbucket for the build status
[10:50:33.808] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:33.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:36:0) after 1m0s
[10:51:33.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:36:0)
[10:51:33.884] Timer tick, asking Buildbucket for the build status
[10:51:34.054] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:34.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:37:0) after 1m0s
[10:52:34.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:37:0)
[10:52:34.115] Timer tick, asking Buildbucket for the build status
[10:52:34.344] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:34.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:38:0) after 1m0s
[10:53:34.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:38:0)
[10:53:34.445] Timer tick, asking Buildbucket for the build status
[10:53:34.687] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:34.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:39:0) after 1m0s
[10:54:34.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:39:0)
[10:54:34.711] Timer tick, asking Buildbucket for the build status
[10:54:35.075] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:35.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:40:0) after 1m0s
[10:55:35.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:40:0)
[10:55:35.054] Timer tick, asking Buildbucket for the build status
[10:55:35.344] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:55:35.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:41:0) after 1m0s
[10:56:35.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:41:0)
[10:56:35.342] Timer tick, asking Buildbucket for the build status
[10:56:35.826] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:56:35.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:42:0) after 1m0s
[10:57:35.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:42:0)
[10:57:35.863] Timer tick, asking Buildbucket for the build status
[10:57:36.182] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:57:36.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:43:0) after 1m0s
[10:58:36.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:43:0)
[10:58:36.185] Timer tick, asking Buildbucket for the build status
[10:58:36.328] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:58:36.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:44:0) after 1m0s
[10:59:36.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:44:0)
[10:59:36.357] Timer tick, asking Buildbucket for the build status
[10:59:36.608] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:59:36.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:45:0) after 1m0s
[11:00:36.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:45:0)
[11:00:36.646] Timer tick, asking Buildbucket for the build status
[11:00:36.770] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:00:36.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:46:0) after 1m0s
[11:01:36.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:46:0)
[11:01:36.800] Timer tick, asking Buildbucket for the build status
[11:01:37.103] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:01:37.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:47:0) after 1m0s
[11:02:37.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:47:0)
[11:02:37.173] Timer tick, asking Buildbucket for the build status
[11:02:37.365] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:02:37.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:48:0) after 1m0s
[11:03:37.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:48:0)
[11:03:37.433] Timer tick, asking Buildbucket for the build status
[11:03:37.550] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:03:37.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:49:0) after 1m0s
[11:04:37.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:49:0)
[11:04:37.655] Timer tick, asking Buildbucket for the build status
[11:04:37.822] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:04:37.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:50:0) after 1m0s
[11:05:37.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:50:0)
[11:05:37.859] Timer tick, asking Buildbucket for the build status
[11:05:38.619] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:05:38.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:51:0) after 1m0s
[11:06:38.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:51:0)
[11:06:38.797] Timer tick, asking Buildbucket for the build status
[11:06:39.117] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:06:39.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:52:0) after 1m0s
[11:07:39.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:52:0)
[11:07:39.193] Timer tick, asking Buildbucket for the build status
[11:07:39.462] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:07:39.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:53:0) after 1m0s
[11:08:39.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:53:0)
[11:08:39.584] Timer tick, asking Buildbucket for the build status
[11:08:39.764] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:08:39.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:54:0) after 1m0s
[11:09:39.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:54:0)
[11:09:39.792] Timer tick, asking Buildbucket for the build status
[11:09:39.925] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:09:39.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:55:0) after 1m0s
[11:10:39.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:55:0)
[11:10:39.987] Timer tick, asking Buildbucket for the build status
[11:10:40.194] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:10:40.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:56:0) after 1m0s
[11:11:40.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:56:0)
[11:11:40.281] Timer tick, asking Buildbucket for the build status
[11:11:40.428] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:11:40.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:57:0) after 1m0s
[11:12:40.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:57:0)
[11:12:40.472] Timer tick, asking Buildbucket for the build status
[11:12:40.628] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:12:40.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:58:0) after 1m0s
[11:13:40.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:58:0)
[11:13:40.655] Timer tick, asking Buildbucket for the build status
[11:13:40.865] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:13:40.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:59:0) after 1m0s
[11:14:40.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:59:0)
[11:14:40.935] Timer tick, asking Buildbucket for the build status
[11:14:41.068] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:14:41.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:60:0) after 1m0s
[11:15:41.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:60:0)
[11:15:41.162] Timer tick, asking Buildbucket for the build status
[11:15:41.457] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:15:41.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:61:0) after 1m0s
[11:16:41.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:61:0)
[11:16:41.590] Timer tick, asking Buildbucket for the build status
[11:16:41.842] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:16:41.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:62:0) after 1m0s
[11:17:41.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:62:0)
[11:17:41.869] Timer tick, asking Buildbucket for the build status
[11:17:42.253] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:17:42.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:63:0) after 1m0s
[11:18:42.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:63:0)
[11:18:42.424] Timer tick, asking Buildbucket for the build status
[11:18:42.865] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:18:42.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:64:0) after 1m0s
[11:19:43.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:64:0)
[11:19:43.085] Timer tick, asking Buildbucket for the build status
[11:19:43.257] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:19:43.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:65:0) after 1m0s
[11:20:43.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:65:0)
[11:20:43.456] Timer tick, asking Buildbucket for the build status
[11:20:43.636] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:20:43.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:66:0) after 1m0s
[11:21:43.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:66:0)
[11:21:43.756] Timer tick, asking Buildbucket for the build status
[11:21:43.870] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:21:43.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:67:0) after 1m0s
[11:22:44.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:67:0)
[11:22:44.003] Timer tick, asking Buildbucket for the build status
[11:22:44.130] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:22:44.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:68:0) after 1m0s
[11:23:44.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:68:0)
[11:23:44.196] Timer tick, asking Buildbucket for the build status
[11:23:44.542] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:23:44.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:69:0) after 1m0s
[11:24:44.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:69:0)
[11:24:44.640] Timer tick, asking Buildbucket for the build status
[11:24:44.796] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:24:44.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:70:0) after 1m0s
[11:25:44.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:70:0)
[11:25:44.896] Timer tick, asking Buildbucket for the build status
[11:25:45.008] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:25:45.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:71:0) after 1m0s
[11:26:45.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:71:0)
[11:26:45.245] Timer tick, asking Buildbucket for the build status
[11:26:45.435] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:26:45.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:72:0) after 1m0s
[11:27:45.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:72:0)
[11:27:45.513] Timer tick, asking Buildbucket for the build status
[11:27:45.977] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:27:45.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:73:0) after 1m0s
[11:28:46.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:73:0)
[11:28:46.116] Timer tick, asking Buildbucket for the build status
[11:28:46.227] Build 8883685232529499920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:28:46.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:74:0) after 1m0s
[11:29:27.633] Received PubSub notification, asking Buildbucket for the build status
[11:29:27.860] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:46.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:74:0)
[11:29:46.356] Timer tick, asking Buildbucket for the build status
[11:29:48.439] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:48.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:76:0) after 1m0s
[11:30:48.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:76:0)
[11:30:48.457] Timer tick, asking Buildbucket for the build status
[11:30:48.583] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:48.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:77:0) after 1m0s
[11:31:48.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:77:0)
[11:31:48.621] Timer tick, asking Buildbucket for the build status
[11:31:49.178] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:49.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:78:0) after 1m0s
[11:32:49.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:78:0)
[11:32:49.300] Timer tick, asking Buildbucket for the build status
[11:32:49.466] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:49.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:79:0) after 1m0s
[11:33:49.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:79:0)
[11:33:49.553] Timer tick, asking Buildbucket for the build status
[11:33:50.188] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:50.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:80:0) after 1m0s
[11:34:50.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:80:0)
[11:34:50.210] Timer tick, asking Buildbucket for the build status
[11:34:50.394] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:50.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:81:0) after 1m0s
[11:35:50.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:81:0)
[11:35:50.376] Timer tick, asking Buildbucket for the build status
[11:35:50.668] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:50.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:82:0) after 1m0s
[11:36:50.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:82:0)
[11:36:50.798] Timer tick, asking Buildbucket for the build status
[11:36:51.049] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:51.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:83:0) after 1m0s
[11:37:51.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:83:0)
[11:37:51.049] Timer tick, asking Buildbucket for the build status
[11:37:51.257] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:51.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:84:0) after 1m0s
[11:38:51.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:84:0)
[11:38:51.283] Timer tick, asking Buildbucket for the build status
[11:38:52.066] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:52.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:85:0) after 1m0s
[11:39:52.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:85:0)
[11:39:52.097] Timer tick, asking Buildbucket for the build status
[11:39:52.242] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:52.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:86:0) after 1m0s
[11:40:52.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:86:0)
[11:40:52.307] Timer tick, asking Buildbucket for the build status
[11:40:52.663] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:52.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:87:0) after 1m0s
[11:41:52.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:87:0)
[11:41:52.687] Timer tick, asking Buildbucket for the build status
[11:41:52.816] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:52.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:88:0) after 1m0s
[11:42:52.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:88:0)
[11:42:52.872] Timer tick, asking Buildbucket for the build status
[11:42:53.004] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:53.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:89:0) after 1m0s
[11:43:53.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:89:0)
[11:43:53.031] Timer tick, asking Buildbucket for the build status
[11:43:53.176] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:53.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:90:0) after 1m0s
[11:44:53.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:90:0)
[11:44:53.215] Timer tick, asking Buildbucket for the build status
[11:44:53.332] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:53.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:91:0) after 1m0s
[11:45:53.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:91:0)
[11:45:53.362] Timer tick, asking Buildbucket for the build status
[11:45:53.623] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:53.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:92:0) after 1m0s
[11:46:53.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:92:0)
[11:46:53.626] Timer tick, asking Buildbucket for the build status
[11:46:53.893] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:53.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:93:0) after 1m0s
[11:47:53.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:93:0)
[11:47:53.923] Timer tick, asking Buildbucket for the build status
[11:47:54.096] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:54.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:94:0) after 1m0s
[11:48:54.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:94:0)
[11:48:54.131] Timer tick, asking Buildbucket for the build status
[11:48:54.502] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:54.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:95:0) after 1m0s
[11:49:54.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:95:0)
[11:49:54.573] Timer tick, asking Buildbucket for the build status
[11:49:54.791] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:54.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:96:0) after 1m0s
[11:50:54.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:96:0)
[11:50:54.824] Timer tick, asking Buildbucket for the build status
[11:50:55.247] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:55.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:97:0) after 1m0s
[11:51:55.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:97:0)
[11:51:55.283] Timer tick, asking Buildbucket for the build status
[11:51:55.443] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:55.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:98:0) after 1m0s
[11:52:55.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:98:0)
[11:52:55.523] Timer tick, asking Buildbucket for the build status
[11:52:55.690] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:55.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:99:0) after 1m0s
[11:53:55.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:99:0)
[11:53:55.759] Timer tick, asking Buildbucket for the build status
[11:53:55.943] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:55.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:100:0) after 1m0s
[11:54:55.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:100:0)
[11:54:55.940] Timer tick, asking Buildbucket for the build status
[11:54:56.078] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:56.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:101:0) after 1m0s
[11:55:56.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:101:0)
[11:55:56.104] Timer tick, asking Buildbucket for the build status
[11:55:56.256] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:56.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:102:0) after 1m0s
[11:56:56.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:102:0)
[11:56:56.324] Timer tick, asking Buildbucket for the build status
[11:56:56.624] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:56.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:103:0) after 1m0s
[11:57:56.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:103:0)
[11:57:56.689] Timer tick, asking Buildbucket for the build status
[11:57:57.106] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:57.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:104:0) after 1m0s
[11:58:57.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:104:0)
[11:58:57.244] Timer tick, asking Buildbucket for the build status
[11:58:57.639] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:57.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:105:0) after 1m0s
[11:59:57.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:105:0)
[11:59:57.664] Timer tick, asking Buildbucket for the build status
[11:59:58.004] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:58.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:106:0) after 1m0s
[12:00:58.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:106:0)
[12:00:58.031] Timer tick, asking Buildbucket for the build status
[12:00:58.253] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:58.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:107:0) after 1m0s
[12:01:58.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:107:0)
[12:01:58.266] Timer tick, asking Buildbucket for the build status
[12:01:58.406] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:58.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:108:0) after 1m0s
[12:02:58.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:108:0)
[12:02:58.489] Timer tick, asking Buildbucket for the build status
[12:02:59.814] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:59.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:109:0) after 1m0s
[12:03:59.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:109:0)
[12:03:59.853] Timer tick, asking Buildbucket for the build status
[12:04:00.134] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:00.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:110:0) after 1m0s
[12:05:00.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:110:0)
[12:05:00.133] Timer tick, asking Buildbucket for the build status
[12:05:00.646] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:00.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:111:0) after 1m0s
[12:06:00.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:111:0)
[12:06:00.750] Timer tick, asking Buildbucket for the build status
[12:06:00.849] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:00.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:112:0) after 1m0s
[12:07:00.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:112:0)
[12:07:00.927] Timer tick, asking Buildbucket for the build status
[12:07:01.122] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:01.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:113:0) after 1m0s
[12:08:01.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:113:0)
[12:08:01.202] Timer tick, asking Buildbucket for the build status
[12:08:01.392] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:01.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:114:0) after 1m0s
[12:09:01.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:114:0)
[12:09:01.408] Timer tick, asking Buildbucket for the build status
[12:09:02.548] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:02.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:115:0) after 1m0s
[12:10:02.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:115:0)
[12:10:02.639] Timer tick, asking Buildbucket for the build status
[12:10:03.134] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:03.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:116:0) after 1m0s
[12:11:03.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:116:0)
[12:11:03.314] Timer tick, asking Buildbucket for the build status
[12:11:04.221] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:04.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:117:0) after 1m0s
[12:12:04.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:117:0)
[12:12:04.323] Timer tick, asking Buildbucket for the build status
[12:12:04.498] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:04.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:118:0) after 1m0s
[12:13:04.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:118:0)
[12:13:04.522] Timer tick, asking Buildbucket for the build status
[12:13:04.734] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:04.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:119:0) after 1m0s
[12:14:04.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:119:0)
[12:14:04.787] Timer tick, asking Buildbucket for the build status
[12:14:04.916] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:04.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:120:0) after 1m0s
[12:15:04.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:120:0)
[12:15:04.992] Timer tick, asking Buildbucket for the build status
[12:15:06.506] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:06.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:121:0) after 1m0s
[12:16:06.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:121:0)
[12:16:06.499] Timer tick, asking Buildbucket for the build status
[12:16:06.834] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:06.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:122:0) after 1m0s
[12:17:06.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:122:0)
[12:17:06.852] Timer tick, asking Buildbucket for the build status
[12:17:07.077] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:07.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:123:0) after 1m0s
[12:18:07.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:123:0)
[12:18:07.203] Timer tick, asking Buildbucket for the build status
[12:18:07.367] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:07.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:124:0) after 1m0s
[12:19:07.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:124:0)
[12:19:07.463] Timer tick, asking Buildbucket for the build status
[12:19:07.860] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:07.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:125:0) after 1m0s
[12:20:07.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:125:0)
[12:20:07.886] Timer tick, asking Buildbucket for the build status
[12:20:08.262] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:08.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:126:0) after 1m0s
[12:21:08.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:126:0)
[12:21:08.315] Timer tick, asking Buildbucket for the build status
[12:21:08.630] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:08.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:127:0) after 1m0s
[12:22:08.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:127:0)
[12:22:08.744] Timer tick, asking Buildbucket for the build status
[12:22:09.005] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:09.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:128:0) after 1m0s
[12:23:09.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:128:0)
[12:23:09.040] Timer tick, asking Buildbucket for the build status
[12:23:09.193] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:09.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:129:0) after 1m0s
[12:24:09.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:129:0)
[12:24:09.230] Timer tick, asking Buildbucket for the build status
[12:24:09.446] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:09.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:130:0) after 1m0s
[12:25:09.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:130:0)
[12:25:09.481] Timer tick, asking Buildbucket for the build status
[12:25:09.695] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:09.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:131:0) after 1m0s
[12:26:09.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:131:0)
[12:26:09.711] Timer tick, asking Buildbucket for the build status
[12:26:09.890] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:09.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:132:0) after 1m0s
[12:27:09.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:132:0)
[12:27:09.916] Timer tick, asking Buildbucket for the build status
[12:27:10.076] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:10.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:133:0) after 1m0s
[12:28:10.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:133:0)
[12:28:10.147] Timer tick, asking Buildbucket for the build status
[12:28:11.214] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:11.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:134:0) after 1m0s
[12:29:11.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:134:0)
[12:29:11.227] Timer tick, asking Buildbucket for the build status
[12:29:11.391] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:11.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:135:0) after 1m0s
[12:30:11.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:135:0)
[12:30:11.460] Timer tick, asking Buildbucket for the build status
[12:30:11.787] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:11.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:136:0) after 1m0s
[12:31:11.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:136:0)
[12:31:11.811] Timer tick, asking Buildbucket for the build status
[12:31:13.377] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:13.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:137:0) after 1m0s
[12:32:13.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:137:0)
[12:32:13.386] Timer tick, asking Buildbucket for the build status
[12:32:13.506] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:13.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:138:0) after 1m0s
[12:33:13.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:138:0)
[12:33:13.597] Timer tick, asking Buildbucket for the build status
[12:33:13.736] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:13.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:139:0) after 1m0s
[12:34:13.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:139:0)
[12:34:13.762] Timer tick, asking Buildbucket for the build status
[12:34:14.070] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:14.070] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:140:0) after 1m0s
[12:35:14.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:140:0)
[12:35:14.093] Timer tick, asking Buildbucket for the build status
[12:35:14.435] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:14.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:141:0) after 1m0s
[12:36:14.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:141:0)
[12:36:14.513] Timer tick, asking Buildbucket for the build status
[12:36:14.634] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:14.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:142:0) after 1m0s
[12:37:14.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:142:0)
[12:37:14.674] Timer tick, asking Buildbucket for the build status
[12:37:14.801] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:14.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:143:0) after 1m0s
[12:38:14.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:143:0)
[12:38:14.855] Timer tick, asking Buildbucket for the build status
[12:38:16.805] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:16.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:144:0) after 1m0s
[12:39:16.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:144:0)
[12:39:16.851] Timer tick, asking Buildbucket for the build status
[12:39:17.152] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:17.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:145:0) after 1m0s
[12:40:17.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:145:0)
[12:40:17.196] Timer tick, asking Buildbucket for the build status
[12:40:17.477] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:17.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:146:0) after 1m0s
[12:41:17.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:146:0)
[12:41:17.525] Timer tick, asking Buildbucket for the build status
[12:41:17.680] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:17.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:147:0) after 1m0s
[12:42:17.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:147:0)
[12:42:17.715] Timer tick, asking Buildbucket for the build status
[12:42:17.877] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:17.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:148:0) after 1m0s
[12:43:17.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:148:0)
[12:43:17.972] Timer tick, asking Buildbucket for the build status
[12:43:18.090] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:18.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:149:0) after 1m0s
[12:44:18.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:149:0)
[12:44:18.126] Timer tick, asking Buildbucket for the build status
[12:44:18.246] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:18.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:150:0) after 1m0s
[12:45:18.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:150:0)
[12:45:18.259] Timer tick, asking Buildbucket for the build status
[12:45:18.608] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:18.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:151:0) after 1m0s
[12:46:18.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:151:0)
[12:46:18.656] Timer tick, asking Buildbucket for the build status
[12:46:18.767] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:18.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:152:0) after 1m0s
[12:47:18.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:152:0)
[12:47:18.809] Timer tick, asking Buildbucket for the build status
[12:47:18.960] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:18.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:153:0) after 1m0s
[12:48:19.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:153:0)
[12:48:19.068] Timer tick, asking Buildbucket for the build status
[12:48:19.202] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:19.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:154:0) after 1m0s
[12:49:19.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:154:0)
[12:49:19.282] Timer tick, asking Buildbucket for the build status
[12:49:19.827] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:19.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:155:0) after 1m0s
[12:50:19.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:155:0)
[12:50:19.877] Timer tick, asking Buildbucket for the build status
[12:50:20.271] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:20.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:156:0) after 1m0s
[12:51:20.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:156:0)
[12:51:20.359] Timer tick, asking Buildbucket for the build status
[12:51:20.560] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:20.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:157:0) after 1m0s
[12:52:20.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:157:0)
[12:52:20.599] Timer tick, asking Buildbucket for the build status
[12:52:20.930] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:20.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:158:0) after 1m0s
[12:53:20.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:158:0)
[12:53:20.966] Timer tick, asking Buildbucket for the build status
[12:53:21.098] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:21.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:159:0) after 1m0s
[12:54:21.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:159:0)
[12:54:21.194] Timer tick, asking Buildbucket for the build status
[12:54:21.429] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:21.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:160:0) after 1m0s
[12:55:21.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:160:0)
[12:55:21.485] Timer tick, asking Buildbucket for the build status
[12:55:21.714] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:21.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:161:0) after 1m0s
[12:56:21.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:161:0)
[12:56:21.802] Timer tick, asking Buildbucket for the build status
[12:56:22.396] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:22.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:162:0) after 1m0s
[12:57:22.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:162:0)
[12:57:22.464] Timer tick, asking Buildbucket for the build status
[12:57:22.873] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:22.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:163:0) after 1m0s
[12:58:23.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:163:0)
[12:58:23.077] Timer tick, asking Buildbucket for the build status
[12:58:23.284] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:23.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:164:0) after 1m0s
[12:59:23.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:164:0)
[12:59:23.301] Timer tick, asking Buildbucket for the build status
[12:59:23.453] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:23.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:165:0) after 1m0s
[13:00:23.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:165:0)
[13:00:23.503] Timer tick, asking Buildbucket for the build status
[13:00:24.172] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:24.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:166:0) after 1m0s
[13:01:24.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:166:0)
[13:01:24.258] Timer tick, asking Buildbucket for the build status
[13:01:26.068] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:26.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:167:0) after 1m0s
[13:02:26.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:167:0)
[13:02:26.165] Timer tick, asking Buildbucket for the build status
[13:02:26.550] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:26.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:168:0) after 1m0s
[13:03:26.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:168:0)
[13:03:26.733] Timer tick, asking Buildbucket for the build status
[13:03:26.886] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:26.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:169:0) after 1m0s
[13:04:26.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:169:0)
[13:04:26.981] Timer tick, asking Buildbucket for the build status
[13:04:27.509] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:27.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:170:0) after 1m0s
[13:05:27.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:170:0)
[13:05:27.565] Timer tick, asking Buildbucket for the build status
[13:05:27.711] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:27.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:171:0) after 1m0s
[13:06:27.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:171:0)
[13:06:27.797] Timer tick, asking Buildbucket for the build status
[13:06:28.370] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:28.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:172:0) after 1m0s
[13:07:28.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:172:0)
[13:07:28.474] Timer tick, asking Buildbucket for the build status
[13:07:28.741] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:28.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:173:0) after 1m0s
[13:08:28.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:173:0)
[13:08:28.862] Timer tick, asking Buildbucket for the build status
[13:08:29.105] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:29.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:174:0) after 1m0s
[13:09:29.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:174:0)
[13:09:29.108] Timer tick, asking Buildbucket for the build status
[13:09:29.287] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:29.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:175:0) after 1m0s
[13:10:29.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:175:0)
[13:10:29.437] Timer tick, asking Buildbucket for the build status
[13:10:29.559] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:29.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:176:0) after 1m0s
[13:11:29.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:176:0)
[13:11:29.738] Timer tick, asking Buildbucket for the build status
[13:11:29.985] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:29.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:177:0) after 1m0s
[13:12:30.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:177:0)
[13:12:30.105] Timer tick, asking Buildbucket for the build status
[13:12:30.321] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:30.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:178:0) after 1m0s
[13:13:30.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:178:0)
[13:13:30.481] Timer tick, asking Buildbucket for the build status
[13:13:30.611] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:30.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:179:0) after 1m0s
[13:14:30.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:179:0)
[13:14:30.880] Timer tick, asking Buildbucket for the build status
[13:14:31.087] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:31.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:180:0) after 1m0s
[13:15:31.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:180:0)
[13:15:31.301] Timer tick, asking Buildbucket for the build status
[13:15:31.468] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:31.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:181:0) after 1m0s
[13:16:31.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:181:0)
[13:16:31.574] Timer tick, asking Buildbucket for the build status
[13:16:31.723] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:31.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:182:0) after 1m0s
[13:17:31.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:182:0)
[13:17:31.811] Timer tick, asking Buildbucket for the build status
[13:17:31.977] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:31.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:183:0) after 1m0s
[13:18:32.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:183:0)
[13:18:32.047] Timer tick, asking Buildbucket for the build status
[13:18:32.167] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:32.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:184:0) after 1m0s
[13:19:32.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:184:0)
[13:19:32.282] Timer tick, asking Buildbucket for the build status
[13:19:32.672] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:32.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:185:0) after 1m0s
[13:20:32.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:185:0)
[13:20:32.774] Timer tick, asking Buildbucket for the build status
[13:20:32.985] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:32.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:186:0) after 1m0s
[13:21:33.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:186:0)
[13:21:33.017] Timer tick, asking Buildbucket for the build status
[13:21:33.230] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:33.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:187:0) after 1m0s
[13:22:33.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:187:0)
[13:22:33.256] Timer tick, asking Buildbucket for the build status
[13:22:33.681] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:33.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:188:0) after 1m0s
[13:23:33.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:188:0)
[13:23:33.696] Timer tick, asking Buildbucket for the build status
[13:23:33.868] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:33.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:189:0) after 1m0s
[13:24:33.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:189:0)
[13:24:33.963] Timer tick, asking Buildbucket for the build status
[13:24:34.343] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:34.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:190:0) after 1m0s
[13:25:34.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:190:0)
[13:25:34.364] Timer tick, asking Buildbucket for the build status
[13:25:34.510] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:34.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:191:0) after 1m0s
[13:26:34.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:191:0)
[13:26:34.541] Timer tick, asking Buildbucket for the build status
[13:26:34.697] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:34.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:192:0) after 1m0s
[13:27:34.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:192:0)
[13:27:34.745] Timer tick, asking Buildbucket for the build status
[13:27:34.987] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:34.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:193:0) after 1m0s
[13:28:35.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:193:0)
[13:28:35.123] Timer tick, asking Buildbucket for the build status
[13:28:36.031] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:36.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:194:0) after 1m0s
[13:29:36.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:194:0)
[13:29:36.071] Timer tick, asking Buildbucket for the build status
[13:29:36.579] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:36.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:195:0) after 1m0s
[13:30:36.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:195:0)
[13:30:36.604] Timer tick, asking Buildbucket for the build status
[13:30:36.745] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:36.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:196:0) after 1m0s
[13:31:36.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:196:0)
[13:31:36.776] Timer tick, asking Buildbucket for the build status
[13:31:36.943] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:36.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:197:0) after 1m0s
[13:32:36.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:197:0)
[13:32:36.969] Timer tick, asking Buildbucket for the build status
[13:32:37.139] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:37.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:198:0) after 1m0s
[13:33:37.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:198:0)
[13:33:37.147] Timer tick, asking Buildbucket for the build status
[13:33:37.447] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:37.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:199:0) after 1m0s
[13:34:37.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:199:0)
[13:34:37.490] Timer tick, asking Buildbucket for the build status
[13:34:37.784] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:37.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:200:0) after 1m0s
[13:35:37.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:200:0)
[13:35:37.855] Timer tick, asking Buildbucket for the build status
[13:35:38.601] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:38.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:201:0) after 1m0s
[13:36:38.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:201:0)
[13:36:38.762] Timer tick, asking Buildbucket for the build status
[13:36:39.530] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:39.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:202:0) after 1m0s
[13:37:39.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:202:0)
[13:37:39.594] Timer tick, asking Buildbucket for the build status
[13:37:39.809] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:39.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:203:0) after 1m0s
[13:38:39.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:203:0)
[13:38:39.878] Timer tick, asking Buildbucket for the build status
[13:38:40.065] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:40.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:204:0) after 1m0s
[13:39:40.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:204:0)
[13:39:40.162] Timer tick, asking Buildbucket for the build status
[13:39:40.379] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:40.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:205:0) after 1m0s
[13:40:40.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:205:0)
[13:40:40.415] Timer tick, asking Buildbucket for the build status
[13:40:40.710] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:40.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:206:0) after 1m0s
[13:41:40.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:206:0)
[13:41:40.781] Timer tick, asking Buildbucket for the build status
[13:41:41.245] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:41.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:207:0) after 1m0s
[13:42:41.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:207:0)
[13:42:41.281] Timer tick, asking Buildbucket for the build status
[13:42:41.535] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:41.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:208:0) after 1m0s
[13:43:41.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:208:0)
[13:43:41.541] Timer tick, asking Buildbucket for the build status
[13:43:41.877] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:41.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:209:0) after 1m0s
[13:44:42.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:209:0)
[13:44:42.086] Timer tick, asking Buildbucket for the build status
[13:44:42.519] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:42.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:210:0) after 1m0s
[13:45:42.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:210:0)
[13:45:42.551] Timer tick, asking Buildbucket for the build status
[13:45:42.993] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:42.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:211:0) after 1m0s
[13:46:43.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:211:0)
[13:46:43.066] Timer tick, asking Buildbucket for the build status
[13:46:44.951] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:44.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:212:0) after 1m0s
[13:47:45.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:212:0)
[13:47:45.043] Timer tick, asking Buildbucket for the build status
[13:47:45.205] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:45.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:213:0) after 1m0s
[13:48:45.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:213:0)
[13:48:45.400] Timer tick, asking Buildbucket for the build status
[13:48:45.613] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:45.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:214:0) after 1m0s
[13:49:45.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:214:0)
[13:49:45.681] Timer tick, asking Buildbucket for the build status
[13:49:46.089] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:46.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:215:0) after 1m0s
[13:50:46.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:215:0)
[13:50:46.083] Timer tick, asking Buildbucket for the build status
[13:50:46.531] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:46.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:216:0) after 1m0s
[13:51:46.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:216:0)
[13:51:46.563] Timer tick, asking Buildbucket for the build status
[13:51:46.759] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:46.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:217:0) after 1m0s
[13:52:50.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:217:0)
[13:52:50.702] Timer tick, asking Buildbucket for the build status
[13:52:54.638] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:54.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:218:0) after 1m0s
[13:53:55.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:218:0)
[13:53:55.125] Timer tick, asking Buildbucket for the build status
[13:53:55.612] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:55.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:219:0) after 1m0s
[13:54:55.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:219:0)
[13:54:55.740] Timer tick, asking Buildbucket for the build status
[13:54:55.894] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:55.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:220:0) after 1m0s
[13:55:55.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:220:0)
[13:55:55.946] Timer tick, asking Buildbucket for the build status
[13:55:56.113] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:56.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:221:0) after 1m0s
[13:56:56.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:221:0)
[13:56:56.210] Timer tick, asking Buildbucket for the build status
[13:56:56.480] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:56.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:222:0) after 1m0s
[13:57:56.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:222:0)
[13:57:56.610] Timer tick, asking Buildbucket for the build status
[13:57:57.037] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:57.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:223:0) after 1m0s
[13:58:57.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:223:0)
[13:58:57.157] Timer tick, asking Buildbucket for the build status
[13:58:57.675] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:57.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:224:0) after 1m0s
[13:59:58.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:224:0)
[13:59:58.553] Timer tick, asking Buildbucket for the build status
[13:59:58.784] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:58.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:225:0) after 1m0s
[14:00:58.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:225:0)
[14:00:58.827] Timer tick, asking Buildbucket for the build status
[14:00:59.226] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:59.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:226:0) after 1m0s
[14:01:59.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:226:0)
[14:01:59.262] Timer tick, asking Buildbucket for the build status
[14:01:59.428] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:59.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:227:0) after 1m0s
[14:02:59.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:227:0)
[14:02:59.446] Timer tick, asking Buildbucket for the build status
[14:02:59.797] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:59.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:228:0) after 1m0s
[14:03:59.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:228:0)
[14:03:59.840] Timer tick, asking Buildbucket for the build status
[14:04:01.864] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:01.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:229:0) after 1m0s
[14:05:01.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:229:0)
[14:05:01.944] Timer tick, asking Buildbucket for the build status
[14:05:02.193] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:02.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:230:0) after 1m0s
[14:06:02.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:230:0)
[14:06:02.238] Timer tick, asking Buildbucket for the build status
[14:06:06.217] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:06.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:231:0) after 1m0s
[14:07:06.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:231:0)
[14:07:06.263] Timer tick, asking Buildbucket for the build status
[14:07:06.963] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:06.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:232:0) after 1m0s
[14:08:07.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:232:0)
[14:08:07.001] Timer tick, asking Buildbucket for the build status
[14:08:07.147] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:07.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:233:0) after 1m0s
[14:09:07.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:233:0)
[14:09:07.228] Timer tick, asking Buildbucket for the build status
[14:09:07.431] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:07.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:234:0) after 1m0s
[14:10:07.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:234:0)
[14:10:07.546] Timer tick, asking Buildbucket for the build status
[14:10:11.694] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:11.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:235:0) after 1m0s
[14:11:11.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:235:0)
[14:11:11.730] Timer tick, asking Buildbucket for the build status
[14:11:12.063] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:12.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:236:0) after 1m0s
[14:12:12.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:236:0)
[14:12:12.094] Timer tick, asking Buildbucket for the build status
[14:12:12.331] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:12.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:237:0) after 1m0s
[14:13:12.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:237:0)
[14:13:12.441] Timer tick, asking Buildbucket for the build status
[14:13:12.597] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:12.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:238:0) after 1m0s
[14:14:12.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:238:0)
[14:14:12.643] Timer tick, asking Buildbucket for the build status
[14:14:12.814] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:12.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:239:0) after 1m0s
[14:15:12.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:239:0)
[14:15:12.895] Timer tick, asking Buildbucket for the build status
[14:15:13.024] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:13.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:240:0) after 1m0s
[14:16:13.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:240:0)
[14:16:13.191] Timer tick, asking Buildbucket for the build status
[14:16:13.348] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:13.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:241:0) after 1m0s
[14:17:13.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:241:0)
[14:17:13.377] Timer tick, asking Buildbucket for the build status
[14:17:13.507] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:13.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:242:0) after 1m0s
[14:18:13.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:242:0)
[14:18:13.576] Timer tick, asking Buildbucket for the build status
[14:18:13.698] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:13.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:243:0) after 1m0s
[14:19:13.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:243:0)
[14:19:13.745] Timer tick, asking Buildbucket for the build status
[14:19:13.880] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:13.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:244:0) after 1m0s
[14:20:14.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:244:0)
[14:20:14.111] Timer tick, asking Buildbucket for the build status
[14:20:14.399] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:14.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:245:0) after 1m0s
[14:21:14.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:245:0)
[14:21:14.609] Timer tick, asking Buildbucket for the build status
[14:21:14.774] Build 8883685232529499920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:14.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049115295255606448:246:0) after 1m0s
[14:22:05.162] Received PubSub notification, asking Buildbucket for the build status
[14:22:05.336] Build 8883685232529499920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:22:05.336] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1586269323108156",
    "created_by": "project:infra",
    "created_ts": "1586254580907796",
    "id": "8883685232529499920",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4107.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"fc64a7b7-ec65-4580-859e-93443d05695e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4107.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4107.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"fc64a7b7-ec65-4580-859e-93443d05695e\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-0s1l\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"84.0.4107.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-0s1l\"], \"image\": [\"chrome-xenial-20040500-42c1ff87780\"], \"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\": [\"4949-7e00c46\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1586258966777044",
    "status": "COMPLETED",
    "status_changed_ts": "1586269324162655",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9049115295255606448",
      "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/8883685232529499920/+/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:4b6cf597dcefa210",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1586269324546526",
    "url": "https://ci.chromium.org/b/8883685232529499920",
    "utcnow_ts": "1586269325326003"
  }
}
[14:22:05.337] Invocation finished in 4h5m46.414377406s with status SUCCEEDED