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.4224.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[19:48:07.176] New invocation is queued and will start shortly
[19:48:07.176] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[19:48:08.825] Starting the invocation (attempt 1)
[19:48:08.940] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:48:08.940] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:48:08.940] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9038207684496747472",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4224.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"c6bc942f-11d9-4cea-b56c-05a532b1c750\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4224.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:9038207684496747472",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[19:48:09.829] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1596656889117175",
    "id": "8872777621795595232",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4224.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c6bc942f-11d9-4cea-b56c-05a532b1c750\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4224.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1596656889478949",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9038207684496747472",
      "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/8872777621795595232/+/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": "1596656889479164",
    "url": "https://ci.chromium.org/b/8872777621795595232",
    "utcnow_ts": "1596656889818250"
  }
}
[19:48:09.829] Task URL: https://ci.chromium.org/b/8872777621795595232
[19:48:09.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:2:0) after 1m0s
[19:48:48.177] Received PubSub notification, asking Buildbucket for the build status
[19:48:48.266] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:09.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:2:0)
[19:49:09.893] Timer tick, asking Buildbucket for the build status
[19:49:10.004] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:10.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:4:0) after 1m0s
[19:50:10.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:4:0)
[19:50:10.115] Timer tick, asking Buildbucket for the build status
[19:50:10.394] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:10.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:5:0) after 1m0s
[19:51:10.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:5:0)
[19:51:10.525] Timer tick, asking Buildbucket for the build status
[19:51:10.634] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:10.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:6:0) after 1m0s
[19:52:10.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:6:0)
[19:52:10.688] Timer tick, asking Buildbucket for the build status
[19:52:10.808] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:10.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:7:0) after 1m0s
[19:53:10.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:7:0)
[19:53:10.879] Timer tick, asking Buildbucket for the build status
[19:53:10.941] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:10.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:8:0) after 1m0s
[19:54:11.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:8:0)
[19:54:11.014] Timer tick, asking Buildbucket for the build status
[19:54:11.168] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:11.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:9:0) after 1m0s
[19:55:11.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:9:0)
[19:55:11.208] Timer tick, asking Buildbucket for the build status
[19:55:11.460] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:11.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:10:0) after 1m0s
[19:56:11.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:10:0)
[19:56:11.461] Timer tick, asking Buildbucket for the build status
[19:56:11.762] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:11.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:11:0) after 1m0s
[19:57:11.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:11:0)
[19:57:11.793] Timer tick, asking Buildbucket for the build status
[19:57:12.073] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:12.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:12:0) after 1m0s
[19:58:12.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:12:0)
[19:58:12.158] Timer tick, asking Buildbucket for the build status
[19:58:12.366] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:12.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:13:0) after 1m0s
[19:59:12.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:13:0)
[19:59:12.477] Timer tick, asking Buildbucket for the build status
[19:59:12.699] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:12.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:14:0) after 1m0s
[20:00:12.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:14:0)
[20:00:12.730] Timer tick, asking Buildbucket for the build status
[20:00:12.917] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:12.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:15:0) after 1m0s
[20:01:13.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:15:0)
[20:01:13.043] Timer tick, asking Buildbucket for the build status
[20:01:13.310] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:13.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:16:0) after 1m0s
[20:02:13.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:16:0)
[20:02:13.323] Timer tick, asking Buildbucket for the build status
[20:02:13.413] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:13.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:17:0) after 1m0s
[20:03:13.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:17:0)
[20:03:13.464] Timer tick, asking Buildbucket for the build status
[20:03:13.678] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:13.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:18:0) after 1m0s
[20:04:13.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:18:0)
[20:04:13.704] Timer tick, asking Buildbucket for the build status
[20:04:15.807] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:15.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:19:0) after 1m0s
[20:05:15.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:19:0)
[20:05:15.858] Timer tick, asking Buildbucket for the build status
[20:05:15.966] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:15.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:20:0) after 1m0s
[20:06:15.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:20:0)
[20:06:15.991] Timer tick, asking Buildbucket for the build status
[20:06:16.101] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:16.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:21:0) after 1m0s
[20:07:16.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:21:0)
[20:07:16.185] Timer tick, asking Buildbucket for the build status
[20:07:16.251] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:16.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:22:0) after 1m0s
[20:08:16.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:22:0)
[20:08:16.362] Timer tick, asking Buildbucket for the build status
[20:08:16.601] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:16.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:23:0) after 1m0s
[20:09:16.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:23:0)
[20:09:16.667] Timer tick, asking Buildbucket for the build status
[20:09:16.852] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:16.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:24:0) after 1m0s
[20:10:16.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:24:0)
[20:10:16.972] Timer tick, asking Buildbucket for the build status
[20:10:17.174] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:17.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:25:0) after 1m0s
[20:11:17.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:25:0)
[20:11:17.261] Timer tick, asking Buildbucket for the build status
[20:11:17.629] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:17.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:26:0) after 1m0s
[20:12:17.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:26:0)
[20:12:17.657] Timer tick, asking Buildbucket for the build status
[20:12:17.743] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:17.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:27:0) after 1m0s
[20:13:17.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:27:0)
[20:13:17.776] Timer tick, asking Buildbucket for the build status
[20:13:18.121] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:18.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:28:0) after 1m0s
[20:14:18.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:28:0)
[20:14:18.149] Timer tick, asking Buildbucket for the build status
[20:14:18.249] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:18.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:29:0) after 1m0s
[20:15:18.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:29:0)
[20:15:18.351] Timer tick, asking Buildbucket for the build status
[20:15:18.487] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:18.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:30:0) after 1m0s
[20:16:18.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:30:0)
[20:16:18.578] Timer tick, asking Buildbucket for the build status
[20:16:19.168] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:19.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:31:0) after 1m0s
[20:17:19.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:31:0)
[20:17:19.187] Timer tick, asking Buildbucket for the build status
[20:17:19.357] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:19.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:32:0) after 1m0s
[20:18:19.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:32:0)
[20:18:19.388] Timer tick, asking Buildbucket for the build status
[20:18:19.559] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:19.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:33:0) after 1m0s
[20:19:19.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:33:0)
[20:19:19.590] Timer tick, asking Buildbucket for the build status
[20:19:19.662] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:19.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:34:0) after 1m0s
[20:20:19.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:34:0)
[20:20:19.855] Timer tick, asking Buildbucket for the build status
[20:20:20.002] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:20.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:35:0) after 1m0s
[20:21:20.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:35:0)
[20:21:20.029] Timer tick, asking Buildbucket for the build status
[20:21:20.104] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:20.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:36:0) after 1m0s
[20:22:20.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:36:0)
[20:22:20.127] Timer tick, asking Buildbucket for the build status
[20:22:20.202] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:20.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:37:0) after 1m0s
[20:23:20.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:37:0)
[20:23:20.347] Timer tick, asking Buildbucket for the build status
[20:23:20.568] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:20.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:38:0) after 1m0s
[20:24:20.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:38:0)
[20:24:20.671] Timer tick, asking Buildbucket for the build status
[20:24:20.752] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:20.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:39:0) after 1m0s
[20:25:20.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:39:0)
[20:25:20.793] Timer tick, asking Buildbucket for the build status
[20:25:20.864] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:20.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:40:0) after 1m0s
[20:26:20.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:40:0)
[20:26:20.896] Timer tick, asking Buildbucket for the build status
[20:26:20.993] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:20.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:41:0) after 1m0s
[20:27:21.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:41:0)
[20:27:21.046] Timer tick, asking Buildbucket for the build status
[20:27:21.198] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:21.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:42:0) after 1m0s
[20:28:21.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:42:0)
[20:28:21.229] Timer tick, asking Buildbucket for the build status
[20:28:21.450] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:21.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:43:0) after 1m0s
[20:29:21.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:43:0)
[20:29:21.530] Timer tick, asking Buildbucket for the build status
[20:29:21.613] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:21.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:44:0) after 1m0s
[20:30:21.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:44:0)
[20:30:21.642] Timer tick, asking Buildbucket for the build status
[20:30:21.719] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:21.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:45:0) after 1m0s
[20:31:22.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:45:0)
[20:31:22.090] Timer tick, asking Buildbucket for the build status
[20:31:22.700] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:22.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:46:0) after 1m0s
[20:32:22.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:46:0)
[20:32:22.833] Timer tick, asking Buildbucket for the build status
[20:32:22.922] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:22.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:47:0) after 1m0s
[20:33:23.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:47:0)
[20:33:23.129] Timer tick, asking Buildbucket for the build status
[20:33:23.256] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:23.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:48:0) after 1m0s
[20:34:23.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:48:0)
[20:34:23.497] Timer tick, asking Buildbucket for the build status
[20:34:23.571] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:23.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:49:0) after 1m0s
[20:35:23.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:49:0)
[20:35:23.705] Timer tick, asking Buildbucket for the build status
[20:35:23.768] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:23.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:50:0) after 1m0s
[20:36:23.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:50:0)
[20:36:23.932] Timer tick, asking Buildbucket for the build status
[20:36:24.141] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:24.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:51:0) after 1m0s
[20:37:24.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:51:0)
[20:37:24.201] Timer tick, asking Buildbucket for the build status
[20:37:24.267] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:24.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:52:0) after 1m0s
[20:38:24.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:52:0)
[20:38:24.396] Timer tick, asking Buildbucket for the build status
[20:38:24.839] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:24.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:53:0) after 1m0s
[20:39:25.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:53:0)
[20:39:25.305] Timer tick, asking Buildbucket for the build status
[20:39:25.386] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:25.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:54:0) after 1m0s
[20:40:25.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:54:0)
[20:40:25.526] Timer tick, asking Buildbucket for the build status
[20:40:25.733] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:25.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:55:0) after 1m0s
[20:41:25.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:55:0)
[20:41:25.945] Timer tick, asking Buildbucket for the build status
[20:41:26.287] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:26.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:56:0) after 1m0s
[20:42:26.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:56:0)
[20:42:26.425] Timer tick, asking Buildbucket for the build status
[20:42:26.574] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:26.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:57:0) after 1m0s
[20:43:26.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:57:0)
[20:43:26.691] Timer tick, asking Buildbucket for the build status
[20:43:26.845] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:26.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:58:0) after 1m0s
[20:44:26.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:58:0)
[20:44:26.930] Timer tick, asking Buildbucket for the build status
[20:44:27.063] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:27.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:59:0) after 1m0s
[20:45:27.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:59:0)
[20:45:27.219] Timer tick, asking Buildbucket for the build status
[20:45:27.683] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:27.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:60:0) after 1m0s
[20:46:27.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:60:0)
[20:46:27.901] Timer tick, asking Buildbucket for the build status
[20:46:28.003] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:28.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:61:0) after 1m0s
[20:47:28.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:61:0)
[20:47:28.058] Timer tick, asking Buildbucket for the build status
[20:47:28.168] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:28.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:62:0) after 1m0s
[20:48:28.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:62:0)
[20:48:28.339] Timer tick, asking Buildbucket for the build status
[20:48:28.463] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:28.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:63:0) after 1m0s
[20:49:28.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:63:0)
[20:49:28.570] Timer tick, asking Buildbucket for the build status
[20:49:28.645] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:28.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:64:0) after 1m0s
[20:50:28.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:64:0)
[20:50:28.675] Timer tick, asking Buildbucket for the build status
[20:50:28.765] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:28.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:65:0) after 1m0s
[20:51:28.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:65:0)
[20:51:28.922] Timer tick, asking Buildbucket for the build status
[20:51:29.010] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:29.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:66:0) after 1m0s
[20:52:29.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:66:0)
[20:52:29.164] Timer tick, asking Buildbucket for the build status
[20:52:29.249] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:29.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:67:0) after 1m0s
[20:53:29.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:67:0)
[20:53:29.373] Timer tick, asking Buildbucket for the build status
[20:53:29.634] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:29.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:68:0) after 1m0s
[20:54:29.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:68:0)
[20:54:29.776] Timer tick, asking Buildbucket for the build status
[20:54:29.913] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:29.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:69:0) after 1m0s
[20:55:30.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:69:0)
[20:55:30.051] Timer tick, asking Buildbucket for the build status
[20:55:30.144] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:30.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:70:0) after 1m0s
[20:56:30.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:70:0)
[20:56:30.384] Timer tick, asking Buildbucket for the build status
[20:56:30.545] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:30.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:71:0) after 1m0s
[20:57:30.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:71:0)
[20:57:30.783] Timer tick, asking Buildbucket for the build status
[20:57:31.071] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:31.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:72:0) after 1m0s
[20:58:31.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:72:0)
[20:58:31.281] Timer tick, asking Buildbucket for the build status
[20:58:31.684] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:31.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:73:0) after 1m0s
[20:59:31.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:73:0)
[20:59:31.715] Timer tick, asking Buildbucket for the build status
[20:59:31.814] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:31.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:74:0) after 1m0s
[21:00:31.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:74:0)
[21:00:31.889] Timer tick, asking Buildbucket for the build status
[21:00:32.584] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:32.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:75:0) after 1m0s
[21:01:32.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:75:0)
[21:01:32.942] Timer tick, asking Buildbucket for the build status
[21:01:33.025] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:33.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:76:0) after 1m0s
[21:02:33.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:76:0)
[21:02:33.146] Timer tick, asking Buildbucket for the build status
[21:02:33.217] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:33.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:77:0) after 1m0s
[21:03:33.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:77:0)
[21:03:33.295] Timer tick, asking Buildbucket for the build status
[21:03:33.526] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:33.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:78:0) after 1m0s
[21:04:33.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:78:0)
[21:04:33.549] Timer tick, asking Buildbucket for the build status
[21:04:35.121] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:35.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:79:0) after 1m0s
[21:05:35.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:79:0)
[21:05:35.264] Timer tick, asking Buildbucket for the build status
[21:05:35.434] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:35.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:80:0) after 1m0s
[21:06:35.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:80:0)
[21:06:35.457] Timer tick, asking Buildbucket for the build status
[21:06:35.523] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:35.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:81:0) after 1m0s
[21:07:35.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:81:0)
[21:07:35.509] Timer tick, asking Buildbucket for the build status
[21:07:35.714] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:35.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:82:0) after 1m0s
[21:08:35.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:82:0)
[21:08:35.747] Timer tick, asking Buildbucket for the build status
[21:08:36.105] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:36.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:83:0) after 1m0s
[21:09:36.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:83:0)
[21:09:36.161] Timer tick, asking Buildbucket for the build status
[21:09:36.262] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:36.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:84:0) after 1m0s
[21:10:36.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:84:0)
[21:10:36.370] Timer tick, asking Buildbucket for the build status
[21:10:36.447] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:36.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:85:0) after 1m0s
[21:11:36.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:85:0)
[21:11:36.636] Timer tick, asking Buildbucket for the build status
[21:11:36.713] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:36.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:86:0) after 1m0s
[21:12:36.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:86:0)
[21:12:36.790] Timer tick, asking Buildbucket for the build status
[21:12:36.876] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:36.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:87:0) after 1m0s
[21:13:37.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:87:0)
[21:13:37.024] Timer tick, asking Buildbucket for the build status
[21:13:37.151] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:37.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:88:0) after 1m0s
[21:14:37.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:88:0)
[21:14:37.177] Timer tick, asking Buildbucket for the build status
[21:14:37.245] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:37.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:89:0) after 1m0s
[21:15:37.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:89:0)
[21:15:37.275] Timer tick, asking Buildbucket for the build status
[21:15:37.397] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:37.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:90:0) after 1m0s
[21:16:37.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:90:0)
[21:16:37.418] Timer tick, asking Buildbucket for the build status
[21:16:37.471] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:37.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:91:0) after 1m0s
[21:17:37.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:91:0)
[21:17:37.499] Timer tick, asking Buildbucket for the build status
[21:17:37.618] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:37.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:92:0) after 1m0s
[21:18:37.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:92:0)
[21:18:37.836] Timer tick, asking Buildbucket for the build status
[21:18:38.183] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:38.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:93:0) after 1m0s
[21:19:38.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:93:0)
[21:19:38.179] Timer tick, asking Buildbucket for the build status
[21:19:38.521] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:38.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:94:0) after 1m0s
[21:20:38.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:94:0)
[21:20:38.566] Timer tick, asking Buildbucket for the build status
[21:20:38.835] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:38.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:95:0) after 1m0s
[21:21:38.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:95:0)
[21:21:38.838] Timer tick, asking Buildbucket for the build status
[21:21:38.973] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:38.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:96:0) after 1m0s
[21:22:39.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:96:0)
[21:22:39.008] Timer tick, asking Buildbucket for the build status
[21:22:39.382] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:39.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:97:0) after 1m0s
[21:23:39.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:97:0)
[21:23:39.426] Timer tick, asking Buildbucket for the build status
[21:23:39.859] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:39.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:98:0) after 1m0s
[21:24:39.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:98:0)
[21:24:39.899] Timer tick, asking Buildbucket for the build status
[21:24:40.275] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:40.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:99:0) after 1m0s
[21:25:40.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:99:0)
[21:25:40.351] Timer tick, asking Buildbucket for the build status
[21:25:40.409] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:40.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:100:0) after 1m0s
[21:26:40.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:100:0)
[21:26:40.468] Timer tick, asking Buildbucket for the build status
[21:26:40.556] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:40.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:101:0) after 1m0s
[21:27:40.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:101:0)
[21:27:40.622] Timer tick, asking Buildbucket for the build status
[21:27:40.709] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:40.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:102:0) after 1m0s
[21:28:40.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:102:0)
[21:28:40.799] Timer tick, asking Buildbucket for the build status
[21:28:40.940] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:40.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:103:0) after 1m0s
[21:29:41.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:103:0)
[21:29:41.014] Timer tick, asking Buildbucket for the build status
[21:29:41.083] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:41.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:104:0) after 1m0s
[21:30:41.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:104:0)
[21:30:41.138] Timer tick, asking Buildbucket for the build status
[21:30:41.228] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:41.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:105:0) after 1m0s
[21:31:41.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:105:0)
[21:31:41.303] Timer tick, asking Buildbucket for the build status
[21:31:41.740] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:41.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:106:0) after 1m0s
[21:32:41.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:106:0)
[21:32:41.733] Timer tick, asking Buildbucket for the build status
[21:32:41.810] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:41.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:107:0) after 1m0s
[21:33:41.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:107:0)
[21:33:41.847] Timer tick, asking Buildbucket for the build status
[21:33:42.196] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:42.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:108:0) after 1m0s
[21:34:42.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:108:0)
[21:34:42.264] Timer tick, asking Buildbucket for the build status
[21:34:42.378] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:42.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:109:0) after 1m0s
[21:35:42.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:109:0)
[21:35:42.427] Timer tick, asking Buildbucket for the build status
[21:35:42.525] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:42.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:110:0) after 1m0s
[21:36:42.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:110:0)
[21:36:42.575] Timer tick, asking Buildbucket for the build status
[21:36:42.908] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:42.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:111:0) after 1m0s
[21:37:42.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:111:0)
[21:37:42.948] Timer tick, asking Buildbucket for the build status
[21:37:43.018] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:43.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:112:0) after 1m0s
[21:38:43.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:112:0)
[21:38:43.050] Timer tick, asking Buildbucket for the build status
[21:38:43.256] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:43.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:113:0) after 1m0s
[21:39:43.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:113:0)
[21:39:43.276] Timer tick, asking Buildbucket for the build status
[21:39:43.424] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:43.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:114:0) after 1m0s
[21:40:43.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:114:0)
[21:40:43.433] Timer tick, asking Buildbucket for the build status
[21:40:43.511] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:43.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:115:0) after 1m0s
[21:41:43.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:115:0)
[21:41:43.598] Timer tick, asking Buildbucket for the build status
[21:41:43.672] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:43.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:116:0) after 1m0s
[21:42:43.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:116:0)
[21:42:43.743] Timer tick, asking Buildbucket for the build status
[21:42:43.990] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:43.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:117:0) after 1m0s
[21:43:43.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:117:0)
[21:43:43.966] Timer tick, asking Buildbucket for the build status
[21:43:44.224] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:44.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:118:0) after 1m0s
[21:44:44.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:118:0)
[21:44:44.266] Timer tick, asking Buildbucket for the build status
[21:44:44.335] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:44.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:119:0) after 1m0s
[21:45:44.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:119:0)
[21:45:44.422] Timer tick, asking Buildbucket for the build status
[21:45:44.662] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:44.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:120:0) after 1m0s
[21:46:44.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:120:0)
[21:46:44.726] Timer tick, asking Buildbucket for the build status
[21:46:44.832] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:44.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:121:0) after 1m0s
[21:47:44.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:121:0)
[21:47:44.875] Timer tick, asking Buildbucket for the build status
[21:47:45.120] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:45.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:122:0) after 1m0s
[21:48:45.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:122:0)
[21:48:45.149] Timer tick, asking Buildbucket for the build status
[21:48:45.412] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:45.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:123:0) after 1m0s
[21:49:45.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:123:0)
[21:49:45.435] Timer tick, asking Buildbucket for the build status
[21:49:45.738] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:45.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:124:0) after 1m0s
[21:50:45.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:124:0)
[21:50:45.835] Timer tick, asking Buildbucket for the build status
[21:50:45.927] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:45.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:125:0) after 1m0s
[21:51:46.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:125:0)
[21:51:46.055] Timer tick, asking Buildbucket for the build status
[21:51:46.112] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:46.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:126:0) after 1m0s
[21:52:46.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:126:0)
[21:52:46.288] Timer tick, asking Buildbucket for the build status
[21:52:46.366] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:46.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:127:0) after 1m0s
[21:53:46.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:127:0)
[21:53:46.413] Timer tick, asking Buildbucket for the build status
[21:53:46.621] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:46.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:128:0) after 1m0s
[21:54:46.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:128:0)
[21:54:46.683] Timer tick, asking Buildbucket for the build status
[21:54:46.771] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:46.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:129:0) after 1m0s
[21:55:46.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:129:0)
[21:55:46.808] Timer tick, asking Buildbucket for the build status
[21:55:46.885] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:46.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:130:0) after 1m0s
[21:56:46.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:130:0)
[21:56:46.984] Timer tick, asking Buildbucket for the build status
[21:56:47.077] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:47.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:131:0) after 1m0s
[21:57:47.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:131:0)
[21:57:47.118] Timer tick, asking Buildbucket for the build status
[21:57:47.234] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:47.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:132:0) after 1m0s
[21:58:47.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:132:0)
[21:58:47.364] Timer tick, asking Buildbucket for the build status
[21:58:47.500] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:47.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:133:0) after 1m0s
[21:59:47.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:133:0)
[21:59:47.721] Timer tick, asking Buildbucket for the build status
[21:59:47.804] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:47.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:134:0) after 1m0s
[22:00:47.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:134:0)
[22:00:47.927] Timer tick, asking Buildbucket for the build status
[22:00:48.013] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:48.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:135:0) after 1m0s
[22:01:48.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:135:0)
[22:01:48.112] Timer tick, asking Buildbucket for the build status
[22:01:48.177] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:48.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:136:0) after 1m0s
[22:02:48.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:136:0)
[22:02:48.298] Timer tick, asking Buildbucket for the build status
[22:02:48.361] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:48.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:137:0) after 1m0s
[22:03:48.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:137:0)
[22:03:48.428] Timer tick, asking Buildbucket for the build status
[22:03:48.543] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:48.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:138:0) after 1m0s
[22:04:48.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:138:0)
[22:04:48.630] Timer tick, asking Buildbucket for the build status
[22:04:48.719] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:48.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:139:0) after 1m0s
[22:05:48.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:139:0)
[22:05:48.749] Timer tick, asking Buildbucket for the build status
[22:05:48.849] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:48.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:140:0) after 1m0s
[22:06:48.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:140:0)
[22:06:48.876] Timer tick, asking Buildbucket for the build status
[22:06:49.290] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:49.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:141:0) after 1m0s
[22:07:49.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:141:0)
[22:07:49.355] Timer tick, asking Buildbucket for the build status
[22:07:49.615] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:49.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:142:0) after 1m0s
[22:08:49.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:142:0)
[22:08:49.623] Timer tick, asking Buildbucket for the build status
[22:08:49.739] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:49.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:143:0) after 1m0s
[22:09:49.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:143:0)
[22:09:49.837] Timer tick, asking Buildbucket for the build status
[22:09:49.925] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:49.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:144:0) after 1m0s
[22:10:49.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:144:0)
[22:10:49.965] Timer tick, asking Buildbucket for the build status
[22:10:50.175] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:50.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:145:0) after 1m0s
[22:11:50.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:145:0)
[22:11:50.219] Timer tick, asking Buildbucket for the build status
[22:11:50.510] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:50.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:146:0) after 1m0s
[22:12:50.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:146:0)
[22:12:50.637] Timer tick, asking Buildbucket for the build status
[22:12:50.956] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:50.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:147:0) after 1m0s
[22:13:51.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:147:0)
[22:13:51.014] Timer tick, asking Buildbucket for the build status
[22:13:51.281] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:51.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:148:0) after 1m0s
[22:14:51.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:148:0)
[22:14:51.354] Timer tick, asking Buildbucket for the build status
[22:14:51.630] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:51.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:149:0) after 1m0s
[22:15:51.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:149:0)
[22:15:51.692] Timer tick, asking Buildbucket for the build status
[22:15:51.858] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:51.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:150:0) after 1m0s
[22:16:51.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:150:0)
[22:16:51.917] Timer tick, asking Buildbucket for the build status
[22:16:52.168] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:52.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:151:0) after 1m0s
[22:17:52.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:151:0)
[22:17:52.212] Timer tick, asking Buildbucket for the build status
[22:17:52.321] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:52.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:152:0) after 1m0s
[22:18:52.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:152:0)
[22:18:52.323] Timer tick, asking Buildbucket for the build status
[22:18:52.448] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:52.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:153:0) after 1m0s
[22:19:52.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:153:0)
[22:19:52.454] Timer tick, asking Buildbucket for the build status
[22:19:52.526] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:52.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:154:0) after 1m0s
[22:20:52.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:154:0)
[22:20:52.599] Timer tick, asking Buildbucket for the build status
[22:20:52.693] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:52.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:155:0) after 1m0s
[22:21:52.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:155:0)
[22:21:52.730] Timer tick, asking Buildbucket for the build status
[22:21:52.839] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:52.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:156:0) after 1m0s
[22:22:52.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:156:0)
[22:22:52.816] Timer tick, asking Buildbucket for the build status
[22:22:52.909] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:52.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:157:0) after 1m0s
[22:23:53.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:157:0)
[22:23:53.046] Timer tick, asking Buildbucket for the build status
[22:23:53.211] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:53.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:158:0) after 1m0s
[22:24:53.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:158:0)
[22:24:53.279] Timer tick, asking Buildbucket for the build status
[22:24:53.341] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:53.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:159:0) after 1m0s
[22:25:53.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:159:0)
[22:25:53.375] Timer tick, asking Buildbucket for the build status
[22:25:53.475] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:53.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:160:0) after 1m0s
[22:26:53.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:160:0)
[22:26:53.535] Timer tick, asking Buildbucket for the build status
[22:26:53.696] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:53.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:161:0) after 1m0s
[22:27:53.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:161:0)
[22:27:53.728] Timer tick, asking Buildbucket for the build status
[22:27:53.790] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:53.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:162:0) after 1m0s
[22:28:53.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:162:0)
[22:28:53.799] Timer tick, asking Buildbucket for the build status
[22:28:53.920] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:53.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:163:0) after 1m0s
[22:29:53.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:163:0)
[22:29:53.946] Timer tick, asking Buildbucket for the build status
[22:29:54.312] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:54.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:164:0) after 1m0s
[22:30:54.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:164:0)
[22:30:54.589] Timer tick, asking Buildbucket for the build status
[22:30:54.733] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:54.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:165:0) after 1m0s
[22:31:54.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:165:0)
[22:31:54.795] Timer tick, asking Buildbucket for the build status
[22:31:54.861] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:54.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:166:0) after 1m0s
[22:32:54.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:166:0)
[22:32:54.998] Timer tick, asking Buildbucket for the build status
[22:32:55.078] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:55.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:167:0) after 1m0s
[22:33:55.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:167:0)
[22:33:55.306] Timer tick, asking Buildbucket for the build status
[22:33:55.443] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:55.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:168:0) after 1m0s
[22:34:55.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:168:0)
[22:34:55.524] Timer tick, asking Buildbucket for the build status
[22:34:55.822] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:55.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:169:0) after 1m0s
[22:35:56.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:169:0)
[22:35:56.643] Timer tick, asking Buildbucket for the build status
[22:35:56.827] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:56.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:170:0) after 1m0s
[22:36:56.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:170:0)
[22:36:56.903] Timer tick, asking Buildbucket for the build status
[22:36:57.062] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:57.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:171:0) after 1m0s
[22:37:57.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:171:0)
[22:37:57.102] Timer tick, asking Buildbucket for the build status
[22:37:57.188] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:57.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:172:0) after 1m0s
[22:38:57.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:172:0)
[22:38:57.301] Timer tick, asking Buildbucket for the build status
[22:38:57.498] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:57.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:173:0) after 1m0s
[22:39:57.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:173:0)
[22:39:57.560] Timer tick, asking Buildbucket for the build status
[22:39:57.628] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:57.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:174:0) after 1m0s
[22:40:57.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:174:0)
[22:40:57.734] Timer tick, asking Buildbucket for the build status
[22:40:58.136] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:58.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:175:0) after 1m0s
[22:41:58.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:175:0)
[22:41:58.190] Timer tick, asking Buildbucket for the build status
[22:41:58.251] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:58.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:176:0) after 1m0s
[22:42:58.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:176:0)
[22:42:58.353] Timer tick, asking Buildbucket for the build status
[22:42:58.423] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:58.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:177:0) after 1m0s
[22:43:58.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:177:0)
[22:43:58.487] Timer tick, asking Buildbucket for the build status
[22:43:58.622] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:58.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:178:0) after 1m0s
[22:44:58.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:178:0)
[22:44:58.781] Timer tick, asking Buildbucket for the build status
[22:44:58.878] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:58.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:179:0) after 1m0s
[22:45:58.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:179:0)
[22:45:58.900] Timer tick, asking Buildbucket for the build status
[22:45:58.967] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:58.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:180:0) after 1m0s
[22:46:59.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:180:0)
[22:46:59.037] Timer tick, asking Buildbucket for the build status
[22:46:59.287] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:59.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:181:0) after 1m0s
[22:47:59.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:181:0)
[22:47:59.346] Timer tick, asking Buildbucket for the build status
[22:47:59.942] Build 8872777621795595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:59.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038207684496747472:182:0) after 1m0s
[22:48:20.821] Received PubSub notification, asking Buildbucket for the build status
[22:48:20.896] Build 8872777621795595232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:48:20.896] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1596667699702720",
    "created_by": "project:infra",
    "created_ts": "1596656889117175",
    "id": "8872777621795595232",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4224.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c6bc942f-11d9-4cea-b56c-05a532b1c750\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4224.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-vqrk\"], \"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\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5436-adf7f8a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1596656924997813",
    "status": "COMPLETED",
    "status_changed_ts": "1596667700574662",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9038207684496747472",
      "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/8872777621795595232/+/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:4dd8fc38a245e410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1596667700588789",
    "url": "https://ci.chromium.org/b/8872777621795595232",
    "utcnow_ts": "1596667700886934"
  }
}
[22:48:20.896] Invocation finished in 3h0m13.738486503s with status SUCCEEDED