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

• parent_buildername:publish_tarball
• user_agent:recipe

[14:07:51.019] New invocation is queued and will start shortly
[14:07:51.019] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[14:07:52.818] Starting the invocation (attempt 1)
[14:07:52.857] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:07:52.857] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:07:52.857] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9040765807448910560",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4196.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"b810865d-a1b1-4c54-9a46-c15f110e26df\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4196.2\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9040765807448910560",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[14:07:53.670] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1594217272959688",
    "id": "8875335744748189424",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4196.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b810865d-a1b1-4c54-9a46-c15f110e26df\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4196.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1594217273287512",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9040765807448910560",
      "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/8875335744748189424/+/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": "1594217273287694",
    "url": "https://ci.chromium.org/b/8875335744748189424",
    "utcnow_ts": "1594217273660352"
  }
}
[14:07:53.670] Task URL: https://ci.chromium.org/b/8875335744748189424
[14:07:53.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:2:0) after 1m0s
[14:08:35.889] Received PubSub notification, asking Buildbucket for the build status
[14:08:36.034] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:53.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:2:0)
[14:08:53.691] Timer tick, asking Buildbucket for the build status
[14:08:53.953] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:53.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:4:0) after 1m0s
[14:09:54.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:4:0)
[14:09:54.052] Timer tick, asking Buildbucket for the build status
[14:09:54.301] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:54.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:5:0) after 1m0s
[14:10:54.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:5:0)
[14:10:54.322] Timer tick, asking Buildbucket for the build status
[14:10:54.548] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:54.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:6:0) after 1m0s
[14:11:54.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:6:0)
[14:11:54.585] Timer tick, asking Buildbucket for the build status
[14:11:54.720] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:54.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:7:0) after 1m0s
[14:12:54.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:7:0)
[14:12:54.836] Timer tick, asking Buildbucket for the build status
[14:12:54.985] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:54.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:8:0) after 1m0s
[14:13:55.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:8:0)
[14:13:55.009] Timer tick, asking Buildbucket for the build status
[14:13:55.447] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:55.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:9:0) after 1m0s
[14:14:55.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:9:0)
[14:14:55.479] Timer tick, asking Buildbucket for the build status
[14:14:55.647] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:55.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:10:0) after 1m0s
[14:15:57.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:10:0)
[14:15:57.260] Timer tick, asking Buildbucket for the build status
[14:15:57.621] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:57.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:11:0) after 1m0s
[14:16:57.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:11:0)
[14:16:57.712] Timer tick, asking Buildbucket for the build status
[14:16:59.222] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:59.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:12:0) after 1m0s
[14:17:59.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:12:0)
[14:17:59.330] Timer tick, asking Buildbucket for the build status
[14:18:00.164] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:00.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:13:0) after 1m0s
[14:19:00.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:13:0)
[14:19:00.312] Timer tick, asking Buildbucket for the build status
[14:19:00.648] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:00.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:14:0) after 1m0s
[14:20:01.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:14:0)
[14:20:01.114] Timer tick, asking Buildbucket for the build status
[14:20:01.397] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:01.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:15:0) after 1m0s
[14:21:02.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:15:0)
[14:21:02.746] Timer tick, asking Buildbucket for the build status
[14:21:03.051] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:03.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:16:0) after 1m0s
[14:22:03.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:16:0)
[14:22:03.091] Timer tick, asking Buildbucket for the build status
[14:22:03.375] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:03.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:17:0) after 1m0s
[14:23:03.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:17:0)
[14:23:03.406] Timer tick, asking Buildbucket for the build status
[14:23:03.587] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:03.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:18:0) after 1m0s
[14:24:03.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:18:0)
[14:24:03.703] Timer tick, asking Buildbucket for the build status
[14:24:03.828] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:03.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:19:0) after 1m0s
[14:25:03.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:19:0)
[14:25:03.911] Timer tick, asking Buildbucket for the build status
[14:25:04.189] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:04.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:20:0) after 1m0s
[14:26:04.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:20:0)
[14:26:04.258] Timer tick, asking Buildbucket for the build status
[14:26:04.489] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:04.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:21:0) after 1m0s
[14:27:04.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:21:0)
[14:27:04.520] Timer tick, asking Buildbucket for the build status
[14:27:05.319] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:05.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:22:0) after 1m0s
[14:28:05.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:22:0)
[14:28:05.390] Timer tick, asking Buildbucket for the build status
[14:28:06.174] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:06.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:23:0) after 1m0s
[14:29:06.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:23:0)
[14:29:06.867] Timer tick, asking Buildbucket for the build status
[14:29:06.955] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:06.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:24:0) after 1m0s
[14:30:07.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:24:0)
[14:30:07.021] Timer tick, asking Buildbucket for the build status
[14:30:07.162] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:07.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:25:0) after 1m0s
[14:31:07.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:25:0)
[14:31:07.291] Timer tick, asking Buildbucket for the build status
[14:31:07.487] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:07.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:26:0) after 1m0s
[14:32:07.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:26:0)
[14:32:07.499] Timer tick, asking Buildbucket for the build status
[14:32:08.843] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:08.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:27:0) after 1m0s
[14:33:08.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:27:0)
[14:33:08.875] Timer tick, asking Buildbucket for the build status
[14:33:09.074] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:09.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:28:0) after 1m0s
[14:34:09.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:28:0)
[14:34:09.095] Timer tick, asking Buildbucket for the build status
[14:34:09.270] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:09.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:29:0) after 1m0s
[14:35:09.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:29:0)
[14:35:09.350] Timer tick, asking Buildbucket for the build status
[14:35:09.658] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:09.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:30:0) after 1m0s
[14:36:09.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:30:0)
[14:36:09.852] Timer tick, asking Buildbucket for the build status
[14:36:10.560] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:10.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:31:0) after 1m0s
[14:37:10.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:31:0)
[14:37:10.694] Timer tick, asking Buildbucket for the build status
[14:37:10.798] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:10.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:32:0) after 1m0s
[14:38:10.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:32:0)
[14:38:10.817] Timer tick, asking Buildbucket for the build status
[14:38:11.004] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:11.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:33:0) after 1m0s
[14:39:11.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:33:0)
[14:39:11.146] Timer tick, asking Buildbucket for the build status
[14:39:11.530] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:11.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:34:0) after 1m0s
[14:40:11.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:34:0)
[14:40:11.516] Timer tick, asking Buildbucket for the build status
[14:40:11.909] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:11.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:35:0) after 1m0s
[14:41:11.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:35:0)
[14:41:11.911] Timer tick, asking Buildbucket for the build status
[14:41:12.299] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:12.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:36:0) after 1m0s
[14:42:12.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:36:0)
[14:42:12.366] Timer tick, asking Buildbucket for the build status
[14:42:12.487] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:12.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:37:0) after 1m0s
[14:43:12.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:37:0)
[14:43:12.571] Timer tick, asking Buildbucket for the build status
[14:43:12.920] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:12.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:38:0) after 1m0s
[14:44:13.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:38:0)
[14:44:13.015] Timer tick, asking Buildbucket for the build status
[14:44:13.206] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:13.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:39:0) after 1m0s
[14:45:13.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:39:0)
[14:45:13.221] Timer tick, asking Buildbucket for the build status
[14:45:13.359] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:13.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:40:0) after 1m0s
[14:46:13.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:40:0)
[14:46:13.390] Timer tick, asking Buildbucket for the build status
[14:46:14.331] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:14.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:41:0) after 1m0s
[14:47:14.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:41:0)
[14:47:14.361] Timer tick, asking Buildbucket for the build status
[14:47:14.699] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:14.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:42:0) after 1m0s
[14:48:14.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:42:0)
[14:48:14.751] Timer tick, asking Buildbucket for the build status
[14:48:14.983] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:14.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:43:0) after 1m0s
[14:49:15.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:43:0)
[14:49:15.006] Timer tick, asking Buildbucket for the build status
[14:49:15.480] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:15.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:44:0) after 1m0s
[14:50:15.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:44:0)
[14:50:15.552] Timer tick, asking Buildbucket for the build status
[14:50:15.696] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:15.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:45:0) after 1m0s
[14:51:15.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:45:0)
[14:51:15.854] Timer tick, asking Buildbucket for the build status
[14:51:16.051] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:16.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:46:0) after 1m0s
[14:52:16.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:46:0)
[14:52:16.094] Timer tick, asking Buildbucket for the build status
[14:52:17.999] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:17.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:47:0) after 1m0s
[14:53:18.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:47:0)
[14:53:18.326] Timer tick, asking Buildbucket for the build status
[14:53:18.459] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:18.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:48:0) after 1m0s
[14:54:18.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:48:0)
[14:54:18.718] Timer tick, asking Buildbucket for the build status
[14:54:19.262] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:19.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:49:0) after 1m0s
[14:55:19.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:49:0)
[14:55:19.336] Timer tick, asking Buildbucket for the build status
[14:55:19.530] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:19.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:50:0) after 1m0s
[14:56:19.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:50:0)
[14:56:19.580] Timer tick, asking Buildbucket for the build status
[14:56:19.989] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:19.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:51:0) after 1m0s
[14:57:20.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:51:0)
[14:57:20.039] Timer tick, asking Buildbucket for the build status
[14:57:20.765] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:20.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:52:0) after 1m0s
[14:58:20.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:52:0)
[14:58:20.914] Timer tick, asking Buildbucket for the build status
[14:58:21.109] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:21.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:53:0) after 1m0s
[14:59:21.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:53:0)
[14:59:21.133] Timer tick, asking Buildbucket for the build status
[14:59:21.256] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:21.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:54:0) after 1m0s
[15:00:21.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:54:0)
[15:00:21.473] Timer tick, asking Buildbucket for the build status
[15:00:23.702] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:23.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:55:0) after 1m0s
[15:01:23.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:55:0)
[15:01:23.737] Timer tick, asking Buildbucket for the build status
[15:01:24.659] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:24.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:56:0) after 1m0s
[15:02:24.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:56:0)
[15:02:24.675] Timer tick, asking Buildbucket for the build status
[15:02:24.912] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:24.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:57:0) after 1m0s
[15:03:24.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:57:0)
[15:03:24.956] Timer tick, asking Buildbucket for the build status
[15:03:25.190] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:25.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:58:0) after 1m0s
[15:04:25.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:58:0)
[15:04:25.227] Timer tick, asking Buildbucket for the build status
[15:04:25.427] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:25.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:59:0) after 1m0s
[15:05:25.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:59:0)
[15:05:25.457] Timer tick, asking Buildbucket for the build status
[15:05:25.724] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:25.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:60:0) after 1m0s
[15:06:25.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:60:0)
[15:06:25.791] Timer tick, asking Buildbucket for the build status
[15:06:25.992] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:25.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:61:0) after 1m0s
[15:07:26.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:61:0)
[15:07:26.036] Timer tick, asking Buildbucket for the build status
[15:07:26.311] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:26.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:62:0) after 1m0s
[15:08:26.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:62:0)
[15:08:26.345] Timer tick, asking Buildbucket for the build status
[15:08:26.617] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:26.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:63:0) after 1m0s
[15:09:26.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:63:0)
[15:09:26.653] Timer tick, asking Buildbucket for the build status
[15:09:27.367] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:27.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:64:0) after 1m0s
[15:10:27.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:64:0)
[15:10:27.401] Timer tick, asking Buildbucket for the build status
[15:10:27.767] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:27.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:65:0) after 1m0s
[15:11:27.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:65:0)
[15:11:27.805] Timer tick, asking Buildbucket for the build status
[15:11:27.987] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:27.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:66:0) after 1m0s
[15:12:28.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:66:0)
[15:12:28.033] Timer tick, asking Buildbucket for the build status
[15:12:28.276] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:28.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:67:0) after 1m0s
[15:13:28.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:67:0)
[15:13:28.285] Timer tick, asking Buildbucket for the build status
[15:13:28.406] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:28.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:68:0) after 1m0s
[15:14:28.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:68:0)
[15:14:28.423] Timer tick, asking Buildbucket for the build status
[15:14:28.722] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:28.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:69:0) after 1m0s
[15:15:28.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:69:0)
[15:15:28.799] Timer tick, asking Buildbucket for the build status
[15:15:29.165] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:29.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:70:0) after 1m0s
[15:16:29.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:70:0)
[15:16:29.293] Timer tick, asking Buildbucket for the build status
[15:16:29.503] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:29.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:71:0) after 1m0s
[15:17:29.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:71:0)
[15:17:29.594] Timer tick, asking Buildbucket for the build status
[15:17:29.812] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:29.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:72:0) after 1m0s
[15:18:29.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:72:0)
[15:18:29.866] Timer tick, asking Buildbucket for the build status
[15:18:29.977] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:29.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:73:0) after 1m0s
[15:19:29.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:73:0)
[15:19:29.998] Timer tick, asking Buildbucket for the build status
[15:19:31.358] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:31.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:74:0) after 1m0s
[15:20:31.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:74:0)
[15:20:31.414] Timer tick, asking Buildbucket for the build status
[15:20:32.142] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:32.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:75:0) after 1m0s
[15:21:32.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:75:0)
[15:21:32.167] Timer tick, asking Buildbucket for the build status
[15:21:32.628] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:32.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:76:0) after 1m0s
[15:22:32.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:76:0)
[15:22:32.676] Timer tick, asking Buildbucket for the build status
[15:22:33.063] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:33.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:77:0) after 1m0s
[15:23:33.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:77:0)
[15:23:33.097] Timer tick, asking Buildbucket for the build status
[15:23:33.478] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:33.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:78:0) after 1m0s
[15:24:33.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:78:0)
[15:24:33.533] Timer tick, asking Buildbucket for the build status
[15:24:33.701] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:33.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:79:0) after 1m0s
[15:25:33.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:79:0)
[15:25:33.753] Timer tick, asking Buildbucket for the build status
[15:25:33.867] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:33.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:80:0) after 1m0s
[15:26:33.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:80:0)
[15:26:33.896] Timer tick, asking Buildbucket for the build status
[15:26:34.185] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:34.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:81:0) after 1m0s
[15:27:34.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:81:0)
[15:27:34.492] Timer tick, asking Buildbucket for the build status
[15:27:34.669] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:34.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:82:0) after 1m0s
[15:28:35.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:82:0)
[15:28:35.661] Timer tick, asking Buildbucket for the build status
[15:28:35.821] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:35.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:83:0) after 1m0s
[15:29:36.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:83:0)
[15:29:36.366] Timer tick, asking Buildbucket for the build status
[15:29:36.583] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:36.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:84:0) after 1m0s
[15:30:36.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:84:0)
[15:30:36.664] Timer tick, asking Buildbucket for the build status
[15:30:36.860] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:36.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:85:0) after 1m0s
[15:31:36.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:85:0)
[15:31:36.974] Timer tick, asking Buildbucket for the build status
[15:31:37.235] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:37.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:86:0) after 1m0s
[15:32:37.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:86:0)
[15:32:37.274] Timer tick, asking Buildbucket for the build status
[15:32:38.634] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:38.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:87:0) after 1m0s
[15:33:38.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:87:0)
[15:33:38.687] Timer tick, asking Buildbucket for the build status
[15:33:38.883] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:38.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:88:0) after 1m0s
[15:34:38.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:88:0)
[15:34:38.987] Timer tick, asking Buildbucket for the build status
[15:34:39.142] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:39.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:89:0) after 1m0s
[15:35:39.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:89:0)
[15:35:39.398] Timer tick, asking Buildbucket for the build status
[15:35:39.507] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:39.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:90:0) after 1m0s
[15:36:39.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:90:0)
[15:36:39.734] Timer tick, asking Buildbucket for the build status
[15:36:40.025] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:40.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:91:0) after 1m0s
[15:37:40.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:91:0)
[15:37:40.049] Timer tick, asking Buildbucket for the build status
[15:37:41.063] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:41.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:92:0) after 1m0s
[15:38:41.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:92:0)
[15:38:41.191] Timer tick, asking Buildbucket for the build status
[15:38:43.215] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:43.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:93:0) after 1m0s
[15:39:43.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:93:0)
[15:39:43.364] Timer tick, asking Buildbucket for the build status
[15:39:43.498] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:43.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:94:0) after 1m0s
[15:40:43.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:94:0)
[15:40:43.613] Timer tick, asking Buildbucket for the build status
[15:40:43.747] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:43.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:95:0) after 1m0s
[15:41:43.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:95:0)
[15:41:43.800] Timer tick, asking Buildbucket for the build status
[15:41:44.215] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:44.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:96:0) after 1m0s
[15:42:44.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:96:0)
[15:42:44.337] Timer tick, asking Buildbucket for the build status
[15:42:44.480] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:44.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:97:0) after 1m0s
[15:43:44.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:97:0)
[15:43:44.541] Timer tick, asking Buildbucket for the build status
[15:43:44.704] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:44.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:98:0) after 1m0s
[15:44:44.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:98:0)
[15:44:44.732] Timer tick, asking Buildbucket for the build status
[15:44:44.924] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:44.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:99:0) after 1m0s
[15:45:45.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:99:0)
[15:45:45.004] Timer tick, asking Buildbucket for the build status
[15:45:45.376] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:45.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:100:0) after 1m0s
[15:46:45.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:100:0)
[15:46:45.432] Timer tick, asking Buildbucket for the build status
[15:46:45.593] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:45.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:101:0) after 1m0s
[15:47:45.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:101:0)
[15:47:45.697] Timer tick, asking Buildbucket for the build status
[15:47:45.866] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:45.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:102:0) after 1m0s
[15:48:45.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:102:0)
[15:48:45.903] Timer tick, asking Buildbucket for the build status
[15:48:46.047] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:46.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:103:0) after 1m0s
[15:49:47.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:103:0)
[15:49:47.047] Timer tick, asking Buildbucket for the build status
[15:49:47.282] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:47.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:104:0) after 1m0s
[15:50:47.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:104:0)
[15:50:47.456] Timer tick, asking Buildbucket for the build status
[15:50:47.904] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:47.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:105:0) after 1m0s
[15:51:48.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:105:0)
[15:51:48.199] Timer tick, asking Buildbucket for the build status
[15:51:48.532] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:48.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:106:0) after 1m0s
[15:52:48.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:106:0)
[15:52:48.822] Timer tick, asking Buildbucket for the build status
[15:52:48.932] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:48.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:107:0) after 1m0s
[15:53:48.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:107:0)
[15:53:48.960] Timer tick, asking Buildbucket for the build status
[15:53:49.312] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:49.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:108:0) after 1m0s
[15:54:49.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:108:0)
[15:54:49.431] Timer tick, asking Buildbucket for the build status
[15:54:49.641] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:49.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:109:0) after 1m0s
[15:55:49.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:109:0)
[15:55:49.672] Timer tick, asking Buildbucket for the build status
[15:55:50.070] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:50.070] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:110:0) after 1m0s
[15:56:50.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:110:0)
[15:56:50.131] Timer tick, asking Buildbucket for the build status
[15:56:50.332] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:50.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:111:0) after 1m0s
[15:57:50.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:111:0)
[15:57:50.664] Timer tick, asking Buildbucket for the build status
[15:57:52.115] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:52.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:112:0) after 1m0s
[15:58:52.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:112:0)
[15:58:52.171] Timer tick, asking Buildbucket for the build status
[15:58:52.284] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:52.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:113:0) after 1m0s
[15:59:52.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:113:0)
[15:59:52.555] Timer tick, asking Buildbucket for the build status
[15:59:52.709] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:52.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:114:0) after 1m0s
[16:00:52.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:114:0)
[16:00:52.781] Timer tick, asking Buildbucket for the build status
[16:00:52.895] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:52.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:115:0) after 1m0s
[16:01:52.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:115:0)
[16:01:52.917] Timer tick, asking Buildbucket for the build status
[16:01:53.239] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:53.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:116:0) after 1m0s
[16:02:53.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:116:0)
[16:02:53.231] Timer tick, asking Buildbucket for the build status
[16:02:53.930] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:53.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:117:0) after 1m0s
[16:03:54.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:117:0)
[16:03:54.087] Timer tick, asking Buildbucket for the build status
[16:03:54.272] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:54.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:118:0) after 1m0s
[16:04:54.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:118:0)
[16:04:54.315] Timer tick, asking Buildbucket for the build status
[16:04:54.445] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:54.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:119:0) after 1m0s
[16:05:54.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:119:0)
[16:05:54.648] Timer tick, asking Buildbucket for the build status
[16:05:56.837] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:56.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:120:0) after 1m0s
[16:06:56.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:120:0)
[16:06:56.872] Timer tick, asking Buildbucket for the build status
[16:06:56.999] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:56.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:121:0) after 1m0s
[16:07:57.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:121:0)
[16:07:57.066] Timer tick, asking Buildbucket for the build status
[16:07:57.595] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:57.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:122:0) after 1m0s
[16:08:57.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:122:0)
[16:08:57.691] Timer tick, asking Buildbucket for the build status
[16:08:59.458] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:59.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:123:0) after 1m0s
[16:09:59.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:123:0)
[16:09:59.531] Timer tick, asking Buildbucket for the build status
[16:10:00.137] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:00.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:124:0) after 1m0s
[16:11:00.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:124:0)
[16:11:00.253] Timer tick, asking Buildbucket for the build status
[16:11:00.444] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:00.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:125:0) after 1m0s
[16:12:00.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:125:0)
[16:12:00.528] Timer tick, asking Buildbucket for the build status
[16:12:01.128] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:01.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:126:0) after 1m0s
[16:13:01.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:126:0)
[16:13:01.257] Timer tick, asking Buildbucket for the build status
[16:13:01.415] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:01.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:127:0) after 1m0s
[16:14:01.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:127:0)
[16:14:01.711] Timer tick, asking Buildbucket for the build status
[16:14:02.001] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:02.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:128:0) after 1m0s
[16:15:02.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:128:0)
[16:15:02.053] Timer tick, asking Buildbucket for the build status
[16:15:02.510] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:02.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:129:0) after 1m0s
[16:16:02.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:129:0)
[16:16:02.595] Timer tick, asking Buildbucket for the build status
[16:16:03.022] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:03.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:130:0) after 1m0s
[16:17:03.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:130:0)
[16:17:03.054] Timer tick, asking Buildbucket for the build status
[16:17:03.741] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:03.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:131:0) after 1m0s
[16:18:03.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:131:0)
[16:18:03.787] Timer tick, asking Buildbucket for the build status
[16:18:04.317] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:04.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:132:0) after 1m0s
[16:19:04.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:132:0)
[16:19:04.433] Timer tick, asking Buildbucket for the build status
[16:19:04.568] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:04.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:133:0) after 1m0s
[16:20:04.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:133:0)
[16:20:04.555] Timer tick, asking Buildbucket for the build status
[16:20:04.877] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:04.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:134:0) after 1m0s
[16:21:04.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:134:0)
[16:21:04.895] Timer tick, asking Buildbucket for the build status
[16:21:05.117] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:05.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:135:0) after 1m0s
[16:22:05.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:135:0)
[16:22:05.193] Timer tick, asking Buildbucket for the build status
[16:22:06.032] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:06.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:136:0) after 1m0s
[16:23:06.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:136:0)
[16:23:06.068] Timer tick, asking Buildbucket for the build status
[16:23:06.226] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:06.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:137:0) after 1m0s
[16:24:06.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:137:0)
[16:24:06.263] Timer tick, asking Buildbucket for the build status
[16:24:06.473] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:06.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:138:0) after 1m0s
[16:25:06.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:138:0)
[16:25:06.487] Timer tick, asking Buildbucket for the build status
[16:25:06.828] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:06.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:139:0) after 1m0s
[16:26:06.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:139:0)
[16:26:06.883] Timer tick, asking Buildbucket for the build status
[16:26:07.033] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:07.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:140:0) after 1m0s
[16:27:07.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:140:0)
[16:27:07.067] Timer tick, asking Buildbucket for the build status
[16:27:09.585] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:09.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:141:0) after 1m0s
[16:28:09.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:141:0)
[16:28:09.679] Timer tick, asking Buildbucket for the build status
[16:28:09.991] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:09.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:142:0) after 1m0s
[16:29:10.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:142:0)
[16:29:10.015] Timer tick, asking Buildbucket for the build status
[16:29:10.146] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:10.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:143:0) after 1m0s
[16:30:10.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:143:0)
[16:30:10.272] Timer tick, asking Buildbucket for the build status
[16:30:13.011] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:13.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:144:0) after 1m0s
[16:31:13.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:144:0)
[16:31:13.063] Timer tick, asking Buildbucket for the build status
[16:31:13.276] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:13.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:145:0) after 1m0s
[16:32:13.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:145:0)
[16:32:13.384] Timer tick, asking Buildbucket for the build status
[16:32:13.530] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:13.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:146:0) after 1m0s
[16:33:13.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:146:0)
[16:33:13.637] Timer tick, asking Buildbucket for the build status
[16:33:18.995] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:18.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:147:0) after 1m0s
[16:34:19.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:147:0)
[16:34:19.102] Timer tick, asking Buildbucket for the build status
[16:34:19.471] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:19.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:148:0) after 1m0s
[16:35:19.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:148:0)
[16:35:19.515] Timer tick, asking Buildbucket for the build status
[16:35:19.684] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:19.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:149:0) after 1m0s
[16:36:19.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:149:0)
[16:36:19.831] Timer tick, asking Buildbucket for the build status
[16:36:20.017] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:20.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:150:0) after 1m0s
[16:37:20.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:150:0)
[16:37:20.223] Timer tick, asking Buildbucket for the build status
[16:37:20.927] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:20.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:151:0) after 1m0s
[16:38:20.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:151:0)
[16:38:20.963] Timer tick, asking Buildbucket for the build status
[16:38:21.237] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:21.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:152:0) after 1m0s
[16:39:21.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:152:0)
[16:39:21.277] Timer tick, asking Buildbucket for the build status
[16:39:21.457] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:21.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:153:0) after 1m0s
[16:40:21.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:153:0)
[16:40:21.589] Timer tick, asking Buildbucket for the build status
[16:40:21.771] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:21.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:154:0) after 1m0s
[16:41:21.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:154:0)
[16:41:21.766] Timer tick, asking Buildbucket for the build status
[16:41:21.938] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:21.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:155:0) after 1m0s
[16:42:22.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:155:0)
[16:42:22.048] Timer tick, asking Buildbucket for the build status
[16:42:22.802] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:22.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:156:0) after 1m0s
[16:43:22.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:156:0)
[16:43:22.857] Timer tick, asking Buildbucket for the build status
[16:43:23.228] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:23.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:157:0) after 1m0s
[16:44:23.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:157:0)
[16:44:23.844] Timer tick, asking Buildbucket for the build status
[16:44:24.001] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:24.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:158:0) after 1m0s
[16:45:24.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:158:0)
[16:45:24.044] Timer tick, asking Buildbucket for the build status
[16:45:24.382] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:24.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:159:0) after 1m0s
[16:46:24.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:159:0)
[16:46:24.397] Timer tick, asking Buildbucket for the build status
[16:46:24.560] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:24.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:160:0) after 1m0s
[16:47:24.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:160:0)
[16:47:24.625] Timer tick, asking Buildbucket for the build status
[16:47:24.772] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:24.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:161:0) after 1m0s
[16:48:24.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:161:0)
[16:48:24.782] Timer tick, asking Buildbucket for the build status
[16:48:25.134] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:25.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:162:0) after 1m0s
[16:49:25.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:162:0)
[16:49:25.179] Timer tick, asking Buildbucket for the build status
[16:49:25.296] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:25.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:163:0) after 1m0s
[16:50:25.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:163:0)
[16:50:25.396] Timer tick, asking Buildbucket for the build status
[16:50:25.714] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:25.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:164:0) after 1m0s
[16:51:26.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:164:0)
[16:51:26.511] Timer tick, asking Buildbucket for the build status
[16:51:26.763] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:26.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:165:0) after 1m0s
[16:52:27.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:165:0)
[16:52:27.023] Timer tick, asking Buildbucket for the build status
[16:52:27.179] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:27.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:166:0) after 1m0s
[16:53:27.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:166:0)
[16:53:27.485] Timer tick, asking Buildbucket for the build status
[16:53:27.623] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:27.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:167:0) after 1m0s
[16:54:27.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:167:0)
[16:54:27.737] Timer tick, asking Buildbucket for the build status
[16:54:28.177] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:28.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:168:0) after 1m0s
[16:55:28.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:168:0)
[16:55:28.346] Timer tick, asking Buildbucket for the build status
[16:55:28.499] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:28.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:169:0) after 1m0s
[16:56:28.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:169:0)
[16:56:28.809] Timer tick, asking Buildbucket for the build status
[16:56:33.560] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:33.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:170:0) after 1m0s
[16:57:34.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:170:0)
[16:57:34.841] Timer tick, asking Buildbucket for the build status
[16:57:35.349] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:35.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:171:0) after 1m0s
[16:58:35.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:171:0)
[16:58:35.553] Timer tick, asking Buildbucket for the build status
[16:58:36.077] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:36.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:172:0) after 1m0s
[16:59:36.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:172:0)
[16:59:36.176] Timer tick, asking Buildbucket for the build status
[16:59:36.324] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:36.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:173:0) after 1m0s
[17:00:36.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:173:0)
[17:00:36.441] Timer tick, asking Buildbucket for the build status
[17:00:36.658] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:36.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:174:0) after 1m0s
[17:01:36.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:174:0)
[17:01:36.838] Timer tick, asking Buildbucket for the build status
[17:01:36.992] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:36.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:175:0) after 1m0s
[17:02:37.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:175:0)
[17:02:37.295] Timer tick, asking Buildbucket for the build status
[17:02:37.517] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:37.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:176:0) after 1m0s
[17:03:37.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:176:0)
[17:03:37.794] Timer tick, asking Buildbucket for the build status
[17:03:39.468] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:39.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:177:0) after 1m0s
[17:04:40.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:177:0)
[17:04:40.076] Timer tick, asking Buildbucket for the build status
[17:04:40.256] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:40.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:178:0) after 1m0s
[17:05:40.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:178:0)
[17:05:40.323] Timer tick, asking Buildbucket for the build status
[17:05:40.709] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:40.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:179:0) after 1m0s
[17:06:40.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:179:0)
[17:06:40.813] Timer tick, asking Buildbucket for the build status
[17:06:41.185] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:41.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:180:0) after 1m0s
[17:07:41.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:180:0)
[17:07:41.220] Timer tick, asking Buildbucket for the build status
[17:07:41.366] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:41.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:181:0) after 1m0s
[17:08:41.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:181:0)
[17:08:41.481] Timer tick, asking Buildbucket for the build status
[17:08:41.665] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:41.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:182:0) after 1m0s
[17:09:41.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:182:0)
[17:09:41.735] Timer tick, asking Buildbucket for the build status
[17:09:41.908] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:41.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:183:0) after 1m0s
[17:10:42.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:183:0)
[17:10:42.039] Timer tick, asking Buildbucket for the build status
[17:10:42.708] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:42.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:184:0) after 1m0s
[17:11:42.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:184:0)
[17:11:42.821] Timer tick, asking Buildbucket for the build status
[17:11:43.307] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:43.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:185:0) after 1m0s
[17:12:43.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:185:0)
[17:12:43.379] Timer tick, asking Buildbucket for the build status
[17:12:43.592] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:43.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:186:0) after 1m0s
[17:13:43.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:186:0)
[17:13:43.713] Timer tick, asking Buildbucket for the build status
[17:13:43.886] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:43.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:187:0) after 1m0s
[17:14:43.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:187:0)
[17:14:43.953] Timer tick, asking Buildbucket for the build status
[17:14:44.657] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:44.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:188:0) after 1m0s
[17:15:44.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:188:0)
[17:15:44.728] Timer tick, asking Buildbucket for the build status
[17:15:44.927] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:44.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:189:0) after 1m0s
[17:16:44.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:189:0)
[17:16:44.978] Timer tick, asking Buildbucket for the build status
[17:16:45.361] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:45.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:190:0) after 1m0s
[17:17:45.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:190:0)
[17:17:45.427] Timer tick, asking Buildbucket for the build status
[17:17:45.551] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:45.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:191:0) after 1m0s
[17:18:45.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:191:0)
[17:18:45.662] Timer tick, asking Buildbucket for the build status
[17:18:45.816] Build 8875335744748189424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:45.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9040765807448910560:192:0) after 1m0s
[17:19:30.115] Received PubSub notification, asking Buildbucket for the build status
[17:19:30.318] Build 8875335744748189424: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:19:30.318] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1594228768987554",
    "created_by": "project:infra",
    "created_ts": "1594217272959688",
    "id": "8875335744748189424",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4196.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b810865d-a1b1-4c54-9a46-c15f110e26df\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4196.2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-n3gq\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"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\": [\"5320-752003c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1594217314077205",
    "status": "COMPLETED",
    "status_changed_ts": "1594228769718363",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9040765807448910560",
      "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/8875335744748189424/+/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:4d4792a1b0d38010",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1594228769778319",
    "url": "https://ci.chromium.org/b/8875335744748189424",
    "utcnow_ts": "1594228770308760"
  }
}
[17:19:30.318] Invocation finished in 3h11m39.318573622s with status SUCCEEDED