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

• parent_buildername:publish_tarball
• user_agent:recipe

[02:02:11.512] New invocation is queued and will start shortly
[02:02:11.512] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[02:02:12.733] Starting the invocation (attempt 1)
[02:02:12.813] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:02:12.813] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:02:12.813] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9043801161821845440",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4162.4\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"f1636ea9-2964-4ebf-9236-b88e1eb83792\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4162.4\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9043801161821845440",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[02:02:13.821] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1591322532967998",
    "id": "8878371099629897136",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4162.4\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f1636ea9-2964-4ebf-9236-b88e1eb83792\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4162.4\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1591322533480931",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9043801161821845440",
      "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/8878371099629897136/+/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": "1591322533481081",
    "url": "https://ci.chromium.org/b/8878371099629897136",
    "utcnow_ts": "1591322533787202"
  }
}
[02:02:13.821] Task URL: https://ci.chromium.org/b/8878371099629897136
[02:02:13.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:2:0) after 1m0s
[02:03:13.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:2:0)
[02:03:13.938] Timer tick, asking Buildbucket for the build status
[02:03:14.214] Build 8878371099629897136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:14.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:3:0) after 1m0s
[02:04:14.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:3:0)
[02:04:14.244] Timer tick, asking Buildbucket for the build status
[02:04:14.654] Build 8878371099629897136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:14.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:4:0) after 1m0s
[02:05:14.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:4:0)
[02:05:14.735] Timer tick, asking Buildbucket for the build status
[02:05:15.193] Build 8878371099629897136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:15.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:5:0) after 1m0s
[02:05:26.171] Received PubSub notification, asking Buildbucket for the build status
[02:05:26.335] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:15.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:5:0)
[02:06:15.347] Timer tick, asking Buildbucket for the build status
[02:06:15.510] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:15.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:7:0) after 1m0s
[02:07:15.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:7:0)
[02:07:15.646] Timer tick, asking Buildbucket for the build status
[02:07:15.994] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:15.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:8:0) after 1m0s
[02:08:16.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:8:0)
[02:08:16.025] Timer tick, asking Buildbucket for the build status
[02:08:16.233] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:16.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:9:0) after 1m0s
[02:09:16.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:9:0)
[02:09:16.324] Timer tick, asking Buildbucket for the build status
[02:09:16.529] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:16.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:10:0) after 1m0s
[02:10:16.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:10:0)
[02:10:16.754] Timer tick, asking Buildbucket for the build status
[02:10:17.262] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:17.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:11:0) after 1m0s
[02:11:17.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:11:0)
[02:11:17.287] Timer tick, asking Buildbucket for the build status
[02:11:17.597] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:17.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:12:0) after 1m0s
[02:12:17.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:12:0)
[02:12:17.624] Timer tick, asking Buildbucket for the build status
[02:12:17.757] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:17.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:13:0) after 1m0s
[02:13:17.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:13:0)
[02:13:17.780] Timer tick, asking Buildbucket for the build status
[02:13:18.032] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:18.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:14:0) after 1m0s
[02:14:18.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:14:0)
[02:14:18.109] Timer tick, asking Buildbucket for the build status
[02:14:18.253] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:18.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:15:0) after 1m0s
[02:15:18.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:15:0)
[02:15:18.487] Timer tick, asking Buildbucket for the build status
[02:15:18.717] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:18.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:16:0) after 1m0s
[02:16:18.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:16:0)
[02:16:18.766] Timer tick, asking Buildbucket for the build status
[02:16:19.278] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:19.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:17:0) after 1m0s
[02:17:19.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:17:0)
[02:17:19.357] Timer tick, asking Buildbucket for the build status
[02:17:19.700] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:19.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:18:0) after 1m0s
[02:18:19.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:18:0)
[02:18:19.852] Timer tick, asking Buildbucket for the build status
[02:18:20.189] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:20.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:19:0) after 1m0s
[02:19:20.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:19:0)
[02:19:20.276] Timer tick, asking Buildbucket for the build status
[02:19:20.534] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:20.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:20:0) after 1m0s
[02:20:20.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:20:0)
[02:20:20.899] Timer tick, asking Buildbucket for the build status
[02:20:21.368] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:21.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:21:0) after 1m0s
[02:21:21.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:21:0)
[02:21:21.379] Timer tick, asking Buildbucket for the build status
[02:21:21.904] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:21.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:22:0) after 1m0s
[02:22:21.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:22:0)
[02:22:21.939] Timer tick, asking Buildbucket for the build status
[02:22:22.096] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:22.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:23:0) after 1m0s
[02:23:22.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:23:0)
[02:23:22.181] Timer tick, asking Buildbucket for the build status
[02:23:22.421] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:22.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:24:0) after 1m0s
[02:24:52.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:24:0)
[02:24:52.810] Timer tick, asking Buildbucket for the build status
[02:24:53.278] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:53.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:25:0) after 1m0s
[02:25:53.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:25:0)
[02:25:53.373] Timer tick, asking Buildbucket for the build status
[02:25:53.554] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:53.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:26:0) after 1m0s
[02:26:53.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:26:0)
[02:26:53.634] Timer tick, asking Buildbucket for the build status
[02:26:53.867] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:53.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:27:0) after 1m0s
[02:27:53.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:27:0)
[02:27:53.903] Timer tick, asking Buildbucket for the build status
[02:27:54.231] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:54.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:28:0) after 1m0s
[02:28:54.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:28:0)
[02:28:54.252] Timer tick, asking Buildbucket for the build status
[02:28:54.623] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:54.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:29:0) after 1m0s
[02:29:54.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:29:0)
[02:29:54.646] Timer tick, asking Buildbucket for the build status
[02:29:55.604] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:55.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:30:0) after 1m0s
[02:30:55.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:30:0)
[02:30:55.671] Timer tick, asking Buildbucket for the build status
[02:30:56.122] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:56.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:31:0) after 1m0s
[02:31:56.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:31:0)
[02:31:56.151] Timer tick, asking Buildbucket for the build status
[02:31:56.739] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:56.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:32:0) after 1m0s
[02:32:56.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:32:0)
[02:32:56.860] Timer tick, asking Buildbucket for the build status
[02:32:59.634] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:59.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:33:0) after 1m0s
[02:34:00.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:33:0)
[02:34:00.061] Timer tick, asking Buildbucket for the build status
[02:34:00.291] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:00.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:34:0) after 1m0s
[02:35:00.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:34:0)
[02:35:00.626] Timer tick, asking Buildbucket for the build status
[02:35:00.891] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:00.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:35:0) after 1m0s
[02:36:01.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:35:0)
[02:36:01.438] Timer tick, asking Buildbucket for the build status
[02:36:01.560] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:01.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:36:0) after 1m0s
[02:37:02.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:36:0)
[02:37:02.594] Timer tick, asking Buildbucket for the build status
[02:37:02.782] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:02.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:37:0) after 1m0s
[02:38:02.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:37:0)
[02:38:02.809] Timer tick, asking Buildbucket for the build status
[02:38:03.023] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:03.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:38:0) after 1m0s
[02:39:03.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:38:0)
[02:39:03.598] Timer tick, asking Buildbucket for the build status
[02:39:05.910] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:05.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:39:0) after 1m0s
[02:40:06.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:39:0)
[02:40:06.084] Timer tick, asking Buildbucket for the build status
[02:40:06.698] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:06.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:40:0) after 1m0s
[02:41:06.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:40:0)
[02:41:06.840] Timer tick, asking Buildbucket for the build status
[02:41:07.057] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:07.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:41:0) after 1m0s
[02:42:07.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:41:0)
[02:42:07.137] Timer tick, asking Buildbucket for the build status
[02:42:07.416] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:07.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:42:0) after 1m0s
[02:43:07.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:42:0)
[02:43:07.462] Timer tick, asking Buildbucket for the build status
[02:43:07.648] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:07.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:43:0) after 1m0s
[02:44:07.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:43:0)
[02:44:07.712] Timer tick, asking Buildbucket for the build status
[02:44:08.083] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:08.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:44:0) after 1m0s
[02:45:08.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:44:0)
[02:45:08.223] Timer tick, asking Buildbucket for the build status
[02:45:08.826] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:08.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:45:0) after 1m0s
[02:46:08.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:45:0)
[02:46:08.981] Timer tick, asking Buildbucket for the build status
[02:46:09.132] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:09.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:46:0) after 1m0s
[02:47:09.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:46:0)
[02:47:09.168] Timer tick, asking Buildbucket for the build status
[02:47:09.347] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:09.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:47:0) after 1m0s
[02:48:09.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:47:0)
[02:48:09.385] Timer tick, asking Buildbucket for the build status
[02:48:09.575] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:09.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:48:0) after 1m0s
[02:49:09.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:48:0)
[02:49:09.606] Timer tick, asking Buildbucket for the build status
[02:49:09.844] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:09.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:49:0) after 1m0s
[02:50:09.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:49:0)
[02:50:09.916] Timer tick, asking Buildbucket for the build status
[02:50:10.072] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:10.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:50:0) after 1m0s
[02:51:10.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:50:0)
[02:51:10.150] Timer tick, asking Buildbucket for the build status
[02:51:10.551] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:10.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:51:0) after 1m0s
[02:52:10.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:51:0)
[02:52:10.667] Timer tick, asking Buildbucket for the build status
[02:52:10.795] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:10.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:52:0) after 1m0s
[02:53:10.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:52:0)
[02:53:10.910] Timer tick, asking Buildbucket for the build status
[02:53:11.261] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:11.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:53:0) after 1m0s
[02:54:11.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:53:0)
[02:54:11.387] Timer tick, asking Buildbucket for the build status
[02:54:11.623] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:11.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:54:0) after 1m0s
[02:55:12.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:54:0)
[02:55:12.395] Timer tick, asking Buildbucket for the build status
[02:55:12.618] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:12.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:55:0) after 1m0s
[02:56:12.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:55:0)
[02:56:12.716] Timer tick, asking Buildbucket for the build status
[02:56:13.122] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:13.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:56:0) after 1m0s
[02:57:13.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:56:0)
[02:57:13.212] Timer tick, asking Buildbucket for the build status
[02:57:13.558] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:13.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:57:0) after 1m0s
[02:58:13.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:57:0)
[02:58:13.618] Timer tick, asking Buildbucket for the build status
[02:58:13.787] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:13.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:58:0) after 1m0s
[02:59:13.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:58:0)
[02:59:13.816] Timer tick, asking Buildbucket for the build status
[02:59:14.893] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:14.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:59:0) after 1m0s
[03:00:14.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:59:0)
[03:00:14.947] Timer tick, asking Buildbucket for the build status
[03:00:15.661] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:15.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:60:0) after 1m0s
[03:01:15.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:60:0)
[03:01:15.668] Timer tick, asking Buildbucket for the build status
[03:01:15.799] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:15.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:61:0) after 1m0s
[03:02:15.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:61:0)
[03:02:15.827] Timer tick, asking Buildbucket for the build status
[03:02:15.961] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:15.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:62:0) after 1m0s
[03:03:16.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:62:0)
[03:03:16.017] Timer tick, asking Buildbucket for the build status
[03:03:16.401] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:16.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:63:0) after 1m0s
[03:04:16.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:63:0)
[03:04:16.479] Timer tick, asking Buildbucket for the build status
[03:04:17.435] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:17.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:64:0) after 1m0s
[03:05:17.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:64:0)
[03:05:17.500] Timer tick, asking Buildbucket for the build status
[03:05:17.635] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:17.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:65:0) after 1m0s
[03:06:17.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:65:0)
[03:06:17.738] Timer tick, asking Buildbucket for the build status
[03:06:17.937] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:17.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:66:0) after 1m0s
[03:07:17.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:66:0)
[03:07:17.964] Timer tick, asking Buildbucket for the build status
[03:07:18.585] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:18.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:67:0) after 1m0s
[03:08:18.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:67:0)
[03:08:18.580] Timer tick, asking Buildbucket for the build status
[03:08:19.077] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:19.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:68:0) after 1m0s
[03:09:19.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:68:0)
[03:09:19.151] Timer tick, asking Buildbucket for the build status
[03:09:19.649] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:19.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:69:0) after 1m0s
[03:10:19.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:69:0)
[03:10:19.664] Timer tick, asking Buildbucket for the build status
[03:10:19.844] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:19.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:70:0) after 1m0s
[03:11:19.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:70:0)
[03:11:19.956] Timer tick, asking Buildbucket for the build status
[03:11:20.458] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:20.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:71:0) after 1m0s
[03:12:20.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:71:0)
[03:12:20.563] Timer tick, asking Buildbucket for the build status
[03:12:20.914] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:20.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:72:0) after 1m0s
[03:13:21.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:72:0)
[03:13:21.006] Timer tick, asking Buildbucket for the build status
[03:13:21.184] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:21.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:73:0) after 1m0s
[03:14:21.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:73:0)
[03:14:21.206] Timer tick, asking Buildbucket for the build status
[03:14:21.578] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:21.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:74:0) after 1m0s
[03:15:21.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:74:0)
[03:15:21.614] Timer tick, asking Buildbucket for the build status
[03:15:22.085] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:22.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:75:0) after 1m0s
[03:16:22.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:75:0)
[03:16:22.239] Timer tick, asking Buildbucket for the build status
[03:16:22.417] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:22.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:76:0) after 1m0s
[03:17:22.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:76:0)
[03:17:22.468] Timer tick, asking Buildbucket for the build status
[03:17:22.991] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:22.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:77:0) after 1m0s
[03:18:23.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:77:0)
[03:18:23.053] Timer tick, asking Buildbucket for the build status
[03:18:23.223] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:23.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:78:0) after 1m0s
[03:19:23.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:78:0)
[03:19:23.275] Timer tick, asking Buildbucket for the build status
[03:19:23.377] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:23.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:79:0) after 1m0s
[03:20:23.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:79:0)
[03:20:23.412] Timer tick, asking Buildbucket for the build status
[03:20:23.554] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:23.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:80:0) after 1m0s
[03:21:23.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:80:0)
[03:21:23.555] Timer tick, asking Buildbucket for the build status
[03:21:24.146] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:24.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:81:0) after 1m0s
[03:22:24.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:81:0)
[03:22:24.173] Timer tick, asking Buildbucket for the build status
[03:22:24.316] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:24.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:82:0) after 1m0s
[03:23:24.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:82:0)
[03:23:24.516] Timer tick, asking Buildbucket for the build status
[03:23:24.680] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:24.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:83:0) after 1m0s
[03:24:24.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:83:0)
[03:24:24.986] Timer tick, asking Buildbucket for the build status
[03:24:25.497] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:25.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:84:0) after 1m0s
[03:25:25.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:84:0)
[03:25:25.586] Timer tick, asking Buildbucket for the build status
[03:25:25.790] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:25.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:85:0) after 1m0s
[03:26:25.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:85:0)
[03:26:25.812] Timer tick, asking Buildbucket for the build status
[03:26:25.940] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:25.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:86:0) after 1m0s
[03:27:26.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:86:0)
[03:27:26.001] Timer tick, asking Buildbucket for the build status
[03:27:26.404] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:26.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:87:0) after 1m0s
[03:28:26.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:87:0)
[03:28:26.469] Timer tick, asking Buildbucket for the build status
[03:28:26.683] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:26.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:88:0) after 1m0s
[03:29:26.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:88:0)
[03:29:26.708] Timer tick, asking Buildbucket for the build status
[03:29:26.857] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:26.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:89:0) after 1m0s
[03:30:27.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:89:0)
[03:30:27.032] Timer tick, asking Buildbucket for the build status
[03:30:27.214] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:27.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:90:0) after 1m0s
[03:31:27.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:90:0)
[03:31:27.237] Timer tick, asking Buildbucket for the build status
[03:31:27.415] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:27.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:91:0) after 1m0s
[03:32:27.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:91:0)
[03:32:27.474] Timer tick, asking Buildbucket for the build status
[03:32:28.162] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:28.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:92:0) after 1m0s
[03:33:28.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:92:0)
[03:33:28.241] Timer tick, asking Buildbucket for the build status
[03:33:28.393] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:28.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:93:0) after 1m0s
[03:34:28.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:93:0)
[03:34:28.576] Timer tick, asking Buildbucket for the build status
[03:34:28.746] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:28.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:94:0) after 1m0s
[03:35:28.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:94:0)
[03:35:28.824] Timer tick, asking Buildbucket for the build status
[03:35:29.255] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:29.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:95:0) after 1m0s
[03:36:29.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:95:0)
[03:36:29.325] Timer tick, asking Buildbucket for the build status
[03:36:29.559] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:29.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:96:0) after 1m0s
[03:37:29.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:96:0)
[03:37:29.620] Timer tick, asking Buildbucket for the build status
[03:37:30.069] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:30.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:97:0) after 1m0s
[03:38:30.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:97:0)
[03:38:30.111] Timer tick, asking Buildbucket for the build status
[03:38:30.381] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:30.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:98:0) after 1m0s
[03:39:30.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:98:0)
[03:39:30.469] Timer tick, asking Buildbucket for the build status
[03:39:30.670] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:30.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:99:0) after 1m0s
[03:40:30.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:99:0)
[03:40:30.689] Timer tick, asking Buildbucket for the build status
[03:40:30.898] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:30.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:100:0) after 1m0s
[03:41:30.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:100:0)
[03:41:30.872] Timer tick, asking Buildbucket for the build status
[03:41:31.444] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:31.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:101:0) after 1m0s
[03:42:31.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:101:0)
[03:42:31.520] Timer tick, asking Buildbucket for the build status
[03:42:31.739] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:31.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:102:0) after 1m0s
[03:43:31.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:102:0)
[03:43:31.762] Timer tick, asking Buildbucket for the build status
[03:43:32.124] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:32.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:103:0) after 1m0s
[03:44:32.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:103:0)
[03:44:32.156] Timer tick, asking Buildbucket for the build status
[03:44:32.391] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:32.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:104:0) after 1m0s
[03:45:32.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:104:0)
[03:45:32.422] Timer tick, asking Buildbucket for the build status
[03:45:32.567] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:32.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:105:0) after 1m0s
[03:46:32.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:105:0)
[03:46:32.627] Timer tick, asking Buildbucket for the build status
[03:46:33.057] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:33.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:106:0) after 1m0s
[03:47:33.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:106:0)
[03:47:33.081] Timer tick, asking Buildbucket for the build status
[03:47:33.281] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:33.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:107:0) after 1m0s
[03:48:33.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:107:0)
[03:48:33.305] Timer tick, asking Buildbucket for the build status
[03:48:33.689] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:33.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:108:0) after 1m0s
[03:49:33.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:108:0)
[03:49:33.768] Timer tick, asking Buildbucket for the build status
[03:49:34.142] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:34.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:109:0) after 1m0s
[03:50:34.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:109:0)
[03:50:34.123] Timer tick, asking Buildbucket for the build status
[03:50:34.584] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:34.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:110:0) after 1m0s
[03:51:34.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:110:0)
[03:51:34.647] Timer tick, asking Buildbucket for the build status
[03:51:35.137] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:35.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:111:0) after 1m0s
[03:52:35.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:111:0)
[03:52:35.255] Timer tick, asking Buildbucket for the build status
[03:52:35.380] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:35.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:112:0) after 1m0s
[03:53:35.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:112:0)
[03:53:35.451] Timer tick, asking Buildbucket for the build status
[03:53:36.010] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:36.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:113:0) after 1m0s
[03:54:36.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:113:0)
[03:54:36.042] Timer tick, asking Buildbucket for the build status
[03:54:36.322] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:36.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:114:0) after 1m0s
[03:55:36.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:114:0)
[03:55:36.353] Timer tick, asking Buildbucket for the build status
[03:55:36.846] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:36.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:115:0) after 1m0s
[03:56:36.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:115:0)
[03:56:36.909] Timer tick, asking Buildbucket for the build status
[03:56:37.350] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:37.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:116:0) after 1m0s
[03:57:37.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:116:0)
[03:57:37.443] Timer tick, asking Buildbucket for the build status
[03:57:37.746] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:37.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:117:0) after 1m0s
[03:58:37.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:117:0)
[03:58:37.850] Timer tick, asking Buildbucket for the build status
[03:58:38.456] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:38.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:118:0) after 1m0s
[03:59:39.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:118:0)
[03:59:39.005] Timer tick, asking Buildbucket for the build status
[03:59:39.241] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:39.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:119:0) after 1m0s
[04:00:39.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:119:0)
[04:00:39.336] Timer tick, asking Buildbucket for the build status
[04:00:39.500] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:39.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:120:0) after 1m0s
[04:01:39.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:120:0)
[04:01:39.517] Timer tick, asking Buildbucket for the build status
[04:01:39.696] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:39.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:121:0) after 1m0s
[04:02:39.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:121:0)
[04:02:39.723] Timer tick, asking Buildbucket for the build status
[04:02:39.930] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:39.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:122:0) after 1m0s
[04:03:39.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:122:0)
[04:03:39.982] Timer tick, asking Buildbucket for the build status
[04:03:40.199] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:40.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:123:0) after 1m0s
[04:04:40.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:123:0)
[04:04:40.189] Timer tick, asking Buildbucket for the build status
[04:04:40.391] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:40.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:124:0) after 1m0s
[04:05:40.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:124:0)
[04:05:40.481] Timer tick, asking Buildbucket for the build status
[04:05:40.820] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:40.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:125:0) after 1m0s
[04:06:42.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:125:0)
[04:06:42.958] Timer tick, asking Buildbucket for the build status
[04:06:43.218] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:43.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:126:0) after 1m0s
[04:07:43.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:126:0)
[04:07:43.279] Timer tick, asking Buildbucket for the build status
[04:07:43.460] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:43.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:127:0) after 1m0s
[04:08:43.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:127:0)
[04:08:43.511] Timer tick, asking Buildbucket for the build status
[04:08:44.055] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:44.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:128:0) after 1m0s
[04:09:44.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:128:0)
[04:09:44.152] Timer tick, asking Buildbucket for the build status
[04:09:44.499] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:44.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:129:0) after 1m0s
[04:10:44.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:129:0)
[04:10:44.541] Timer tick, asking Buildbucket for the build status
[04:10:44.848] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:44.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:130:0) after 1m0s
[04:11:44.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:130:0)
[04:11:44.916] Timer tick, asking Buildbucket for the build status
[04:11:46.500] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:46.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:131:0) after 1m0s
[04:12:46.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:131:0)
[04:12:46.539] Timer tick, asking Buildbucket for the build status
[04:12:46.675] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:46.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:132:0) after 1m0s
[04:13:46.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:132:0)
[04:13:46.714] Timer tick, asking Buildbucket for the build status
[04:13:47.271] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:47.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:133:0) after 1m0s
[04:14:47.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:133:0)
[04:14:47.335] Timer tick, asking Buildbucket for the build status
[04:14:47.460] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:47.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:134:0) after 1m0s
[04:15:47.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:134:0)
[04:15:47.495] Timer tick, asking Buildbucket for the build status
[04:15:47.871] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:47.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:135:0) after 1m0s
[04:16:47.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:135:0)
[04:16:47.903] Timer tick, asking Buildbucket for the build status
[04:16:49.696] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:49.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:136:0) after 1m0s
[04:17:49.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:136:0)
[04:17:49.740] Timer tick, asking Buildbucket for the build status
[04:17:49.996] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:49.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:137:0) after 1m0s
[04:18:50.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:137:0)
[04:18:50.134] Timer tick, asking Buildbucket for the build status
[04:18:52.203] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:52.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:138:0) after 1m0s
[04:19:52.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:138:0)
[04:19:52.300] Timer tick, asking Buildbucket for the build status
[04:19:52.795] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:52.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:139:0) after 1m0s
[04:20:52.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:139:0)
[04:20:52.863] Timer tick, asking Buildbucket for the build status
[04:20:53.007] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:53.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:140:0) after 1m0s
[04:21:53.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:140:0)
[04:21:53.123] Timer tick, asking Buildbucket for the build status
[04:21:53.270] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:53.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:141:0) after 1m0s
[04:22:53.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:141:0)
[04:22:53.353] Timer tick, asking Buildbucket for the build status
[04:22:53.481] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:53.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:142:0) after 1m0s
[04:23:53.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:142:0)
[04:23:53.555] Timer tick, asking Buildbucket for the build status
[04:23:53.776] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:53.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:143:0) after 1m0s
[04:24:53.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:143:0)
[04:24:53.858] Timer tick, asking Buildbucket for the build status
[04:24:54.047] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:54.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:144:0) after 1m0s
[04:25:54.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:144:0)
[04:25:54.277] Timer tick, asking Buildbucket for the build status
[04:25:54.673] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:54.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:145:0) after 1m0s
[04:26:54.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:145:0)
[04:26:54.861] Timer tick, asking Buildbucket for the build status
[04:26:55.269] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:55.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:146:0) after 1m0s
[04:27:55.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:146:0)
[04:27:55.403] Timer tick, asking Buildbucket for the build status
[04:27:55.621] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:55.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:147:0) after 1m0s
[04:28:55.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:147:0)
[04:28:55.661] Timer tick, asking Buildbucket for the build status
[04:28:55.790] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:55.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:148:0) after 1m0s
[04:29:55.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:148:0)
[04:29:55.860] Timer tick, asking Buildbucket for the build status
[04:29:56.042] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:56.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:149:0) after 1m0s
[04:30:56.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:149:0)
[04:30:56.182] Timer tick, asking Buildbucket for the build status
[04:30:56.356] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:56.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:150:0) after 1m0s
[04:31:56.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:150:0)
[04:31:56.506] Timer tick, asking Buildbucket for the build status
[04:31:56.697] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:56.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:151:0) after 1m0s
[04:32:56.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:151:0)
[04:32:56.795] Timer tick, asking Buildbucket for the build status
[04:32:57.051] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:57.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:152:0) after 1m0s
[04:33:57.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:152:0)
[04:33:57.068] Timer tick, asking Buildbucket for the build status
[04:33:57.198] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:57.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:153:0) after 1m0s
[04:34:57.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:153:0)
[04:34:57.267] Timer tick, asking Buildbucket for the build status
[04:34:57.505] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:57.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:154:0) after 1m0s
[04:35:57.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:154:0)
[04:35:57.580] Timer tick, asking Buildbucket for the build status
[04:35:57.691] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:57.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:155:0) after 1m0s
[04:36:57.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:155:0)
[04:36:57.758] Timer tick, asking Buildbucket for the build status
[04:36:57.918] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:57.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:156:0) after 1m0s
[04:37:57.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:156:0)
[04:37:57.967] Timer tick, asking Buildbucket for the build status
[04:37:58.179] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:58.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:157:0) after 1m0s
[04:38:58.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:157:0)
[04:38:58.362] Timer tick, asking Buildbucket for the build status
[04:38:58.525] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:58.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:158:0) after 1m0s
[04:39:58.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:158:0)
[04:39:58.616] Timer tick, asking Buildbucket for the build status
[04:39:58.747] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:58.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:159:0) after 1m0s
[04:40:58.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:159:0)
[04:40:58.793] Timer tick, asking Buildbucket for the build status
[04:41:00.658] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:00.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:160:0) after 1m0s
[04:42:01.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:160:0)
[04:42:01.779] Timer tick, asking Buildbucket for the build status
[04:42:02.239] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:02.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:161:0) after 1m0s
[04:43:02.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:161:0)
[04:43:02.299] Timer tick, asking Buildbucket for the build status
[04:43:03.294] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:03.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:162:0) after 1m0s
[04:44:03.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:162:0)
[04:44:03.386] Timer tick, asking Buildbucket for the build status
[04:44:03.835] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:03.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:163:0) after 1m0s
[04:45:04.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:163:0)
[04:45:04.229] Timer tick, asking Buildbucket for the build status
[04:45:04.394] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:04.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:164:0) after 1m0s
[04:46:04.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:164:0)
[04:46:04.478] Timer tick, asking Buildbucket for the build status
[04:46:05.003] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:05.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:165:0) after 1m0s
[04:47:05.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:165:0)
[04:47:05.134] Timer tick, asking Buildbucket for the build status
[04:47:05.487] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:05.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:166:0) after 1m0s
[04:48:05.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:166:0)
[04:48:05.586] Timer tick, asking Buildbucket for the build status
[04:48:05.929] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:05.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:167:0) after 1m0s
[04:49:06.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:167:0)
[04:49:06.129] Timer tick, asking Buildbucket for the build status
[04:49:06.432] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:06.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:168:0) after 1m0s
[04:50:06.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:168:0)
[04:50:06.536] Timer tick, asking Buildbucket for the build status
[04:50:06.723] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:06.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:169:0) after 1m0s
[04:51:06.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:169:0)
[04:51:06.893] Timer tick, asking Buildbucket for the build status
[04:51:07.001] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:07.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:170:0) after 1m0s
[04:52:07.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:170:0)
[04:52:07.101] Timer tick, asking Buildbucket for the build status
[04:52:07.287] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:07.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:171:0) after 1m0s
[04:53:07.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:171:0)
[04:53:07.458] Timer tick, asking Buildbucket for the build status
[04:53:07.586] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:07.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:172:0) after 1m0s
[04:54:07.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:172:0)
[04:54:07.777] Timer tick, asking Buildbucket for the build status
[04:54:07.916] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:07.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:173:0) after 1m0s
[04:55:08.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:173:0)
[04:55:08.164] Timer tick, asking Buildbucket for the build status
[04:55:08.403] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:08.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:174:0) after 1m0s
[04:56:08.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:174:0)
[04:56:08.467] Timer tick, asking Buildbucket for the build status
[04:56:08.877] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:08.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:175:0) after 1m0s
[04:57:09.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:175:0)
[04:57:09.076] Timer tick, asking Buildbucket for the build status
[04:57:09.970] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:09.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:176:0) after 1m0s
[04:58:10.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:176:0)
[04:58:10.146] Timer tick, asking Buildbucket for the build status
[04:58:10.969] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:10.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:177:0) after 1m0s
[04:59:11.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:177:0)
[04:59:11.001] Timer tick, asking Buildbucket for the build status
[04:59:12.293] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:12.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:178:0) after 1m0s
[05:00:12.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:178:0)
[05:00:12.345] Timer tick, asking Buildbucket for the build status
[05:00:12.603] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:12.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:179:0) after 1m0s
[05:01:12.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:179:0)
[05:01:12.688] Timer tick, asking Buildbucket for the build status
[05:01:13.076] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:13.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:180:0) after 1m0s
[05:02:13.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:180:0)
[05:02:13.160] Timer tick, asking Buildbucket for the build status
[05:02:14.183] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:14.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:181:0) after 1m0s
[05:03:14.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:181:0)
[05:03:14.268] Timer tick, asking Buildbucket for the build status
[05:03:14.473] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:14.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:182:0) after 1m0s
[05:04:14.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:182:0)
[05:04:14.612] Timer tick, asking Buildbucket for the build status
[05:04:14.802] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:14.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:183:0) after 1m0s
[05:05:14.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:183:0)
[05:05:14.879] Timer tick, asking Buildbucket for the build status
[05:05:15.186] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:15.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:184:0) after 1m0s
[05:06:15.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:184:0)
[05:06:15.216] Timer tick, asking Buildbucket for the build status
[05:06:15.341] Build 8878371099629897136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:15.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043801161821845440:185:0) after 1m0s
[05:06:38.565] Received PubSub notification, asking Buildbucket for the build status
[05:06:38.807] Build 8878371099629897136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:06:38.807] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1591333596905000",
    "created_by": "project:infra",
    "created_ts": "1591322532967998",
    "id": "8878371099629897136",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4162.4\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f1636ea9-2964-4ebf-9236-b88e1eb83792\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4162.4\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-qx2l\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5165-18126ad\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1591322725003366",
    "status": "COMPLETED",
    "status_changed_ts": "1591333597733013",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9043801161821845440",
      "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/8878371099629897136/+/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:4c9b08697317e710",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1591333597914909",
    "url": "https://ci.chromium.org/b/8878371099629897136",
    "utcnow_ts": "1591333598768621"
  }
}
[05:06:38.807] Invocation finished in 3h4m27.314168997s with status SUCCEEDED