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

• parent_buildername:publish_tarball
• user_agent:recipe

[12:17:34.717] New invocation is queued and will start shortly
[12:17:34.717] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:17:35.878] Starting the invocation (attempt 1)
[12:17:35.917] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:17:35.917] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:17:35.917] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9036695881655234976",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"87.0.4241.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"c9a6ac3e-c51b-406d-9fa6-fef473289edb\",\"title\":\"publish_tarball/0\"}]},\"version\":\"87.0.4241.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9036695881655234976",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:17:36.382] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1598098656011638",
    "id": "8871265819632340080",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4241.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c9a6ac3e-c51b-406d-9fa6-fef473289edb\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4241.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1598098656243957",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036695881655234976",
      "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/8871265819632340080/+/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": "1598098656244104",
    "url": "https://ci.chromium.org/b/8871265819632340080",
    "utcnow_ts": "1598098656366755"
  }
}
[12:17:36.382] Task URL: https://ci.chromium.org/b/8871265819632340080
[12:17:36.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:2:0) after 1m0s
[12:18:36.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:2:0)
[12:18:36.473] Timer tick, asking Buildbucket for the build status
[12:18:36.571] Build 8871265819632340080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:36.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:3:0) after 1m0s
[12:18:36.733] Received PubSub notification, asking Buildbucket for the build status
[12:18:36.857] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:36.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:3:0)
[12:19:36.724] Timer tick, asking Buildbucket for the build status
[12:19:36.851] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:36.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:5:0) after 1m0s
[12:20:36.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:5:0)
[12:20:36.907] Timer tick, asking Buildbucket for the build status
[12:20:37.116] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:37.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:6:0) after 1m0s
[12:21:37.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:6:0)
[12:21:37.326] Timer tick, asking Buildbucket for the build status
[12:21:37.438] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:37.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:7:0) after 1m0s
[12:22:37.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:7:0)
[12:22:37.552] Timer tick, asking Buildbucket for the build status
[12:22:38.761] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:38.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:8:0) after 1m0s
[12:23:38.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:8:0)
[12:23:38.847] Timer tick, asking Buildbucket for the build status
[12:23:38.994] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:38.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:9:0) after 1m0s
[12:24:39.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:9:0)
[12:24:39.094] Timer tick, asking Buildbucket for the build status
[12:24:39.183] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:39.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:10:0) after 1m0s
[12:25:39.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:10:0)
[12:25:39.306] Timer tick, asking Buildbucket for the build status
[12:25:39.382] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:39.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:11:0) after 1m0s
[12:26:39.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:11:0)
[12:26:39.409] Timer tick, asking Buildbucket for the build status
[12:26:39.494] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:39.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:12:0) after 1m0s
[12:27:39.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:12:0)
[12:27:39.522] Timer tick, asking Buildbucket for the build status
[12:27:39.965] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:39.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:13:0) after 1m0s
[12:28:39.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:13:0)
[12:28:39.992] Timer tick, asking Buildbucket for the build status
[12:28:40.406] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:40.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:14:0) after 1m0s
[12:29:40.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:14:0)
[12:29:40.438] Timer tick, asking Buildbucket for the build status
[12:29:40.535] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:40.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:15:0) after 1m0s
[12:30:40.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:15:0)
[12:30:40.649] Timer tick, asking Buildbucket for the build status
[12:30:40.745] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:40.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:16:0) after 1m0s
[12:31:40.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:16:0)
[12:31:40.744] Timer tick, asking Buildbucket for the build status
[12:31:40.848] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:40.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:17:0) after 1m0s
[12:32:40.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:17:0)
[12:32:40.928] Timer tick, asking Buildbucket for the build status
[12:32:53.725] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:53.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:18:0) after 1m0s
[12:33:53.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:18:0)
[12:33:53.781] Timer tick, asking Buildbucket for the build status
[12:33:53.849] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:53.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:19:0) after 1m0s
[12:34:53.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:19:0)
[12:34:53.895] Timer tick, asking Buildbucket for the build status
[12:34:53.973] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:53.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:20:0) after 1m0s
[12:35:53.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:20:0)
[12:35:53.965] Timer tick, asking Buildbucket for the build status
[12:35:54.030] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:54.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:21:0) after 1m0s
[12:36:54.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:21:0)
[12:36:54.269] Timer tick, asking Buildbucket for the build status
[12:36:54.346] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:54.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:22:0) after 1m0s
[12:37:54.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:22:0)
[12:37:54.526] Timer tick, asking Buildbucket for the build status
[12:37:54.663] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:54.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:23:0) after 1m0s
[12:38:54.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:23:0)
[12:38:54.755] Timer tick, asking Buildbucket for the build status
[12:38:54.877] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:54.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:24:0) after 1m0s
[12:39:55.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:24:0)
[12:39:55.140] Timer tick, asking Buildbucket for the build status
[12:39:55.215] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:55.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:25:0) after 1m0s
[12:40:55.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:25:0)
[12:40:55.342] Timer tick, asking Buildbucket for the build status
[12:40:55.411] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:55.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:26:0) after 1m0s
[12:41:55.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:26:0)
[12:41:55.533] Timer tick, asking Buildbucket for the build status
[12:41:55.602] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:55.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:27:0) after 1m0s
[12:42:55.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:27:0)
[12:42:55.701] Timer tick, asking Buildbucket for the build status
[12:42:55.834] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:55.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:28:0) after 1m0s
[12:43:55.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:28:0)
[12:43:55.924] Timer tick, asking Buildbucket for the build status
[12:43:56.034] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:56.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:29:0) after 1m0s
[12:44:56.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:29:0)
[12:44:56.119] Timer tick, asking Buildbucket for the build status
[12:44:58.542] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:58.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:30:0) after 1m0s
[12:45:58.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:30:0)
[12:45:58.726] Timer tick, asking Buildbucket for the build status
[12:45:59.004] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:59.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:31:0) after 1m0s
[12:46:59.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:31:0)
[12:46:59.175] Timer tick, asking Buildbucket for the build status
[12:46:59.372] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:59.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:32:0) after 1m0s
[12:47:59.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:32:0)
[12:47:59.493] Timer tick, asking Buildbucket for the build status
[12:47:59.643] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:59.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:33:0) after 1m0s
[12:48:59.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:33:0)
[12:48:59.678] Timer tick, asking Buildbucket for the build status
[12:48:59.749] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:59.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:34:0) after 1m0s
[12:49:59.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:34:0)
[12:49:59.818] Timer tick, asking Buildbucket for the build status
[12:49:59.906] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:59.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:35:0) after 1m0s
[12:51:00.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:35:0)
[12:51:00.033] Timer tick, asking Buildbucket for the build status
[12:51:00.257] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:00.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:36:0) after 1m0s
[12:52:00.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:36:0)
[12:52:00.293] Timer tick, asking Buildbucket for the build status
[12:52:00.383] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:00.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:37:0) after 1m0s
[12:53:01.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:37:0)
[12:53:01.746] Timer tick, asking Buildbucket for the build status
[12:53:01.882] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:01.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:38:0) after 1m0s
[12:54:02.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:38:0)
[12:54:02.256] Timer tick, asking Buildbucket for the build status
[12:54:02.377] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:02.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:39:0) after 1m0s
[12:55:02.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:39:0)
[12:55:02.381] Timer tick, asking Buildbucket for the build status
[12:55:02.455] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:02.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:40:0) after 1m0s
[12:56:02.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:40:0)
[12:56:02.528] Timer tick, asking Buildbucket for the build status
[12:56:05.826] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:05.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:41:0) after 1m0s
[12:57:05.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:41:0)
[12:57:05.905] Timer tick, asking Buildbucket for the build status
[12:57:06.127] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:06.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:42:0) after 1m0s
[12:58:06.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:42:0)
[12:58:06.396] Timer tick, asking Buildbucket for the build status
[12:58:06.534] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:06.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:43:0) after 1m0s
[12:59:06.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:43:0)
[12:59:06.930] Timer tick, asking Buildbucket for the build status
[12:59:07.096] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:07.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:44:0) after 1m0s
[13:00:07.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:44:0)
[13:00:07.376] Timer tick, asking Buildbucket for the build status
[13:00:07.487] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:07.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:45:0) after 1m0s
[13:01:08.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:45:0)
[13:01:08.097] Timer tick, asking Buildbucket for the build status
[13:01:08.191] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:08.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:46:0) after 1m0s
[13:02:08.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:46:0)
[13:02:08.260] Timer tick, asking Buildbucket for the build status
[13:02:09.115] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:09.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:47:0) after 1m0s
[13:03:09.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:47:0)
[13:03:09.167] Timer tick, asking Buildbucket for the build status
[13:03:09.292] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:09.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:48:0) after 1m0s
[13:04:09.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:48:0)
[13:04:09.306] Timer tick, asking Buildbucket for the build status
[13:04:09.434] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:09.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:49:0) after 1m0s
[13:05:09.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:49:0)
[13:05:09.617] Timer tick, asking Buildbucket for the build status
[13:05:09.815] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:09.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:50:0) after 1m0s
[13:06:09.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:50:0)
[13:06:09.835] Timer tick, asking Buildbucket for the build status
[13:06:09.953] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:09.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:51:0) after 1m0s
[13:07:09.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:51:0)
[13:07:09.976] Timer tick, asking Buildbucket for the build status
[13:07:11.315] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:11.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:52:0) after 1m0s
[13:08:11.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:52:0)
[13:08:11.415] Timer tick, asking Buildbucket for the build status
[13:08:12.056] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:12.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:53:0) after 1m0s
[13:09:12.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:53:0)
[13:09:12.149] Timer tick, asking Buildbucket for the build status
[13:09:12.250] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:12.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:54:0) after 1m0s
[13:10:12.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:54:0)
[13:10:12.313] Timer tick, asking Buildbucket for the build status
[13:10:12.620] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:12.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:55:0) after 1m0s
[13:11:12.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:55:0)
[13:11:12.666] Timer tick, asking Buildbucket for the build status
[13:11:12.743] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:12.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:56:0) after 1m0s
[13:12:12.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:56:0)
[13:12:12.980] Timer tick, asking Buildbucket for the build status
[13:12:17.957] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:17.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:57:0) after 1m0s
[13:13:18.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:57:0)
[13:13:18.360] Timer tick, asking Buildbucket for the build status
[13:13:18.640] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:18.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:58:0) after 1m0s
[13:14:18.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:58:0)
[13:14:18.778] Timer tick, asking Buildbucket for the build status
[13:14:19.005] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:19.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:59:0) after 1m0s
[13:15:19.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:59:0)
[13:15:19.065] Timer tick, asking Buildbucket for the build status
[13:15:19.277] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:19.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:60:0) after 1m0s
[13:16:19.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:60:0)
[13:16:19.299] Timer tick, asking Buildbucket for the build status
[13:16:19.432] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:19.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:61:0) after 1m0s
[13:17:19.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:61:0)
[13:17:19.538] Timer tick, asking Buildbucket for the build status
[13:17:19.672] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:19.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:62:0) after 1m0s
[13:18:20.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:62:0)
[13:18:20.101] Timer tick, asking Buildbucket for the build status
[13:18:20.221] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:20.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:63:0) after 1m0s
[13:19:20.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:63:0)
[13:19:20.411] Timer tick, asking Buildbucket for the build status
[13:19:20.529] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:20.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:64:0) after 1m0s
[13:20:20.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:64:0)
[13:20:20.924] Timer tick, asking Buildbucket for the build status
[13:20:21.002] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:21.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:65:0) after 1m0s
[13:21:21.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:65:0)
[13:21:21.232] Timer tick, asking Buildbucket for the build status
[13:21:21.486] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:21.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:66:0) after 1m0s
[13:22:21.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:66:0)
[13:22:21.724] Timer tick, asking Buildbucket for the build status
[13:22:21.931] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:21.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:67:0) after 1m0s
[13:23:22.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:67:0)
[13:23:22.047] Timer tick, asking Buildbucket for the build status
[13:23:22.669] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:22.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:68:0) after 1m0s
[13:24:23.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:68:0)
[13:24:23.059] Timer tick, asking Buildbucket for the build status
[13:24:23.122] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:23.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:69:0) after 1m0s
[13:25:23.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:69:0)
[13:25:23.306] Timer tick, asking Buildbucket for the build status
[13:25:23.512] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:23.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:70:0) after 1m0s
[13:26:23.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:70:0)
[13:26:23.884] Timer tick, asking Buildbucket for the build status
[13:26:23.965] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:23.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:71:0) after 1m0s
[13:27:24.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:71:0)
[13:27:24.282] Timer tick, asking Buildbucket for the build status
[13:27:24.424] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:24.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:72:0) after 1m0s
[13:28:24.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:72:0)
[13:28:24.604] Timer tick, asking Buildbucket for the build status
[13:28:24.915] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:24.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:73:0) after 1m0s
[13:29:25.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:73:0)
[13:29:25.029] Timer tick, asking Buildbucket for the build status
[13:29:25.103] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:25.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:74:0) after 1m0s
[13:30:25.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:74:0)
[13:30:25.103] Timer tick, asking Buildbucket for the build status
[13:30:25.231] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:25.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:75:0) after 1m0s
[13:31:25.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:75:0)
[13:31:25.281] Timer tick, asking Buildbucket for the build status
[13:31:25.448] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:25.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:76:0) after 1m0s
[13:32:25.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:76:0)
[13:32:25.500] Timer tick, asking Buildbucket for the build status
[13:32:25.561] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:25.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:77:0) after 1m0s
[13:33:25.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:77:0)
[13:33:25.677] Timer tick, asking Buildbucket for the build status
[13:33:25.866] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:25.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:78:0) after 1m0s
[13:34:25.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:78:0)
[13:34:25.909] Timer tick, asking Buildbucket for the build status
[13:34:26.008] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:26.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:79:0) after 1m0s
[13:35:26.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:79:0)
[13:35:26.294] Timer tick, asking Buildbucket for the build status
[13:35:26.449] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:26.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:80:0) after 1m0s
[13:36:26.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:80:0)
[13:36:26.564] Timer tick, asking Buildbucket for the build status
[13:36:26.817] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:26.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:81:0) after 1m0s
[13:37:26.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:81:0)
[13:37:26.951] Timer tick, asking Buildbucket for the build status
[13:37:27.700] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:27.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:82:0) after 1m0s
[13:38:27.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:82:0)
[13:38:27.726] Timer tick, asking Buildbucket for the build status
[13:38:27.867] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:27.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:83:0) after 1m0s
[13:39:28.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:83:0)
[13:39:28.040] Timer tick, asking Buildbucket for the build status
[13:39:28.116] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:28.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:84:0) after 1m0s
[13:40:28.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:84:0)
[13:40:28.289] Timer tick, asking Buildbucket for the build status
[13:40:28.444] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:28.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:85:0) after 1m0s
[13:41:28.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:85:0)
[13:41:28.629] Timer tick, asking Buildbucket for the build status
[13:41:28.701] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:28.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:86:0) after 1m0s
[13:42:28.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:86:0)
[13:42:28.735] Timer tick, asking Buildbucket for the build status
[13:42:28.917] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:28.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:87:0) after 1m0s
[13:43:29.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:87:0)
[13:43:29.535] Timer tick, asking Buildbucket for the build status
[13:43:29.670] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:29.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:88:0) after 1m0s
[13:44:29.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:88:0)
[13:44:29.802] Timer tick, asking Buildbucket for the build status
[13:44:29.874] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:29.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:89:0) after 1m0s
[13:45:29.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:89:0)
[13:45:29.974] Timer tick, asking Buildbucket for the build status
[13:45:30.085] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:30.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:90:0) after 1m0s
[13:46:30.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:90:0)
[13:46:30.248] Timer tick, asking Buildbucket for the build status
[13:46:30.991] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:30.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:91:0) after 1m0s
[13:47:30.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:91:0)
[13:47:30.995] Timer tick, asking Buildbucket for the build status
[13:47:31.105] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:31.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:92:0) after 1m0s
[13:48:31.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:92:0)
[13:48:31.142] Timer tick, asking Buildbucket for the build status
[13:48:31.253] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:31.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:93:0) after 1m0s
[13:49:31.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:93:0)
[13:49:31.284] Timer tick, asking Buildbucket for the build status
[13:49:31.353] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:31.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:94:0) after 1m0s
[13:50:31.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:94:0)
[13:50:31.389] Timer tick, asking Buildbucket for the build status
[13:50:31.462] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:31.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:95:0) after 1m0s
[13:51:31.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:95:0)
[13:51:31.559] Timer tick, asking Buildbucket for the build status
[13:51:31.859] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:31.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:96:0) after 1m0s
[13:52:31.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:96:0)
[13:52:31.884] Timer tick, asking Buildbucket for the build status
[13:52:31.984] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:31.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:97:0) after 1m0s
[13:53:32.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:97:0)
[13:53:32.016] Timer tick, asking Buildbucket for the build status
[13:53:32.164] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:32.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:98:0) after 1m0s
[13:54:32.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:98:0)
[13:54:32.188] Timer tick, asking Buildbucket for the build status
[13:54:32.277] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:32.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:99:0) after 1m0s
[13:55:32.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:99:0)
[13:55:32.367] Timer tick, asking Buildbucket for the build status
[13:55:32.531] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:32.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:100:0) after 1m0s
[13:56:32.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:100:0)
[13:56:32.672] Timer tick, asking Buildbucket for the build status
[13:56:32.753] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:32.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:101:0) after 1m0s
[13:57:33.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:101:0)
[13:57:33.107] Timer tick, asking Buildbucket for the build status
[13:57:34.685] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:34.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:102:0) after 1m0s
[13:58:34.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:102:0)
[13:58:34.983] Timer tick, asking Buildbucket for the build status
[13:58:35.226] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:35.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:103:0) after 1m0s
[13:59:35.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:103:0)
[13:59:35.291] Timer tick, asking Buildbucket for the build status
[13:59:35.372] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:35.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:104:0) after 1m0s
[14:00:35.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:104:0)
[14:00:35.535] Timer tick, asking Buildbucket for the build status
[14:00:35.692] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:35.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:105:0) after 1m0s
[14:01:35.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:105:0)
[14:01:35.720] Timer tick, asking Buildbucket for the build status
[14:01:35.796] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:35.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:106:0) after 1m0s
[14:02:35.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:106:0)
[14:02:35.832] Timer tick, asking Buildbucket for the build status
[14:02:35.927] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:35.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:107:0) after 1m0s
[14:03:36.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:107:0)
[14:03:36.028] Timer tick, asking Buildbucket for the build status
[14:03:36.200] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:36.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:108:0) after 1m0s
[14:04:36.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:108:0)
[14:04:36.369] Timer tick, asking Buildbucket for the build status
[14:04:36.541] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:36.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:109:0) after 1m0s
[14:05:36.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:109:0)
[14:05:36.569] Timer tick, asking Buildbucket for the build status
[14:05:36.905] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:36.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:110:0) after 1m0s
[14:06:36.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:110:0)
[14:06:36.922] Timer tick, asking Buildbucket for the build status
[14:06:37.001] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:37.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:111:0) after 1m0s
[14:07:37.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:111:0)
[14:07:37.093] Timer tick, asking Buildbucket for the build status
[14:07:37.337] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:37.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:112:0) after 1m0s
[14:08:37.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:112:0)
[14:08:37.443] Timer tick, asking Buildbucket for the build status
[14:08:37.625] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:37.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:113:0) after 1m0s
[14:09:37.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:113:0)
[14:09:37.632] Timer tick, asking Buildbucket for the build status
[14:09:37.861] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:37.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:114:0) after 1m0s
[14:10:37.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:114:0)
[14:10:37.958] Timer tick, asking Buildbucket for the build status
[14:10:38.022] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:38.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:115:0) after 1m0s
[14:11:38.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:115:0)
[14:11:38.172] Timer tick, asking Buildbucket for the build status
[14:11:38.862] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:38.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:116:0) after 1m0s
[14:12:38.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:116:0)
[14:12:38.891] Timer tick, asking Buildbucket for the build status
[14:12:39.087] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:39.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:117:0) after 1m0s
[14:13:39.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:117:0)
[14:13:39.199] Timer tick, asking Buildbucket for the build status
[14:13:40.459] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:40.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:118:0) after 1m0s
[14:14:40.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:118:0)
[14:14:40.513] Timer tick, asking Buildbucket for the build status
[14:14:40.669] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:40.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:119:0) after 1m0s
[14:15:40.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:119:0)
[14:15:40.655] Timer tick, asking Buildbucket for the build status
[14:15:40.738] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:40.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:120:0) after 1m0s
[14:16:40.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:120:0)
[14:16:40.720] Timer tick, asking Buildbucket for the build status
[14:16:40.778] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:40.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:121:0) after 1m0s
[14:17:41.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:121:0)
[14:17:41.127] Timer tick, asking Buildbucket for the build status
[14:17:41.276] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:41.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:122:0) after 1m0s
[14:18:41.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:122:0)
[14:18:41.437] Timer tick, asking Buildbucket for the build status
[14:18:41.682] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:41.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:123:0) after 1m0s
[14:19:41.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:123:0)
[14:19:41.807] Timer tick, asking Buildbucket for the build status
[14:19:41.899] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:41.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:124:0) after 1m0s
[14:20:42.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:124:0)
[14:20:42.039] Timer tick, asking Buildbucket for the build status
[14:20:42.109] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:42.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:125:0) after 1m0s
[14:21:42.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:125:0)
[14:21:42.221] Timer tick, asking Buildbucket for the build status
[14:21:42.309] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:42.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:126:0) after 1m0s
[14:22:42.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:126:0)
[14:22:42.334] Timer tick, asking Buildbucket for the build status
[14:22:42.437] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:42.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:127:0) after 1m0s
[14:23:42.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:127:0)
[14:23:42.623] Timer tick, asking Buildbucket for the build status
[14:23:42.803] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:42.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:128:0) after 1m0s
[14:24:42.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:128:0)
[14:24:42.830] Timer tick, asking Buildbucket for the build status
[14:24:43.050] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:43.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:129:0) after 1m0s
[14:25:43.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:129:0)
[14:25:43.079] Timer tick, asking Buildbucket for the build status
[14:25:43.138] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:43.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:130:0) after 1m0s
[14:26:43.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:130:0)
[14:26:43.170] Timer tick, asking Buildbucket for the build status
[14:26:43.238] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:43.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:131:0) after 1m0s
[14:27:43.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:131:0)
[14:27:43.267] Timer tick, asking Buildbucket for the build status
[14:27:43.349] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:43.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:132:0) after 1m0s
[14:28:43.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:132:0)
[14:28:43.398] Timer tick, asking Buildbucket for the build status
[14:28:43.496] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:43.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:133:0) after 1m0s
[14:29:43.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:133:0)
[14:29:43.527] Timer tick, asking Buildbucket for the build status
[14:29:43.637] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:43.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:134:0) after 1m0s
[14:30:43.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:134:0)
[14:30:43.709] Timer tick, asking Buildbucket for the build status
[14:30:45.611] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:45.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:135:0) after 1m0s
[14:31:45.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:135:0)
[14:31:45.635] Timer tick, asking Buildbucket for the build status
[14:31:45.728] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:45.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:136:0) after 1m0s
[14:32:45.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:136:0)
[14:32:45.753] Timer tick, asking Buildbucket for the build status
[14:32:45.861] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:45.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:137:0) after 1m0s
[14:33:45.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:137:0)
[14:33:45.922] Timer tick, asking Buildbucket for the build status
[14:33:46.008] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:46.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:138:0) after 1m0s
[14:34:46.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:138:0)
[14:34:46.172] Timer tick, asking Buildbucket for the build status
[14:34:46.393] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:46.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:139:0) after 1m0s
[14:35:46.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:139:0)
[14:35:46.425] Timer tick, asking Buildbucket for the build status
[14:35:48.921] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:48.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:140:0) after 1m0s
[14:36:48.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:140:0)
[14:36:48.981] Timer tick, asking Buildbucket for the build status
[14:36:49.141] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:49.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:141:0) after 1m0s
[14:37:49.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:141:0)
[14:37:49.168] Timer tick, asking Buildbucket for the build status
[14:37:49.410] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:49.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:142:0) after 1m0s
[14:38:49.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:142:0)
[14:38:49.534] Timer tick, asking Buildbucket for the build status
[14:38:49.614] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:49.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:143:0) after 1m0s
[14:39:49.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:143:0)
[14:39:49.756] Timer tick, asking Buildbucket for the build status
[14:39:49.828] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:49.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:144:0) after 1m0s
[14:40:49.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:144:0)
[14:40:49.854] Timer tick, asking Buildbucket for the build status
[14:40:49.937] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:49.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:145:0) after 1m0s
[14:41:50.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:145:0)
[14:41:50.017] Timer tick, asking Buildbucket for the build status
[14:41:50.094] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:50.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:146:0) after 1m0s
[14:42:50.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:146:0)
[14:42:50.122] Timer tick, asking Buildbucket for the build status
[14:42:50.252] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:50.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:147:0) after 1m0s
[14:43:50.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:147:0)
[14:43:50.381] Timer tick, asking Buildbucket for the build status
[14:43:50.481] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:50.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:148:0) after 1m0s
[14:44:50.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:148:0)
[14:44:50.532] Timer tick, asking Buildbucket for the build status
[14:44:50.704] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:50.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:149:0) after 1m0s
[14:45:50.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:149:0)
[14:45:50.887] Timer tick, asking Buildbucket for the build status
[14:45:50.977] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:50.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:150:0) after 1m0s
[14:46:51.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:150:0)
[14:46:51.072] Timer tick, asking Buildbucket for the build status
[14:46:51.405] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:51.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:151:0) after 1m0s
[14:47:51.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:151:0)
[14:47:51.400] Timer tick, asking Buildbucket for the build status
[14:47:51.517] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:51.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:152:0) after 1m0s
[14:48:51.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:152:0)
[14:48:51.597] Timer tick, asking Buildbucket for the build status
[14:48:51.705] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:51.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:153:0) after 1m0s
[14:49:51.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:153:0)
[14:49:51.778] Timer tick, asking Buildbucket for the build status
[14:49:51.860] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:51.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:154:0) after 1m0s
[14:50:51.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:154:0)
[14:50:51.937] Timer tick, asking Buildbucket for the build status
[14:50:54.698] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:54.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:155:0) after 1m0s
[14:51:54.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:155:0)
[14:51:54.745] Timer tick, asking Buildbucket for the build status
[14:51:54.826] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:54.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:156:0) after 1m0s
[14:52:54.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:156:0)
[14:52:54.900] Timer tick, asking Buildbucket for the build status
[14:52:55.026] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:55.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:157:0) after 1m0s
[14:53:55.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:157:0)
[14:53:55.128] Timer tick, asking Buildbucket for the build status
[14:53:55.204] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:55.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:158:0) after 1m0s
[14:54:55.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:158:0)
[14:54:55.292] Timer tick, asking Buildbucket for the build status
[14:54:55.356] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:55.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:159:0) after 1m0s
[14:55:55.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:159:0)
[14:55:55.384] Timer tick, asking Buildbucket for the build status
[14:55:55.475] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:55.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:160:0) after 1m0s
[14:56:55.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:160:0)
[14:56:55.590] Timer tick, asking Buildbucket for the build status
[14:56:55.671] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:55.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:161:0) after 1m0s
[14:57:55.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:161:0)
[14:57:55.772] Timer tick, asking Buildbucket for the build status
[14:57:55.884] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:55.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:162:0) after 1m0s
[14:58:55.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:162:0)
[14:58:55.985] Timer tick, asking Buildbucket for the build status
[14:58:56.067] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:56.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:163:0) after 1m0s
[14:59:56.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:163:0)
[14:59:56.102] Timer tick, asking Buildbucket for the build status
[14:59:56.183] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:56.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:164:0) after 1m0s
[15:00:56.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:164:0)
[15:00:56.326] Timer tick, asking Buildbucket for the build status
[15:00:56.514] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:56.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:165:0) after 1m0s
[15:01:56.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:165:0)
[15:01:56.599] Timer tick, asking Buildbucket for the build status
[15:01:58.555] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:58.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:166:0) after 1m0s
[15:02:58.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:166:0)
[15:02:58.655] Timer tick, asking Buildbucket for the build status
[15:02:59.055] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:59.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:167:0) after 1m0s
[15:03:59.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:167:0)
[15:03:59.069] Timer tick, asking Buildbucket for the build status
[15:03:59.161] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:59.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:168:0) after 1m0s
[15:04:59.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:168:0)
[15:04:59.195] Timer tick, asking Buildbucket for the build status
[15:04:59.279] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:59.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:169:0) after 1m0s
[15:05:59.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:169:0)
[15:05:59.267] Timer tick, asking Buildbucket for the build status
[15:05:59.384] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:59.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:170:0) after 1m0s
[15:06:59.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:170:0)
[15:06:59.418] Timer tick, asking Buildbucket for the build status
[15:06:59.504] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:59.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:171:0) after 1m0s
[15:07:59.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:171:0)
[15:07:59.529] Timer tick, asking Buildbucket for the build status
[15:07:59.728] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:59.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:172:0) after 1m0s
[15:08:59.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:172:0)
[15:08:59.850] Timer tick, asking Buildbucket for the build status
[15:08:59.943] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:59.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:173:0) after 1m0s
[15:09:59.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:173:0)
[15:09:59.971] Timer tick, asking Buildbucket for the build status
[15:10:00.195] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:00.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:174:0) after 1m0s
[15:11:00.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:174:0)
[15:11:00.246] Timer tick, asking Buildbucket for the build status
[15:11:00.811] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:00.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:175:0) after 1m0s
[15:12:01.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:175:0)
[15:12:01.824] Timer tick, asking Buildbucket for the build status
[15:12:01.908] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:01.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:176:0) after 1m0s
[15:13:02.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:176:0)
[15:13:02.198] Timer tick, asking Buildbucket for the build status
[15:13:05.002] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:05.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:177:0) after 1m0s
[15:14:05.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:177:0)
[15:14:05.090] Timer tick, asking Buildbucket for the build status
[15:14:05.158] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:05.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:178:0) after 1m0s
[15:15:05.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:178:0)
[15:15:05.155] Timer tick, asking Buildbucket for the build status
[15:15:05.235] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:05.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:179:0) after 1m0s
[15:16:05.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:179:0)
[15:16:05.272] Timer tick, asking Buildbucket for the build status
[15:16:05.389] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:05.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:180:0) after 1m0s
[15:17:05.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:180:0)
[15:17:05.468] Timer tick, asking Buildbucket for the build status
[15:17:05.580] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:05.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:181:0) after 1m0s
[15:18:05.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:181:0)
[15:18:05.624] Timer tick, asking Buildbucket for the build status
[15:18:05.768] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:05.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:182:0) after 1m0s
[15:19:05.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:182:0)
[15:19:05.813] Timer tick, asking Buildbucket for the build status
[15:19:05.930] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:05.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:183:0) after 1m0s
[15:20:06.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:183:0)
[15:20:06.015] Timer tick, asking Buildbucket for the build status
[15:20:06.328] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:06.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:184:0) after 1m0s
[15:21:06.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:184:0)
[15:21:06.376] Timer tick, asking Buildbucket for the build status
[15:21:06.464] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:06.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:185:0) after 1m0s
[15:22:06.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:185:0)
[15:22:06.494] Timer tick, asking Buildbucket for the build status
[15:22:06.619] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:06.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:186:0) after 1m0s
[15:23:06.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:186:0)
[15:23:06.646] Timer tick, asking Buildbucket for the build status
[15:23:06.792] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:06.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:187:0) after 1m0s
[15:24:06.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:187:0)
[15:24:06.885] Timer tick, asking Buildbucket for the build status
[15:24:07.321] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:07.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:188:0) after 1m0s
[15:25:07.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:188:0)
[15:25:07.394] Timer tick, asking Buildbucket for the build status
[15:25:07.466] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:07.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:189:0) after 1m0s
[15:26:07.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:189:0)
[15:26:07.550] Timer tick, asking Buildbucket for the build status
[15:26:07.681] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:07.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:190:0) after 1m0s
[15:27:07.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:190:0)
[15:27:07.781] Timer tick, asking Buildbucket for the build status
[15:27:07.867] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:07.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:191:0) after 1m0s
[15:28:07.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:191:0)
[15:28:07.994] Timer tick, asking Buildbucket for the build status
[15:28:08.137] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:08.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:192:0) after 1m0s
[15:29:08.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:192:0)
[15:29:08.198] Timer tick, asking Buildbucket for the build status
[15:29:08.879] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:08.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:193:0) after 1m0s
[15:30:08.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:193:0)
[15:30:08.990] Timer tick, asking Buildbucket for the build status
[15:30:09.086] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:09.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:194:0) after 1m0s
[15:31:09.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:194:0)
[15:31:09.172] Timer tick, asking Buildbucket for the build status
[15:31:09.544] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:09.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:195:0) after 1m0s
[15:32:09.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:195:0)
[15:32:09.615] Timer tick, asking Buildbucket for the build status
[15:32:09.681] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:09.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:196:0) after 1m0s
[15:33:09.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:196:0)
[15:33:09.718] Timer tick, asking Buildbucket for the build status
[15:33:09.869] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:09.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:197:0) after 1m0s
[15:34:09.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:197:0)
[15:34:09.860] Timer tick, asking Buildbucket for the build status
[15:34:10.057] Build 8871265819632340080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:10.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036695881655234976:198:0) after 1m0s
[15:34:27.887] Received PubSub notification, asking Buildbucket for the build status
[15:34:28.012] Build 8871265819632340080: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:34:28.012] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1598110466563576",
    "created_by": "project:infra",
    "created_ts": "1598098656011638",
    "id": "8871265819632340080",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4241.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c9a6ac3e-c51b-406d-9fa6-fef473289edb\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4241.0\"}}",
    "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-wu28\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1598098715939163",
    "status": "COMPLETED",
    "status_changed_ts": "1598110466988415",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036695881655234976",
      "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/8871265819632340080/+/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:4e2eebd5f692ed10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1598110467004202",
    "url": "https://ci.chromium.org/b/8871265819632340080",
    "utcnow_ts": "1598110467988089"
  }
}
[15:34:28.017] Invocation finished in 3h16m53.318487987s with status SUCCEEDED