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

• parent_buildername:publish_tarball
• user_agent:recipe

[01:57:34.000] New invocation is queued and will start shortly
[01:57:34.000] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[01:57:35.092] Starting the invocation (attempt 1)
[01:57:35.155] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:57:35.158] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:57:35.158] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9039543395391468624",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4183.38\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"79edc954-8e9f-4c9e-a871-60bf84bd8a8e\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4183.38\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9039543395391468624",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[01:57:36.592] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1595383055370758",
    "id": "8874113333290308560",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4183.38\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"79edc954-8e9f-4c9e-a871-60bf84bd8a8e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4183.38\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1595383056106058",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9039543395391468624",
      "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/8874113333290308560/+/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": "1595383056106197",
    "url": "https://ci.chromium.org/b/8874113333290308560",
    "utcnow_ts": "1595383056564397"
  }
}
[01:57:36.592] Task URL: https://ci.chromium.org/b/8874113333290308560
[01:57:36.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:2:0) after 1m0s
[01:58:35.404] Received PubSub notification, asking Buildbucket for the build status
[01:58:35.754] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:36.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:2:0)
[01:58:36.659] Timer tick, asking Buildbucket for the build status
[01:58:36.771] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:36.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:4:0) after 1m0s
[01:59:36.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:4:0)
[01:59:36.952] Timer tick, asking Buildbucket for the build status
[01:59:37.199] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:37.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:5:0) after 1m0s
[02:00:37.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:5:0)
[02:00:37.371] Timer tick, asking Buildbucket for the build status
[02:00:37.619] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:37.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:6:0) after 1m0s
[02:01:37.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:6:0)
[02:01:37.777] Timer tick, asking Buildbucket for the build status
[02:01:38.275] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:38.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:7:0) after 1m0s
[02:02:38.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:7:0)
[02:02:38.409] Timer tick, asking Buildbucket for the build status
[02:02:38.677] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:38.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:8:0) after 1m0s
[02:03:38.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:8:0)
[02:03:38.741] Timer tick, asking Buildbucket for the build status
[02:03:38.954] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:38.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:9:0) after 1m0s
[02:04:39.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:9:0)
[02:04:39.069] Timer tick, asking Buildbucket for the build status
[02:04:39.282] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:39.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:10:0) after 1m0s
[02:05:39.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:10:0)
[02:05:39.313] Timer tick, asking Buildbucket for the build status
[02:05:39.686] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:39.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:11:0) after 1m0s
[02:06:39.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:11:0)
[02:06:39.748] Timer tick, asking Buildbucket for the build status
[02:06:39.849] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:39.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:12:0) after 1m0s
[02:07:39.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:12:0)
[02:07:39.900] Timer tick, asking Buildbucket for the build status
[02:07:40.181] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:40.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:13:0) after 1m0s
[02:08:40.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:13:0)
[02:08:40.216] Timer tick, asking Buildbucket for the build status
[02:08:40.521] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:40.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:14:0) after 1m0s
[02:09:40.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:14:0)
[02:09:40.598] Timer tick, asking Buildbucket for the build status
[02:09:40.775] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:40.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:15:0) after 1m0s
[02:10:40.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:15:0)
[02:10:40.810] Timer tick, asking Buildbucket for the build status
[02:10:41.053] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:41.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:16:0) after 1m0s
[02:11:41.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:16:0)
[02:11:41.085] Timer tick, asking Buildbucket for the build status
[02:11:42.865] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:42.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:17:0) after 1m0s
[02:12:43.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:17:0)
[02:12:43.030] Timer tick, asking Buildbucket for the build status
[02:12:43.158] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:43.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:18:0) after 1m0s
[02:13:43.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:18:0)
[02:13:43.166] Timer tick, asking Buildbucket for the build status
[02:13:43.258] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:43.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:19:0) after 1m0s
[02:14:43.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:19:0)
[02:14:43.376] Timer tick, asking Buildbucket for the build status
[02:14:43.496] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:43.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:20:0) after 1m0s
[02:15:43.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:20:0)
[02:15:43.607] Timer tick, asking Buildbucket for the build status
[02:15:43.734] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:43.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:21:0) after 1m0s
[02:16:43.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:21:0)
[02:16:43.827] Timer tick, asking Buildbucket for the build status
[02:16:44.043] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:44.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:22:0) after 1m0s
[02:17:44.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:22:0)
[02:17:44.186] Timer tick, asking Buildbucket for the build status
[02:17:44.321] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:44.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:23:0) after 1m0s
[02:18:44.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:23:0)
[02:18:44.395] Timer tick, asking Buildbucket for the build status
[02:18:44.464] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:44.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:24:0) after 1m0s
[02:19:44.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:24:0)
[02:19:44.674] Timer tick, asking Buildbucket for the build status
[02:19:44.741] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:44.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:25:0) after 1m0s
[02:20:44.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:25:0)
[02:20:44.952] Timer tick, asking Buildbucket for the build status
[02:20:45.104] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:45.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:26:0) after 1m0s
[02:21:45.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:26:0)
[02:21:45.202] Timer tick, asking Buildbucket for the build status
[02:21:45.421] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:45.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:27:0) after 1m0s
[02:22:45.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:27:0)
[02:22:45.584] Timer tick, asking Buildbucket for the build status
[02:22:45.715] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:45.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:28:0) after 1m0s
[02:23:45.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:28:0)
[02:23:45.774] Timer tick, asking Buildbucket for the build status
[02:23:45.846] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:45.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:29:0) after 1m0s
[02:24:45.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:29:0)
[02:24:45.951] Timer tick, asking Buildbucket for the build status
[02:24:46.153] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:46.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:30:0) after 1m0s
[02:25:46.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:30:0)
[02:25:46.189] Timer tick, asking Buildbucket for the build status
[02:25:46.286] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:46.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:31:0) after 1m0s
[02:26:46.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:31:0)
[02:26:46.382] Timer tick, asking Buildbucket for the build status
[02:26:46.458] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:46.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:32:0) after 1m0s
[02:27:46.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:32:0)
[02:27:46.523] Timer tick, asking Buildbucket for the build status
[02:27:46.689] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:46.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:33:0) after 1m0s
[02:28:46.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:33:0)
[02:28:46.764] Timer tick, asking Buildbucket for the build status
[02:28:46.836] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:46.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:34:0) after 1m0s
[02:29:46.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:34:0)
[02:29:46.972] Timer tick, asking Buildbucket for the build status
[02:29:47.068] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:47.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:35:0) after 1m0s
[02:30:47.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:35:0)
[02:30:47.163] Timer tick, asking Buildbucket for the build status
[02:30:47.229] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:47.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:36:0) after 1m0s
[02:31:47.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:36:0)
[02:31:47.265] Timer tick, asking Buildbucket for the build status
[02:31:47.342] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:47.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:37:0) after 1m0s
[02:32:47.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:37:0)
[02:32:47.478] Timer tick, asking Buildbucket for the build status
[02:32:47.630] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:47.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:38:0) after 1m0s
[02:33:47.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:38:0)
[02:33:47.678] Timer tick, asking Buildbucket for the build status
[02:33:48.018] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:48.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:39:0) after 1m0s
[02:34:48.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:39:0)
[02:34:48.061] Timer tick, asking Buildbucket for the build status
[02:34:48.138] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:48.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:40:0) after 1m0s
[02:35:48.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:40:0)
[02:35:48.188] Timer tick, asking Buildbucket for the build status
[02:35:48.383] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:48.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:41:0) after 1m0s
[02:36:48.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:41:0)
[02:36:48.429] Timer tick, asking Buildbucket for the build status
[02:36:48.503] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:48.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:42:0) after 1m0s
[02:37:48.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:42:0)
[02:37:48.521] Timer tick, asking Buildbucket for the build status
[02:37:48.712] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:48.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:43:0) after 1m0s
[02:38:48.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:43:0)
[02:38:48.831] Timer tick, asking Buildbucket for the build status
[02:38:48.984] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:48.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:44:0) after 1m0s
[02:39:49.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:44:0)
[02:39:49.041] Timer tick, asking Buildbucket for the build status
[02:39:49.155] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:49.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:45:0) after 1m0s
[02:40:49.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:45:0)
[02:40:49.220] Timer tick, asking Buildbucket for the build status
[02:40:49.417] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:49.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:46:0) after 1m0s
[02:41:49.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:46:0)
[02:41:49.477] Timer tick, asking Buildbucket for the build status
[02:41:49.557] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:49.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:47:0) after 1m0s
[02:42:49.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:47:0)
[02:42:49.587] Timer tick, asking Buildbucket for the build status
[02:42:49.761] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:49.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:48:0) after 1m0s
[02:43:49.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:48:0)
[02:43:49.794] Timer tick, asking Buildbucket for the build status
[02:43:50.012] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:50.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:49:0) after 1m0s
[02:44:50.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:49:0)
[02:44:50.177] Timer tick, asking Buildbucket for the build status
[02:44:50.396] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:50.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:50:0) after 1m0s
[02:45:50.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:50:0)
[02:45:50.521] Timer tick, asking Buildbucket for the build status
[02:45:50.592] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:50.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:51:0) after 1m0s
[02:46:50.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:51:0)
[02:46:50.767] Timer tick, asking Buildbucket for the build status
[02:46:50.857] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:50.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:52:0) after 1m0s
[02:47:50.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:52:0)
[02:47:50.852] Timer tick, asking Buildbucket for the build status
[02:47:51.235] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:51.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:53:0) after 1m0s
[02:48:51.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:53:0)
[02:48:51.297] Timer tick, asking Buildbucket for the build status
[02:48:51.515] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:51.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:54:0) after 1m0s
[02:49:51.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:54:0)
[02:49:51.543] Timer tick, asking Buildbucket for the build status
[02:49:51.815] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:51.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:55:0) after 1m0s
[02:50:51.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:55:0)
[02:50:51.852] Timer tick, asking Buildbucket for the build status
[02:50:51.973] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:51.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:56:0) after 1m0s
[02:51:52.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:56:0)
[02:51:52.035] Timer tick, asking Buildbucket for the build status
[02:51:52.187] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:52.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:57:0) after 1m0s
[02:52:52.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:57:0)
[02:52:52.214] Timer tick, asking Buildbucket for the build status
[02:52:52.401] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:52.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:58:0) after 1m0s
[02:53:52.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:58:0)
[02:53:52.461] Timer tick, asking Buildbucket for the build status
[02:53:52.575] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:52.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:59:0) after 1m0s
[02:54:52.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:59:0)
[02:54:52.712] Timer tick, asking Buildbucket for the build status
[02:54:53.098] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:53.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:60:0) after 1m0s
[02:55:53.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:60:0)
[02:55:53.213] Timer tick, asking Buildbucket for the build status
[02:55:53.454] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:53.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:61:0) after 1m0s
[02:56:53.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:61:0)
[02:56:53.520] Timer tick, asking Buildbucket for the build status
[02:56:53.735] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:53.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:62:0) after 1m0s
[02:57:53.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:62:0)
[02:57:53.763] Timer tick, asking Buildbucket for the build status
[02:57:53.926] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:53.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:63:0) after 1m0s
[02:58:53.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:63:0)
[02:58:53.950] Timer tick, asking Buildbucket for the build status
[02:58:54.086] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:54.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:64:0) after 1m0s
[02:59:54.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:64:0)
[02:59:54.162] Timer tick, asking Buildbucket for the build status
[02:59:54.276] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:54.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:65:0) after 1m0s
[03:00:54.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:65:0)
[03:00:54.485] Timer tick, asking Buildbucket for the build status
[03:00:54.572] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:66:0) after 1m0s
[03:01:54.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:66:0)
[03:01:54.705] Timer tick, asking Buildbucket for the build status
[03:01:54.791] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:54.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:67:0) after 1m0s
[03:02:54.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:67:0)
[03:02:54.933] Timer tick, asking Buildbucket for the build status
[03:02:55.231] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:55.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:68:0) after 1m0s
[03:03:55.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:68:0)
[03:03:55.261] Timer tick, asking Buildbucket for the build status
[03:03:55.474] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:55.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:69:0) after 1m0s
[03:04:55.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:69:0)
[03:04:55.491] Timer tick, asking Buildbucket for the build status
[03:04:55.673] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:55.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:70:0) after 1m0s
[03:05:56.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:70:0)
[03:05:56.415] Timer tick, asking Buildbucket for the build status
[03:05:56.754] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:56.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:71:0) after 1m0s
[03:06:56.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:71:0)
[03:06:56.760] Timer tick, asking Buildbucket for the build status
[03:06:56.882] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:56.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:72:0) after 1m0s
[03:07:56.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:72:0)
[03:07:56.992] Timer tick, asking Buildbucket for the build status
[03:07:57.455] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:57.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:73:0) after 1m0s
[03:08:57.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:73:0)
[03:08:57.558] Timer tick, asking Buildbucket for the build status
[03:08:57.651] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:57.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:74:0) after 1m0s
[03:09:57.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:74:0)
[03:09:57.667] Timer tick, asking Buildbucket for the build status
[03:09:57.898] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:57.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:75:0) after 1m0s
[03:10:58.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:75:0)
[03:10:58.109] Timer tick, asking Buildbucket for the build status
[03:10:58.225] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:58.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:76:0) after 1m0s
[03:11:58.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:76:0)
[03:11:58.302] Timer tick, asking Buildbucket for the build status
[03:11:58.395] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:58.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:77:0) after 1m0s
[03:12:58.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:77:0)
[03:12:58.493] Timer tick, asking Buildbucket for the build status
[03:12:58.638] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:58.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:78:0) after 1m0s
[03:13:58.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:78:0)
[03:13:58.683] Timer tick, asking Buildbucket for the build status
[03:13:58.746] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:58.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:79:0) after 1m0s
[03:14:58.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:79:0)
[03:14:58.875] Timer tick, asking Buildbucket for the build status
[03:14:58.940] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:58.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:80:0) after 1m0s
[03:15:59.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:80:0)
[03:15:59.292] Timer tick, asking Buildbucket for the build status
[03:15:59.389] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:59.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:81:0) after 1m0s
[03:16:59.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:81:0)
[03:16:59.573] Timer tick, asking Buildbucket for the build status
[03:16:59.655] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:59.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:82:0) after 1m0s
[03:17:59.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:82:0)
[03:17:59.758] Timer tick, asking Buildbucket for the build status
[03:18:00.157] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:00.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:83:0) after 1m0s
[03:19:00.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:83:0)
[03:19:00.301] Timer tick, asking Buildbucket for the build status
[03:19:00.763] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:00.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:84:0) after 1m0s
[03:20:01.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:84:0)
[03:20:01.109] Timer tick, asking Buildbucket for the build status
[03:20:01.242] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:01.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:85:0) after 1m0s
[03:21:02.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:85:0)
[03:21:02.587] Timer tick, asking Buildbucket for the build status
[03:21:02.903] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:02.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:86:0) after 1m0s
[03:22:03.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:86:0)
[03:22:03.028] Timer tick, asking Buildbucket for the build status
[03:22:04.604] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:04.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:87:0) after 1m0s
[03:23:04.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:87:0)
[03:23:04.734] Timer tick, asking Buildbucket for the build status
[03:23:04.859] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:04.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:88:0) after 1m0s
[03:24:05.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:88:0)
[03:24:05.011] Timer tick, asking Buildbucket for the build status
[03:24:05.135] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:05.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:89:0) after 1m0s
[03:25:05.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:89:0)
[03:25:05.155] Timer tick, asking Buildbucket for the build status
[03:25:05.255] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:05.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:90:0) after 1m0s
[03:26:05.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:90:0)
[03:26:05.379] Timer tick, asking Buildbucket for the build status
[03:26:05.453] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:05.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:91:0) after 1m0s
[03:27:05.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:91:0)
[03:27:05.494] Timer tick, asking Buildbucket for the build status
[03:27:05.569] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:05.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:92:0) after 1m0s
[03:28:05.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:92:0)
[03:28:05.638] Timer tick, asking Buildbucket for the build status
[03:28:05.782] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:05.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:93:0) after 1m0s
[03:29:05.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:93:0)
[03:29:05.773] Timer tick, asking Buildbucket for the build status
[03:29:05.988] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:05.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:94:0) after 1m0s
[03:30:06.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:94:0)
[03:30:06.014] Timer tick, asking Buildbucket for the build status
[03:30:06.195] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:06.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:95:0) after 1m0s
[03:31:06.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:95:0)
[03:31:06.228] Timer tick, asking Buildbucket for the build status
[03:31:06.411] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:06.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:96:0) after 1m0s
[03:32:06.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:96:0)
[03:32:06.495] Timer tick, asking Buildbucket for the build status
[03:32:07.262] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:07.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:97:0) after 1m0s
[03:33:07.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:97:0)
[03:33:07.323] Timer tick, asking Buildbucket for the build status
[03:33:07.567] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:07.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:98:0) after 1m0s
[03:34:07.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:98:0)
[03:34:07.600] Timer tick, asking Buildbucket for the build status
[03:34:07.782] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:07.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:99:0) after 1m0s
[03:35:07.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:99:0)
[03:35:07.897] Timer tick, asking Buildbucket for the build status
[03:35:08.134] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:08.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:100:0) after 1m0s
[03:36:08.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:100:0)
[03:36:08.195] Timer tick, asking Buildbucket for the build status
[03:36:08.273] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:08.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:101:0) after 1m0s
[03:37:08.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:101:0)
[03:37:08.329] Timer tick, asking Buildbucket for the build status
[03:37:08.417] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:08.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:102:0) after 1m0s
[03:38:08.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:102:0)
[03:38:08.459] Timer tick, asking Buildbucket for the build status
[03:38:08.719] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:08.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:103:0) after 1m0s
[03:39:08.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:103:0)
[03:39:08.735] Timer tick, asking Buildbucket for the build status
[03:39:08.985] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:08.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:104:0) after 1m0s
[03:40:09.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:104:0)
[03:40:09.137] Timer tick, asking Buildbucket for the build status
[03:40:09.195] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:09.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:105:0) after 1m0s
[03:41:09.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:105:0)
[03:41:09.292] Timer tick, asking Buildbucket for the build status
[03:41:09.356] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:09.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:106:0) after 1m0s
[03:42:09.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:106:0)
[03:42:09.355] Timer tick, asking Buildbucket for the build status
[03:42:12.677] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:12.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:107:0) after 1m0s
[03:43:12.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:107:0)
[03:43:12.712] Timer tick, asking Buildbucket for the build status
[03:43:13.375] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:13.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:108:0) after 1m0s
[03:44:13.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:108:0)
[03:44:13.571] Timer tick, asking Buildbucket for the build status
[03:44:13.659] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:13.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:109:0) after 1m0s
[03:45:13.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:109:0)
[03:45:13.733] Timer tick, asking Buildbucket for the build status
[03:45:13.826] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:13.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:110:0) after 1m0s
[03:46:13.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:110:0)
[03:46:13.885] Timer tick, asking Buildbucket for the build status
[03:46:14.294] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:14.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:111:0) after 1m0s
[03:47:14.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:111:0)
[03:47:14.346] Timer tick, asking Buildbucket for the build status
[03:47:14.438] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:14.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:112:0) after 1m0s
[03:48:14.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:112:0)
[03:48:14.567] Timer tick, asking Buildbucket for the build status
[03:48:14.737] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:14.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:113:0) after 1m0s
[03:49:14.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:113:0)
[03:49:14.762] Timer tick, asking Buildbucket for the build status
[03:49:15.009] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:15.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:114:0) after 1m0s
[03:50:15.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:114:0)
[03:50:15.045] Timer tick, asking Buildbucket for the build status
[03:50:15.123] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:15.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:115:0) after 1m0s
[03:51:15.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:115:0)
[03:51:15.164] Timer tick, asking Buildbucket for the build status
[03:51:15.659] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:15.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:116:0) after 1m0s
[03:52:15.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:116:0)
[03:52:15.738] Timer tick, asking Buildbucket for the build status
[03:52:15.804] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:15.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:117:0) after 1m0s
[03:53:15.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:117:0)
[03:53:15.884] Timer tick, asking Buildbucket for the build status
[03:53:16.202] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:16.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:118:0) after 1m0s
[03:54:16.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:118:0)
[03:54:16.325] Timer tick, asking Buildbucket for the build status
[03:54:16.388] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:16.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:119:0) after 1m0s
[03:55:16.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:119:0)
[03:55:16.478] Timer tick, asking Buildbucket for the build status
[03:55:16.547] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:16.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:120:0) after 1m0s
[03:56:16.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:120:0)
[03:56:16.658] Timer tick, asking Buildbucket for the build status
[03:56:17.000] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:17.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:121:0) after 1m0s
[03:57:17.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:121:0)
[03:57:17.632] Timer tick, asking Buildbucket for the build status
[03:57:17.702] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:17.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:122:0) after 1m0s
[03:58:18.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:122:0)
[03:58:18.001] Timer tick, asking Buildbucket for the build status
[03:58:18.136] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:18.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:123:0) after 1m0s
[03:59:18.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:123:0)
[03:59:18.407] Timer tick, asking Buildbucket for the build status
[03:59:18.754] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:18.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:124:0) after 1m0s
[04:00:20.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:124:0)
[04:00:20.450] Timer tick, asking Buildbucket for the build status
[04:00:20.629] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:20.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:125:0) after 1m0s
[04:01:23.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:125:0)
[04:01:23.528] Timer tick, asking Buildbucket for the build status
[04:01:23.783] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:23.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:126:0) after 1m0s
[04:02:23.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:126:0)
[04:02:23.874] Timer tick, asking Buildbucket for the build status
[04:02:24.184] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:24.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:127:0) after 1m0s
[04:03:24.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:127:0)
[04:03:24.318] Timer tick, asking Buildbucket for the build status
[04:03:24.462] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:24.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:128:0) after 1m0s
[04:04:24.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:128:0)
[04:04:24.683] Timer tick, asking Buildbucket for the build status
[04:04:24.895] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:24.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:129:0) after 1m0s
[04:05:25.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:129:0)
[04:05:25.002] Timer tick, asking Buildbucket for the build status
[04:05:25.143] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:25.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:130:0) after 1m0s
[04:06:25.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:130:0)
[04:06:25.286] Timer tick, asking Buildbucket for the build status
[04:06:25.406] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:25.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:131:0) after 1m0s
[04:07:25.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:131:0)
[04:07:25.435] Timer tick, asking Buildbucket for the build status
[04:07:25.670] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:25.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:132:0) after 1m0s
[04:08:25.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:132:0)
[04:08:25.693] Timer tick, asking Buildbucket for the build status
[04:08:26.394] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:26.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:133:0) after 1m0s
[04:09:26.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:133:0)
[04:09:26.482] Timer tick, asking Buildbucket for the build status
[04:09:26.808] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:26.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:134:0) after 1m0s
[04:10:26.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:134:0)
[04:10:26.839] Timer tick, asking Buildbucket for the build status
[04:10:27.000] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:27.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:135:0) after 1m0s
[04:11:27.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:135:0)
[04:11:27.042] Timer tick, asking Buildbucket for the build status
[04:11:27.102] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:27.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:136:0) after 1m0s
[04:12:27.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:136:0)
[04:12:27.142] Timer tick, asking Buildbucket for the build status
[04:12:27.304] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:27.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:137:0) after 1m0s
[04:13:27.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:137:0)
[04:13:27.400] Timer tick, asking Buildbucket for the build status
[04:13:27.519] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:27.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:138:0) after 1m0s
[04:14:27.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:138:0)
[04:14:27.595] Timer tick, asking Buildbucket for the build status
[04:14:27.870] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:27.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:139:0) after 1m0s
[04:15:27.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:139:0)
[04:15:27.936] Timer tick, asking Buildbucket for the build status
[04:15:28.040] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:28.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:140:0) after 1m0s
[04:16:28.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:140:0)
[04:16:28.203] Timer tick, asking Buildbucket for the build status
[04:16:28.324] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:28.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:141:0) after 1m0s
[04:17:28.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:141:0)
[04:17:28.349] Timer tick, asking Buildbucket for the build status
[04:17:28.425] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:28.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:142:0) after 1m0s
[04:18:28.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:142:0)
[04:18:28.501] Timer tick, asking Buildbucket for the build status
[04:18:28.623] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:28.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:143:0) after 1m0s
[04:19:28.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:143:0)
[04:19:28.667] Timer tick, asking Buildbucket for the build status
[04:19:28.779] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:28.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:144:0) after 1m0s
[04:20:28.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:144:0)
[04:20:28.924] Timer tick, asking Buildbucket for the build status
[04:20:29.004] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:29.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:145:0) after 1m0s
[04:21:29.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:145:0)
[04:21:29.007] Timer tick, asking Buildbucket for the build status
[04:21:29.124] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:29.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:146:0) after 1m0s
[04:22:29.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:146:0)
[04:22:29.281] Timer tick, asking Buildbucket for the build status
[04:22:29.364] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:29.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:147:0) after 1m0s
[04:23:29.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:147:0)
[04:23:29.524] Timer tick, asking Buildbucket for the build status
[04:23:29.934] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:29.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:148:0) after 1m0s
[04:24:30.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:148:0)
[04:24:30.068] Timer tick, asking Buildbucket for the build status
[04:24:30.368] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:30.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:149:0) after 1m0s
[04:25:30.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:149:0)
[04:25:30.484] Timer tick, asking Buildbucket for the build status
[04:25:30.557] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:30.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:150:0) after 1m0s
[04:26:30.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:150:0)
[04:26:30.654] Timer tick, asking Buildbucket for the build status
[04:26:31.064] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:31.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:151:0) after 1m0s
[04:27:31.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:151:0)
[04:27:31.113] Timer tick, asking Buildbucket for the build status
[04:27:31.178] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:31.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:152:0) after 1m0s
[04:28:31.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:152:0)
[04:28:31.423] Timer tick, asking Buildbucket for the build status
[04:28:31.774] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:31.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:153:0) after 1m0s
[04:29:31.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:153:0)
[04:29:31.923] Timer tick, asking Buildbucket for the build status
[04:29:32.035] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:32.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:154:0) after 1m0s
[04:30:32.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:154:0)
[04:30:32.066] Timer tick, asking Buildbucket for the build status
[04:30:32.138] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:32.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:155:0) after 1m0s
[04:31:32.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:155:0)
[04:31:32.373] Timer tick, asking Buildbucket for the build status
[04:31:32.469] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:32.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:156:0) after 1m0s
[04:32:32.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:156:0)
[04:32:32.685] Timer tick, asking Buildbucket for the build status
[04:32:32.796] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:32.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:157:0) after 1m0s
[04:33:32.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:157:0)
[04:33:32.902] Timer tick, asking Buildbucket for the build status
[04:33:33.093] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:33.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:158:0) after 1m0s
[04:34:33.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:158:0)
[04:34:33.151] Timer tick, asking Buildbucket for the build status
[04:34:33.239] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:33.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:159:0) after 1m0s
[04:35:33.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:159:0)
[04:35:33.398] Timer tick, asking Buildbucket for the build status
[04:35:33.487] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:33.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:160:0) after 1m0s
[04:36:33.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:160:0)
[04:36:33.612] Timer tick, asking Buildbucket for the build status
[04:36:33.683] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:33.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:161:0) after 1m0s
[04:37:33.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:161:0)
[04:37:33.767] Timer tick, asking Buildbucket for the build status
[04:37:33.979] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:33.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:162:0) after 1m0s
[04:38:34.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:162:0)
[04:38:34.077] Timer tick, asking Buildbucket for the build status
[04:38:34.216] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:34.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:163:0) after 1m0s
[04:39:34.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:163:0)
[04:39:34.315] Timer tick, asking Buildbucket for the build status
[04:39:34.382] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:34.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:164:0) after 1m0s
[04:40:34.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:164:0)
[04:40:34.418] Timer tick, asking Buildbucket for the build status
[04:40:34.513] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:34.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:165:0) after 1m0s
[04:41:34.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:165:0)
[04:41:34.617] Timer tick, asking Buildbucket for the build status
[04:41:34.786] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:34.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:166:0) after 1m0s
[04:42:34.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:166:0)
[04:42:34.967] Timer tick, asking Buildbucket for the build status
[04:42:35.044] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:35.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:167:0) after 1m0s
[04:43:35.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:167:0)
[04:43:35.168] Timer tick, asking Buildbucket for the build status
[04:43:35.475] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:35.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:168:0) after 1m0s
[04:44:35.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:168:0)
[04:44:35.578] Timer tick, asking Buildbucket for the build status
[04:44:35.663] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:35.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:169:0) after 1m0s
[04:45:35.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:169:0)
[04:45:35.879] Timer tick, asking Buildbucket for the build status
[04:45:36.056] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:36.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:170:0) after 1m0s
[04:46:36.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:170:0)
[04:46:36.425] Timer tick, asking Buildbucket for the build status
[04:46:36.498] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:36.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:171:0) after 1m0s
[04:47:36.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:171:0)
[04:47:36.572] Timer tick, asking Buildbucket for the build status
[04:47:36.787] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:36.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:172:0) after 1m0s
[04:48:36.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:172:0)
[04:48:36.833] Timer tick, asking Buildbucket for the build status
[04:48:37.228] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:37.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:173:0) after 1m0s
[04:49:37.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:173:0)
[04:49:37.260] Timer tick, asking Buildbucket for the build status
[04:49:37.320] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:37.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:174:0) after 1m0s
[04:50:37.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:174:0)
[04:50:37.353] Timer tick, asking Buildbucket for the build status
[04:50:37.628] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:37.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:175:0) after 1m0s
[04:51:37.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:175:0)
[04:51:37.659] Timer tick, asking Buildbucket for the build status
[04:51:37.753] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:37.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:176:0) after 1m0s
[04:52:37.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:176:0)
[04:52:37.788] Timer tick, asking Buildbucket for the build status
[04:52:37.855] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:37.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:177:0) after 1m0s
[04:53:37.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:177:0)
[04:53:37.902] Timer tick, asking Buildbucket for the build status
[04:53:38.087] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:38.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:178:0) after 1m0s
[04:54:38.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:178:0)
[04:54:38.129] Timer tick, asking Buildbucket for the build status
[04:54:38.393] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:38.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:179:0) after 1m0s
[04:55:38.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:179:0)
[04:55:38.430] Timer tick, asking Buildbucket for the build status
[04:55:38.612] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:38.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:180:0) after 1m0s
[04:56:38.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:180:0)
[04:56:38.618] Timer tick, asking Buildbucket for the build status
[04:56:38.826] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:38.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:181:0) after 1m0s
[04:57:38.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:181:0)
[04:57:38.893] Timer tick, asking Buildbucket for the build status
[04:57:38.966] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:38.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:182:0) after 1m0s
[04:58:39.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:182:0)
[04:58:39.064] Timer tick, asking Buildbucket for the build status
[04:58:39.679] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:39.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:183:0) after 1m0s
[04:59:39.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:183:0)
[04:59:39.738] Timer tick, asking Buildbucket for the build status
[04:59:39.816] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:39.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:184:0) after 1m0s
[05:00:39.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:184:0)
[05:00:39.845] Timer tick, asking Buildbucket for the build status
[05:00:40.207] Build 8874113333290308560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:40.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039543395391468624:185:0) after 1m0s
[05:01:02.849] Received PubSub notification, asking Buildbucket for the build status
[05:01:02.917] Build 8874113333290308560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:01:02.917] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1595394062041491",
    "created_by": "project:infra",
    "created_ts": "1595383055370758",
    "id": "8874113333290308560",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4183.38\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"79edc954-8e9f-4c9e-a871-60bf84bd8a8e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4183.38\"}}",
    "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-0lj2\"], \"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\": [\"5425-e4e3059\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1595383114511627",
    "status": "COMPLETED",
    "status_changed_ts": "1595394062427332",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9039543395391468624",
      "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/8874113333290308560/+/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:4d8d0f1132960110",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1595394062441998",
    "url": "https://ci.chromium.org/b/8874113333290308560",
    "utcnow_ts": "1595394062909915"
  }
}
[05:01:02.917] Invocation finished in 3h3m28.933911733s with status SUCCEEDED