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

• parent_buildername:publish_tarball
• user_agent:recipe

[03:06:54.156] New invocation is queued and will start shortly
[03:06:54.156] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[03:06:55.296] Starting the invocation (attempt 1)
[03:06:55.354] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:06:55.354] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:06:55.354] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9026855457857035680",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4349.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"16fd2ed6-c9c3-442b-91db-03fc2a6cd685\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4349.3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9026855457857035680",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[03:06:55.748] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1607483215473983",
    "id": "8861425395810670256",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4349.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"16fd2ed6-c9c3-442b-91db-03fc2a6cd685\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4349.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1607483215628645",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026855457857035680",
      "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/8861425395810670256/+/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": "1607483215628754",
    "url": "https://ci.chromium.org/b/8861425395810670256",
    "utcnow_ts": "1607483215740864"
  }
}
[03:06:55.748] Task URL: https://ci.chromium.org/b/8861425395810670256
[03:06:55.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:2:0) after 1m0s
[03:07:30.627] Received PubSub notification, asking Buildbucket for the build status
[03:07:30.731] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:55.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:2:0)
[03:07:55.764] Timer tick, asking Buildbucket for the build status
[03:07:56.054] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:56.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:4:0) after 1m0s
[03:08:56.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:4:0)
[03:08:56.073] Timer tick, asking Buildbucket for the build status
[03:08:56.206] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:56.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:5:0) after 1m0s
[03:09:56.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:5:0)
[03:09:56.225] Timer tick, asking Buildbucket for the build status
[03:09:57.403] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:57.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:6:0) after 1m0s
[03:10:57.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:6:0)
[03:10:57.447] Timer tick, asking Buildbucket for the build status
[03:10:57.528] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:57.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:7:0) after 1m0s
[03:11:57.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:7:0)
[03:11:57.532] Timer tick, asking Buildbucket for the build status
[03:11:57.642] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:57.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:8:0) after 1m0s
[03:12:57.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:8:0)
[03:12:57.683] Timer tick, asking Buildbucket for the build status
[03:12:57.822] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:57.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:9:0) after 1m0s
[03:13:57.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:9:0)
[03:13:57.908] Timer tick, asking Buildbucket for the build status
[03:13:57.978] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:57.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:10:0) after 1m0s
[03:14:58.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:10:0)
[03:14:58.003] Timer tick, asking Buildbucket for the build status
[03:14:58.251] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:58.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:11:0) after 1m0s
[03:15:58.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:11:0)
[03:15:58.274] Timer tick, asking Buildbucket for the build status
[03:15:58.343] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:58.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:12:0) after 1m0s
[03:16:58.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:12:0)
[03:16:58.408] Timer tick, asking Buildbucket for the build status
[03:16:58.525] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:58.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:13:0) after 1m0s
[03:17:58.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:13:0)
[03:17:58.538] Timer tick, asking Buildbucket for the build status
[03:17:58.603] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:58.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:14:0) after 1m0s
[03:18:58.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:14:0)
[03:18:58.679] Timer tick, asking Buildbucket for the build status
[03:18:58.742] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:58.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:15:0) after 1m0s
[03:19:58.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:15:0)
[03:19:58.765] Timer tick, asking Buildbucket for the build status
[03:19:58.841] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:58.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:16:0) after 1m0s
[03:20:58.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:16:0)
[03:20:58.825] Timer tick, asking Buildbucket for the build status
[03:20:58.887] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:58.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:17:0) after 1m0s
[03:21:58.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:17:0)
[03:21:58.925] Timer tick, asking Buildbucket for the build status
[03:21:59.288] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:59.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:18:0) after 1m0s
[03:22:59.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:18:0)
[03:22:59.323] Timer tick, asking Buildbucket for the build status
[03:22:59.403] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:59.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:19:0) after 1m0s
[03:23:59.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:19:0)
[03:23:59.420] Timer tick, asking Buildbucket for the build status
[03:23:59.476] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:59.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:20:0) after 1m0s
[03:24:59.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:20:0)
[03:24:59.494] Timer tick, asking Buildbucket for the build status
[03:24:59.564] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:59.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:21:0) after 1m0s
[03:25:59.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:21:0)
[03:25:59.540] Timer tick, asking Buildbucket for the build status
[03:25:59.609] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:59.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:22:0) after 1m0s
[03:26:59.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:22:0)
[03:26:59.650] Timer tick, asking Buildbucket for the build status
[03:26:59.984] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:59.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:23:0) after 1m0s
[03:28:00.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:23:0)
[03:28:00.011] Timer tick, asking Buildbucket for the build status
[03:28:00.610] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:00.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:24:0) after 1m0s
[03:29:01.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:24:0)
[03:29:01.334] Timer tick, asking Buildbucket for the build status
[03:29:01.548] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:01.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:25:0) after 1m0s
[03:30:01.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:25:0)
[03:30:01.580] Timer tick, asking Buildbucket for the build status
[03:30:01.655] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:01.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:26:0) after 1m0s
[03:31:01.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:26:0)
[03:31:01.655] Timer tick, asking Buildbucket for the build status
[03:31:01.733] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:01.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:27:0) after 1m0s
[03:32:01.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:27:0)
[03:32:01.766] Timer tick, asking Buildbucket for the build status
[03:32:01.920] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:01.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:28:0) after 1m0s
[03:33:02.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:28:0)
[03:33:02.432] Timer tick, asking Buildbucket for the build status
[03:33:02.754] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:02.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:29:0) after 1m0s
[03:34:02.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:29:0)
[03:34:02.778] Timer tick, asking Buildbucket for the build status
[03:34:02.860] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:02.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:30:0) after 1m0s
[03:35:02.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:30:0)
[03:35:02.898] Timer tick, asking Buildbucket for the build status
[03:35:02.974] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:02.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:31:0) after 1m0s
[03:36:03.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:31:0)
[03:36:03.013] Timer tick, asking Buildbucket for the build status
[03:36:03.118] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:03.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:32:0) after 1m0s
[03:37:03.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:32:0)
[03:37:03.140] Timer tick, asking Buildbucket for the build status
[03:37:03.457] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:03.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:33:0) after 1m0s
[03:38:03.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:33:0)
[03:38:03.519] Timer tick, asking Buildbucket for the build status
[03:38:03.590] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:03.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:34:0) after 1m0s
[03:39:03.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:34:0)
[03:39:03.622] Timer tick, asking Buildbucket for the build status
[03:39:03.708] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:03.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:35:0) after 1m0s
[03:40:03.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:35:0)
[03:40:03.853] Timer tick, asking Buildbucket for the build status
[03:40:03.928] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:03.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:36:0) after 1m0s
[03:41:04.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:36:0)
[03:41:04.324] Timer tick, asking Buildbucket for the build status
[03:41:04.519] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:04.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:37:0) after 1m0s
[03:42:04.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:37:0)
[03:42:04.972] Timer tick, asking Buildbucket for the build status
[03:42:05.084] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:05.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:38:0) after 1m0s
[03:43:05.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:38:0)
[03:43:05.237] Timer tick, asking Buildbucket for the build status
[03:43:06.098] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:06.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:39:0) after 1m0s
[03:44:06.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:39:0)
[03:44:06.117] Timer tick, asking Buildbucket for the build status
[03:44:06.265] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:06.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:40:0) after 1m0s
[03:45:06.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:40:0)
[03:45:06.364] Timer tick, asking Buildbucket for the build status
[03:45:06.432] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:06.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:41:0) after 1m0s
[03:46:06.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:41:0)
[03:46:06.504] Timer tick, asking Buildbucket for the build status
[03:46:06.573] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:06.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:42:0) after 1m0s
[03:47:06.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:42:0)
[03:47:06.691] Timer tick, asking Buildbucket for the build status
[03:47:06.813] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:06.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:43:0) after 1m0s
[03:48:06.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:43:0)
[03:48:06.867] Timer tick, asking Buildbucket for the build status
[03:48:06.943] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:06.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:44:0) after 1m0s
[03:49:07.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:44:0)
[03:49:07.027] Timer tick, asking Buildbucket for the build status
[03:49:07.088] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:07.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:45:0) after 1m0s
[03:50:07.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:45:0)
[03:50:07.296] Timer tick, asking Buildbucket for the build status
[03:50:07.371] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:07.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:46:0) after 1m0s
[03:51:07.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:46:0)
[03:51:07.429] Timer tick, asking Buildbucket for the build status
[03:51:07.490] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:07.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:47:0) after 1m0s
[03:52:07.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:47:0)
[03:52:07.609] Timer tick, asking Buildbucket for the build status
[03:52:07.930] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:07.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:48:0) after 1m0s
[03:53:08.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:48:0)
[03:53:08.011] Timer tick, asking Buildbucket for the build status
[03:53:08.086] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:08.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:49:0) after 1m0s
[03:54:08.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:49:0)
[03:54:08.223] Timer tick, asking Buildbucket for the build status
[03:54:08.281] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:08.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:50:0) after 1m0s
[03:55:08.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:50:0)
[03:55:08.464] Timer tick, asking Buildbucket for the build status
[03:55:08.612] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:08.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:51:0) after 1m0s
[03:56:08.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:51:0)
[03:56:08.690] Timer tick, asking Buildbucket for the build status
[03:56:08.905] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:08.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:52:0) after 1m0s
[03:57:09.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:52:0)
[03:57:09.050] Timer tick, asking Buildbucket for the build status
[03:57:09.153] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:09.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:53:0) after 1m0s
[03:58:09.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:53:0)
[03:58:09.302] Timer tick, asking Buildbucket for the build status
[03:58:10.104] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:10.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:54:0) after 1m0s
[03:59:10.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:54:0)
[03:59:10.188] Timer tick, asking Buildbucket for the build status
[03:59:10.250] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:10.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:55:0) after 1m0s
[04:00:10.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:55:0)
[04:00:10.280] Timer tick, asking Buildbucket for the build status
[04:00:10.605] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:10.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:56:0) after 1m0s
[04:01:10.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:56:0)
[04:01:10.881] Timer tick, asking Buildbucket for the build status
[04:01:10.965] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:10.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:57:0) after 1m0s
[04:02:11.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:57:0)
[04:02:11.160] Timer tick, asking Buildbucket for the build status
[04:02:11.303] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:11.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:58:0) after 1m0s
[04:03:11.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:58:0)
[04:03:11.404] Timer tick, asking Buildbucket for the build status
[04:03:11.891] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:11.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:59:0) after 1m0s
[04:04:11.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:59:0)
[04:04:11.964] Timer tick, asking Buildbucket for the build status
[04:04:12.035] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:12.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:60:0) after 1m0s
[04:05:12.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:60:0)
[04:05:12.056] Timer tick, asking Buildbucket for the build status
[04:05:12.112] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:12.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:61:0) after 1m0s
[04:06:12.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:61:0)
[04:06:12.168] Timer tick, asking Buildbucket for the build status
[04:06:12.229] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:12.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:62:0) after 1m0s
[04:07:12.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:62:0)
[04:07:12.266] Timer tick, asking Buildbucket for the build status
[04:07:12.378] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:12.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:63:0) after 1m0s
[04:08:12.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:63:0)
[04:08:12.510] Timer tick, asking Buildbucket for the build status
[04:08:12.772] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:12.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:64:0) after 1m0s
[04:09:12.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:64:0)
[04:09:12.868] Timer tick, asking Buildbucket for the build status
[04:09:13.344] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:13.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:65:0) after 1m0s
[04:10:13.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:65:0)
[04:10:13.423] Timer tick, asking Buildbucket for the build status
[04:10:13.492] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:13.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:66:0) after 1m0s
[04:11:13.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:66:0)
[04:11:13.545] Timer tick, asking Buildbucket for the build status
[04:11:14.076] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:14.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:67:0) after 1m0s
[04:12:14.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:67:0)
[04:12:14.165] Timer tick, asking Buildbucket for the build status
[04:12:14.547] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:14.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:68:0) after 1m0s
[04:13:14.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:68:0)
[04:13:14.590] Timer tick, asking Buildbucket for the build status
[04:13:14.708] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:14.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:69:0) after 1m0s
[04:14:14.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:69:0)
[04:14:14.772] Timer tick, asking Buildbucket for the build status
[04:14:15.100] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:15.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:70:0) after 1m0s
[04:15:15.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:70:0)
[04:15:15.160] Timer tick, asking Buildbucket for the build status
[04:15:15.401] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:15.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:71:0) after 1m0s
[04:16:15.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:71:0)
[04:16:15.443] Timer tick, asking Buildbucket for the build status
[04:16:15.658] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:15.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:72:0) after 1m0s
[04:17:15.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:72:0)
[04:17:15.720] Timer tick, asking Buildbucket for the build status
[04:17:15.822] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:15.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:73:0) after 1m0s
[04:18:15.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:73:0)
[04:18:15.930] Timer tick, asking Buildbucket for the build status
[04:18:15.987] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:15.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:74:0) after 1m0s
[04:19:16.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:74:0)
[04:19:16.014] Timer tick, asking Buildbucket for the build status
[04:19:16.220] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:16.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:75:0) after 1m0s
[04:20:16.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:75:0)
[04:20:16.307] Timer tick, asking Buildbucket for the build status
[04:20:16.368] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:16.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:76:0) after 1m0s
[04:21:16.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:76:0)
[04:21:16.391] Timer tick, asking Buildbucket for the build status
[04:21:16.446] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:16.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:77:0) after 1m0s
[04:22:16.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:77:0)
[04:22:16.461] Timer tick, asking Buildbucket for the build status
[04:22:16.625] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:16.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:78:0) after 1m0s
[04:23:16.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:78:0)
[04:23:16.685] Timer tick, asking Buildbucket for the build status
[04:23:16.754] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:16.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:79:0) after 1m0s
[04:24:16.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:79:0)
[04:24:16.770] Timer tick, asking Buildbucket for the build status
[04:24:16.836] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:16.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:80:0) after 1m0s
[04:25:16.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:80:0)
[04:25:16.874] Timer tick, asking Buildbucket for the build status
[04:25:17.035] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:17.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:81:0) after 1m0s
[04:26:17.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:81:0)
[04:26:17.115] Timer tick, asking Buildbucket for the build status
[04:26:17.258] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:17.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:82:0) after 1m0s
[04:27:17.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:82:0)
[04:27:17.301] Timer tick, asking Buildbucket for the build status
[04:27:17.421] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:17.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:83:0) after 1m0s
[04:28:17.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:83:0)
[04:28:17.474] Timer tick, asking Buildbucket for the build status
[04:28:17.571] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:17.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:84:0) after 1m0s
[04:29:17.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:84:0)
[04:29:17.588] Timer tick, asking Buildbucket for the build status
[04:29:17.911] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:17.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:85:0) after 1m0s
[04:30:17.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:85:0)
[04:30:17.943] Timer tick, asking Buildbucket for the build status
[04:30:18.163] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:18.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:86:0) after 1m0s
[04:31:18.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:86:0)
[04:31:18.186] Timer tick, asking Buildbucket for the build status
[04:31:18.254] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:18.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:87:0) after 1m0s
[04:32:18.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:87:0)
[04:32:18.237] Timer tick, asking Buildbucket for the build status
[04:32:18.358] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:18.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:88:0) after 1m0s
[04:33:18.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:88:0)
[04:33:18.447] Timer tick, asking Buildbucket for the build status
[04:33:18.500] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:18.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:89:0) after 1m0s
[04:34:18.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:89:0)
[04:34:18.546] Timer tick, asking Buildbucket for the build status
[04:34:18.605] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:18.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:90:0) after 1m0s
[04:35:18.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:90:0)
[04:35:18.649] Timer tick, asking Buildbucket for the build status
[04:35:18.726] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:18.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:91:0) after 1m0s
[04:36:18.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:91:0)
[04:36:18.769] Timer tick, asking Buildbucket for the build status
[04:36:19.099] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:19.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:92:0) after 1m0s
[04:37:19.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:92:0)
[04:37:19.180] Timer tick, asking Buildbucket for the build status
[04:37:19.379] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:19.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:93:0) after 1m0s
[04:38:19.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:93:0)
[04:38:19.503] Timer tick, asking Buildbucket for the build status
[04:38:21.068] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:21.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:94:0) after 1m0s
[04:39:21.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:94:0)
[04:39:21.145] Timer tick, asking Buildbucket for the build status
[04:39:21.310] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:21.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:95:0) after 1m0s
[04:40:21.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:95:0)
[04:40:21.351] Timer tick, asking Buildbucket for the build status
[04:40:21.406] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:21.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:96:0) after 1m0s
[04:41:21.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:96:0)
[04:41:21.429] Timer tick, asking Buildbucket for the build status
[04:41:21.488] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:21.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:97:0) after 1m0s
[04:42:21.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:97:0)
[04:42:21.702] Timer tick, asking Buildbucket for the build status
[04:42:21.994] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:21.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:98:0) after 1m0s
[04:43:22.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:98:0)
[04:43:22.072] Timer tick, asking Buildbucket for the build status
[04:43:22.143] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:22.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:99:0) after 1m0s
[04:44:22.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:99:0)
[04:44:22.169] Timer tick, asking Buildbucket for the build status
[04:44:22.241] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:22.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:100:0) after 1m0s
[04:45:22.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:100:0)
[04:45:22.266] Timer tick, asking Buildbucket for the build status
[04:45:22.326] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:22.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:101:0) after 1m0s
[04:46:22.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:101:0)
[04:46:22.398] Timer tick, asking Buildbucket for the build status
[04:46:22.453] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:22.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:102:0) after 1m0s
[04:47:22.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:102:0)
[04:47:22.491] Timer tick, asking Buildbucket for the build status
[04:47:22.610] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:22.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:103:0) after 1m0s
[04:48:22.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:103:0)
[04:48:22.712] Timer tick, asking Buildbucket for the build status
[04:48:22.769] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:22.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:104:0) after 1m0s
[04:49:23.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:104:0)
[04:49:23.126] Timer tick, asking Buildbucket for the build status
[04:49:23.218] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:23.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:105:0) after 1m0s
[04:50:23.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:105:0)
[04:50:23.341] Timer tick, asking Buildbucket for the build status
[04:50:23.404] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:23.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:106:0) after 1m0s
[04:51:23.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:106:0)
[04:51:23.514] Timer tick, asking Buildbucket for the build status
[04:51:23.988] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:23.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:107:0) after 1m0s
[04:52:24.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:107:0)
[04:52:24.046] Timer tick, asking Buildbucket for the build status
[04:52:24.163] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:24.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:108:0) after 1m0s
[04:53:24.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:108:0)
[04:53:24.208] Timer tick, asking Buildbucket for the build status
[04:53:24.496] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:24.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:109:0) after 1m0s
[04:54:24.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:109:0)
[04:54:24.517] Timer tick, asking Buildbucket for the build status
[04:54:24.582] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:24.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:110:0) after 1m0s
[04:55:24.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:110:0)
[04:55:24.711] Timer tick, asking Buildbucket for the build status
[04:55:24.806] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:24.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:111:0) after 1m0s
[04:56:25.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:111:0)
[04:56:25.020] Timer tick, asking Buildbucket for the build status
[04:56:25.091] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:25.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:112:0) after 1m0s
[04:57:25.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:112:0)
[04:57:25.285] Timer tick, asking Buildbucket for the build status
[04:57:25.386] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:25.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:113:0) after 1m0s
[04:58:25.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:113:0)
[04:58:25.468] Timer tick, asking Buildbucket for the build status
[04:58:25.847] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:25.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:114:0) after 1m0s
[04:59:25.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:114:0)
[04:59:25.867] Timer tick, asking Buildbucket for the build status
[04:59:25.933] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:25.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:115:0) after 1m0s
[05:00:25.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:115:0)
[05:00:25.964] Timer tick, asking Buildbucket for the build status
[05:00:26.551] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:26.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:116:0) after 1m0s
[05:01:26.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:116:0)
[05:01:26.622] Timer tick, asking Buildbucket for the build status
[05:01:26.682] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:26.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:117:0) after 1m0s
[05:02:26.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:117:0)
[05:02:26.734] Timer tick, asking Buildbucket for the build status
[05:02:26.924] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:26.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:118:0) after 1m0s
[05:03:26.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:118:0)
[05:03:26.967] Timer tick, asking Buildbucket for the build status
[05:03:27.230] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:27.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:119:0) after 1m0s
[05:04:27.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:119:0)
[05:04:27.305] Timer tick, asking Buildbucket for the build status
[05:04:27.586] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:27.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:120:0) after 1m0s
[05:05:27.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:120:0)
[05:05:27.643] Timer tick, asking Buildbucket for the build status
[05:05:27.724] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:27.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:121:0) after 1m0s
[05:06:27.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:121:0)
[05:06:27.750] Timer tick, asking Buildbucket for the build status
[05:06:27.890] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:27.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:122:0) after 1m0s
[05:07:28.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:122:0)
[05:07:28.043] Timer tick, asking Buildbucket for the build status
[05:07:28.135] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:28.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:123:0) after 1m0s
[05:08:28.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:123:0)
[05:08:28.221] Timer tick, asking Buildbucket for the build status
[05:08:28.335] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:28.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:124:0) after 1m0s
[05:09:28.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:124:0)
[05:09:28.363] Timer tick, asking Buildbucket for the build status
[05:09:28.512] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:28.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:125:0) after 1m0s
[05:10:28.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:125:0)
[05:10:28.608] Timer tick, asking Buildbucket for the build status
[05:10:28.751] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:28.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:126:0) after 1m0s
[05:11:28.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:126:0)
[05:11:28.807] Timer tick, asking Buildbucket for the build status
[05:11:28.937] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:28.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:127:0) after 1m0s
[05:12:29.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:127:0)
[05:12:29.078] Timer tick, asking Buildbucket for the build status
[05:12:29.270] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:29.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:128:0) after 1m0s
[05:13:29.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:128:0)
[05:13:29.362] Timer tick, asking Buildbucket for the build status
[05:13:29.434] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:29.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:129:0) after 1m0s
[05:14:29.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:129:0)
[05:14:29.497] Timer tick, asking Buildbucket for the build status
[05:14:29.565] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:29.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:130:0) after 1m0s
[05:15:29.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:130:0)
[05:15:29.782] Timer tick, asking Buildbucket for the build status
[05:15:29.840] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:29.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:131:0) after 1m0s
[05:16:29.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:131:0)
[05:16:29.911] Timer tick, asking Buildbucket for the build status
[05:16:30.247] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:30.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:132:0) after 1m0s
[05:17:30.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:132:0)
[05:17:30.348] Timer tick, asking Buildbucket for the build status
[05:17:30.495] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:30.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:133:0) after 1m0s
[05:18:30.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:133:0)
[05:18:30.518] Timer tick, asking Buildbucket for the build status
[05:18:30.579] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:30.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:134:0) after 1m0s
[05:19:30.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:134:0)
[05:19:30.927] Timer tick, asking Buildbucket for the build status
[05:19:30.985] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:30.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:135:0) after 1m0s
[05:20:31.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:135:0)
[05:20:31.139] Timer tick, asking Buildbucket for the build status
[05:20:31.203] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:31.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:136:0) after 1m0s
[05:21:31.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:136:0)
[05:21:31.187] Timer tick, asking Buildbucket for the build status
[05:21:31.250] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:31.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:137:0) after 1m0s
[05:22:31.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:137:0)
[05:22:31.375] Timer tick, asking Buildbucket for the build status
[05:22:31.473] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:31.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:138:0) after 1m0s
[05:23:31.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:138:0)
[05:23:31.534] Timer tick, asking Buildbucket for the build status
[05:23:31.592] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:31.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:139:0) after 1m0s
[05:24:32.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:139:0)
[05:24:32.112] Timer tick, asking Buildbucket for the build status
[05:24:33.398] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:33.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:140:0) after 1m0s
[05:25:34.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:140:0)
[05:25:34.021] Timer tick, asking Buildbucket for the build status
[05:25:34.083] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:34.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:141:0) after 1m0s
[05:26:34.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:141:0)
[05:26:34.186] Timer tick, asking Buildbucket for the build status
[05:26:34.247] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:34.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:142:0) after 1m0s
[05:27:34.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:142:0)
[05:27:34.361] Timer tick, asking Buildbucket for the build status
[05:27:34.520] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:34.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:143:0) after 1m0s
[05:28:34.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:143:0)
[05:28:34.764] Timer tick, asking Buildbucket for the build status
[05:28:34.832] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:34.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:144:0) after 1m0s
[05:29:35.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:144:0)
[05:29:35.009] Timer tick, asking Buildbucket for the build status
[05:29:35.078] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:35.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:145:0) after 1m0s
[05:30:35.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:145:0)
[05:30:35.098] Timer tick, asking Buildbucket for the build status
[05:30:35.222] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:35.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:146:0) after 1m0s
[05:31:36.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:146:0)
[05:31:36.514] Timer tick, asking Buildbucket for the build status
[05:31:36.632] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:36.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:147:0) after 1m0s
[05:32:36.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:147:0)
[05:32:36.715] Timer tick, asking Buildbucket for the build status
[05:32:36.813] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:36.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:148:0) after 1m0s
[05:33:37.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:148:0)
[05:33:37.040] Timer tick, asking Buildbucket for the build status
[05:33:37.094] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:37.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:149:0) after 1m0s
[05:34:39.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:149:0)
[05:34:39.509] Timer tick, asking Buildbucket for the build status
[05:34:39.648] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:39.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:150:0) after 1m0s
[05:35:39.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:150:0)
[05:35:39.702] Timer tick, asking Buildbucket for the build status
[05:35:39.993] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:39.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:151:0) after 1m0s
[05:36:40.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:151:0)
[05:36:40.014] Timer tick, asking Buildbucket for the build status
[05:36:40.065] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:40.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:152:0) after 1m0s
[05:37:40.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:152:0)
[05:37:40.097] Timer tick, asking Buildbucket for the build status
[05:37:40.289] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:40.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:153:0) after 1m0s
[05:38:40.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:153:0)
[05:38:40.351] Timer tick, asking Buildbucket for the build status
[05:38:40.410] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:40.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:154:0) after 1m0s
[05:39:40.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:154:0)
[05:39:40.434] Timer tick, asking Buildbucket for the build status
[05:39:40.492] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:40.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:155:0) after 1m0s
[05:40:40.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:155:0)
[05:40:40.545] Timer tick, asking Buildbucket for the build status
[05:40:40.725] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:40.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:156:0) after 1m0s
[05:41:40.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:156:0)
[05:41:40.764] Timer tick, asking Buildbucket for the build status
[05:41:40.827] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:40.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:157:0) after 1m0s
[05:42:40.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:157:0)
[05:42:40.874] Timer tick, asking Buildbucket for the build status
[05:42:40.969] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:40.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:158:0) after 1m0s
[05:43:41.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:158:0)
[05:43:41.090] Timer tick, asking Buildbucket for the build status
[05:43:41.166] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:41.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:159:0) after 1m0s
[05:44:41.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:159:0)
[05:44:41.224] Timer tick, asking Buildbucket for the build status
[05:44:41.304] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:41.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:160:0) after 1m0s
[05:45:41.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:160:0)
[05:45:41.345] Timer tick, asking Buildbucket for the build status
[05:45:41.405] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:41.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:161:0) after 1m0s
[05:46:41.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:161:0)
[05:46:41.428] Timer tick, asking Buildbucket for the build status
[05:46:41.477] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:41.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:162:0) after 1m0s
[05:47:41.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:162:0)
[05:47:41.514] Timer tick, asking Buildbucket for the build status
[05:47:41.605] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:41.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:163:0) after 1m0s
[05:48:41.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:163:0)
[05:48:41.651] Timer tick, asking Buildbucket for the build status
[05:48:41.709] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:41.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:164:0) after 1m0s
[05:49:41.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:164:0)
[05:49:41.758] Timer tick, asking Buildbucket for the build status
[05:49:41.916] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:41.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:165:0) after 1m0s
[05:50:41.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:165:0)
[05:50:41.945] Timer tick, asking Buildbucket for the build status
[05:50:42.015] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:42.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:166:0) after 1m0s
[05:51:42.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:166:0)
[05:51:42.050] Timer tick, asking Buildbucket for the build status
[05:51:42.172] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:42.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:167:0) after 1m0s
[05:52:42.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:167:0)
[05:52:42.193] Timer tick, asking Buildbucket for the build status
[05:52:42.337] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:42.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:168:0) after 1m0s
[05:53:42.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:168:0)
[05:53:42.425] Timer tick, asking Buildbucket for the build status
[05:53:42.500] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:42.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:169:0) after 1m0s
[05:54:42.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:169:0)
[05:54:42.541] Timer tick, asking Buildbucket for the build status
[05:54:42.603] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:42.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:170:0) after 1m0s
[05:55:42.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:170:0)
[05:55:42.652] Timer tick, asking Buildbucket for the build status
[05:55:42.736] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:42.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:171:0) after 1m0s
[05:56:42.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:171:0)
[05:56:42.784] Timer tick, asking Buildbucket for the build status
[05:56:42.856] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:42.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:172:0) after 1m0s
[05:57:42.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:172:0)
[05:57:42.883] Timer tick, asking Buildbucket for the build status
[05:57:43.087] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:43.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:173:0) after 1m0s
[05:58:43.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:173:0)
[05:58:43.152] Timer tick, asking Buildbucket for the build status
[05:58:43.210] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:43.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:174:0) after 1m0s
[05:59:43.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:174:0)
[05:59:43.295] Timer tick, asking Buildbucket for the build status
[05:59:43.366] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:43.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:175:0) after 1m0s
[06:00:43.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:175:0)
[06:00:43.398] Timer tick, asking Buildbucket for the build status
[06:00:43.500] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:43.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:176:0) after 1m0s
[06:01:43.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:176:0)
[06:01:43.525] Timer tick, asking Buildbucket for the build status
[06:01:43.713] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:43.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:177:0) after 1m0s
[06:02:43.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:177:0)
[06:02:43.871] Timer tick, asking Buildbucket for the build status
[06:02:44.394] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:44.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:178:0) after 1m0s
[06:03:44.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:178:0)
[06:03:44.415] Timer tick, asking Buildbucket for the build status
[06:03:44.468] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:44.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:179:0) after 1m0s
[06:04:44.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:179:0)
[06:04:44.473] Timer tick, asking Buildbucket for the build status
[06:04:44.739] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:44.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:180:0) after 1m0s
[06:05:44.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:180:0)
[06:05:44.761] Timer tick, asking Buildbucket for the build status
[06:05:44.837] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:44.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:181:0) after 1m0s
[06:06:44.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:181:0)
[06:06:44.870] Timer tick, asking Buildbucket for the build status
[06:06:44.928] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:44.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:182:0) after 1m0s
[06:07:44.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:182:0)
[06:07:44.926] Timer tick, asking Buildbucket for the build status
[06:07:45.029] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:45.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:183:0) after 1m0s
[06:08:45.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:183:0)
[06:08:45.071] Timer tick, asking Buildbucket for the build status
[06:08:45.130] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:45.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:184:0) after 1m0s
[06:09:45.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:184:0)
[06:09:45.153] Timer tick, asking Buildbucket for the build status
[06:09:45.322] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:45.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:185:0) after 1m0s
[06:10:45.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:185:0)
[06:10:45.375] Timer tick, asking Buildbucket for the build status
[06:10:45.516] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:45.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:186:0) after 1m0s
[06:11:45.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:186:0)
[06:11:45.541] Timer tick, asking Buildbucket for the build status
[06:11:45.597] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:45.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:187:0) after 1m0s
[06:12:45.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:187:0)
[06:12:45.657] Timer tick, asking Buildbucket for the build status
[06:12:46.125] Build 8861425395810670256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:46.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9026855457857035680:188:0) after 1m0s
[06:13:10.115] Received PubSub notification, asking Buildbucket for the build status
[06:13:10.207] Build 8861425395810670256: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:13:10.207] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1607494388878769",
    "created_by": "project:infra",
    "created_ts": "1607483215473983",
    "id": "8861425395810670256",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4349.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"16fd2ed6-c9c3-442b-91db-03fc2a6cd685\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4349.3\"}}",
    "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-uj4s\"], \"image\": [\"chrome-xenial-20120600-c5cfbe11ce8\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1607483249220099",
    "status": "COMPLETED",
    "status_changed_ts": "1607494389577385",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9026855457857035680",
      "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/8861425395810670256/+/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:505e48d808b07a10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1607494389589984",
    "url": "https://ci.chromium.org/b/8861425395810670256",
    "utcnow_ts": "1607494390195036"
  }
}
[06:13:10.207] Invocation finished in 3h6m16.069213194s with status SUCCEEDED