buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.infra.cron"
  builder: "Build From Tarball"
>

• publish_tarball/0 (5 years ago by chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com)

{
  "version": "86.0.4190.2"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[08:25:52.685] New invocation is queued and will start shortly
[08:25:52.685] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:25:54.215] Starting the invocation (attempt 1)
[08:25:54.258] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:25:54.258] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:25:54.258] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9041240307311194912",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4190.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"72f9a5f1-a020-46c6-ae60-827b5437c6ec\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4190.2\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9041240307311194912",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:25:55.677] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1593764754397909",
    "id": "8875810244851930128",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4190.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"72f9a5f1-a020-46c6-ae60-827b5437c6ec\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4190.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1593764755197276",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9041240307311194912",
      "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/8875810244851930128/+/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": "1593764755197462",
    "url": "https://ci.chromium.org/b/8875810244851930128",
    "utcnow_ts": "1593764755631112"
  }
}
[08:25:55.677] Task URL: https://ci.chromium.org/b/8875810244851930128
[08:25:55.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:2:0) after 1m0s
[08:26:23.893] Received PubSub notification, asking Buildbucket for the build status
[08:26:24.081] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:55.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:2:0)
[08:26:55.845] Timer tick, asking Buildbucket for the build status
[08:26:56.029] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:56.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:4:0) after 1m0s
[08:27:56.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:4:0)
[08:27:56.082] Timer tick, asking Buildbucket for the build status
[08:27:56.327] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:56.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:5:0) after 1m0s
[08:28:56.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:5:0)
[08:28:56.382] Timer tick, asking Buildbucket for the build status
[08:28:56.527] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:56.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:6:0) after 1m0s
[08:29:56.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:6:0)
[08:29:56.546] Timer tick, asking Buildbucket for the build status
[08:29:56.709] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:56.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:7:0) after 1m0s
[08:30:56.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:7:0)
[08:30:56.729] Timer tick, asking Buildbucket for the build status
[08:30:57.172] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:57.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:8:0) after 1m0s
[08:31:57.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:8:0)
[08:31:57.264] Timer tick, asking Buildbucket for the build status
[08:31:57.445] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:57.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:9:0) after 1m0s
[08:32:57.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:9:0)
[08:32:57.537] Timer tick, asking Buildbucket for the build status
[08:32:58.022] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:58.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:10:0) after 1m0s
[08:33:58.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:10:0)
[08:33:58.062] Timer tick, asking Buildbucket for the build status
[08:33:58.194] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:58.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:11:0) after 1m0s
[08:34:58.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:11:0)
[08:34:58.249] Timer tick, asking Buildbucket for the build status
[08:34:58.505] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:58.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:12:0) after 1m0s
[08:35:58.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:12:0)
[08:35:58.529] Timer tick, asking Buildbucket for the build status
[08:35:58.632] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:58.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:13:0) after 1m0s
[08:36:58.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:13:0)
[08:36:58.651] Timer tick, asking Buildbucket for the build status
[08:36:59.024] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:59.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:14:0) after 1m0s
[08:37:59.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:14:0)
[08:37:59.142] Timer tick, asking Buildbucket for the build status
[08:37:59.485] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:59.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:15:0) after 1m0s
[08:38:59.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:15:0)
[08:38:59.568] Timer tick, asking Buildbucket for the build status
[08:38:59.889] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:59.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:16:0) after 1m0s
[08:39:59.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:16:0)
[08:39:59.910] Timer tick, asking Buildbucket for the build status
[08:40:00.175] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:00.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:17:0) after 1m0s
[08:41:00.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:17:0)
[08:41:00.199] Timer tick, asking Buildbucket for the build status
[08:41:00.341] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:00.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:18:0) after 1m0s
[08:42:00.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:18:0)
[08:42:00.472] Timer tick, asking Buildbucket for the build status
[08:42:00.830] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:00.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:19:0) after 1m0s
[08:43:01.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:19:0)
[08:43:01.277] Timer tick, asking Buildbucket for the build status
[08:43:01.406] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:01.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:20:0) after 1m0s
[08:44:01.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:20:0)
[08:44:01.518] Timer tick, asking Buildbucket for the build status
[08:44:01.692] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:01.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:21:0) after 1m0s
[08:45:01.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:21:0)
[08:45:01.717] Timer tick, asking Buildbucket for the build status
[08:45:02.187] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:02.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:22:0) after 1m0s
[08:46:02.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:22:0)
[08:46:02.245] Timer tick, asking Buildbucket for the build status
[08:46:02.928] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:02.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:23:0) after 1m0s
[08:47:02.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:23:0)
[08:47:02.985] Timer tick, asking Buildbucket for the build status
[08:47:03.099] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:03.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:24:0) after 1m0s
[08:48:03.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:24:0)
[08:48:03.146] Timer tick, asking Buildbucket for the build status
[08:48:03.487] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:03.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:25:0) after 1m0s
[08:49:03.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:25:0)
[08:49:03.579] Timer tick, asking Buildbucket for the build status
[08:49:03.651] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:03.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:26:0) after 1m0s
[08:50:03.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:26:0)
[08:50:03.729] Timer tick, asking Buildbucket for the build status
[08:50:03.893] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:03.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:27:0) after 1m0s
[08:51:03.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:27:0)
[08:51:03.948] Timer tick, asking Buildbucket for the build status
[08:51:04.711] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:04.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:28:0) after 1m0s
[08:52:04.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:28:0)
[08:52:04.695] Timer tick, asking Buildbucket for the build status
[08:52:05.243] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:05.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:29:0) after 1m0s
[08:53:05.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:29:0)
[08:53:05.261] Timer tick, asking Buildbucket for the build status
[08:53:06.200] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:06.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:30:0) after 1m0s
[08:54:06.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:30:0)
[08:54:06.272] Timer tick, asking Buildbucket for the build status
[08:54:06.409] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:06.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:31:0) after 1m0s
[08:55:06.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:31:0)
[08:55:06.486] Timer tick, asking Buildbucket for the build status
[08:55:06.707] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:06.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:32:0) after 1m0s
[08:56:06.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:32:0)
[08:56:06.749] Timer tick, asking Buildbucket for the build status
[08:56:06.912] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:06.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:33:0) after 1m0s
[08:57:07.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:33:0)
[08:57:07.031] Timer tick, asking Buildbucket for the build status
[08:57:07.189] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:07.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:34:0) after 1m0s
[08:58:07.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:34:0)
[08:58:07.182] Timer tick, asking Buildbucket for the build status
[08:58:07.371] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:07.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:35:0) after 1m0s
[08:59:07.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:35:0)
[08:59:07.480] Timer tick, asking Buildbucket for the build status
[08:59:07.604] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:07.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:36:0) after 1m0s
[09:00:07.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:36:0)
[09:00:07.656] Timer tick, asking Buildbucket for the build status
[09:00:08.093] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:08.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:37:0) after 1m0s
[09:01:08.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:37:0)
[09:01:08.232] Timer tick, asking Buildbucket for the build status
[09:01:08.674] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:08.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:38:0) after 1m0s
[09:02:08.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:38:0)
[09:02:08.772] Timer tick, asking Buildbucket for the build status
[09:02:08.884] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:08.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:39:0) after 1m0s
[09:03:08.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:39:0)
[09:03:08.957] Timer tick, asking Buildbucket for the build status
[09:03:09.139] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:09.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:40:0) after 1m0s
[09:04:09.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:40:0)
[09:04:09.260] Timer tick, asking Buildbucket for the build status
[09:04:09.410] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:09.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:41:0) after 1m0s
[09:05:09.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:41:0)
[09:05:09.494] Timer tick, asking Buildbucket for the build status
[09:05:09.596] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:09.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:42:0) after 1m0s
[09:06:09.605] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:42:0)
[09:06:09.605] Timer tick, asking Buildbucket for the build status
[09:06:10.005] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:10.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:43:0) after 1m0s
[09:07:10.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:43:0)
[09:07:10.041] Timer tick, asking Buildbucket for the build status
[09:07:10.158] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:10.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:44:0) after 1m0s
[09:08:10.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:44:0)
[09:08:10.184] Timer tick, asking Buildbucket for the build status
[09:08:10.324] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:10.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:45:0) after 1m0s
[09:09:10.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:45:0)
[09:09:10.348] Timer tick, asking Buildbucket for the build status
[09:09:12.138] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:12.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:46:0) after 1m0s
[09:10:12.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:46:0)
[09:10:12.166] Timer tick, asking Buildbucket for the build status
[09:10:12.771] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:12.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:47:0) after 1m0s
[09:11:13.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:47:0)
[09:11:13.005] Timer tick, asking Buildbucket for the build status
[09:11:13.394] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:13.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:48:0) after 1m0s
[09:12:13.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:48:0)
[09:12:13.455] Timer tick, asking Buildbucket for the build status
[09:12:13.532] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:13.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:49:0) after 1m0s
[09:13:13.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:49:0)
[09:13:13.593] Timer tick, asking Buildbucket for the build status
[09:13:13.882] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:13.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:50:0) after 1m0s
[09:14:13.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:50:0)
[09:14:13.892] Timer tick, asking Buildbucket for the build status
[09:14:14.039] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:14.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:51:0) after 1m0s
[09:15:14.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:51:0)
[09:15:14.083] Timer tick, asking Buildbucket for the build status
[09:15:14.281] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:14.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:52:0) after 1m0s
[09:16:14.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:52:0)
[09:16:14.523] Timer tick, asking Buildbucket for the build status
[09:16:15.352] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:15.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:53:0) after 1m0s
[09:17:15.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:53:0)
[09:17:15.469] Timer tick, asking Buildbucket for the build status
[09:17:15.545] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:15.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:54:0) after 1m0s
[09:18:15.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:54:0)
[09:18:15.698] Timer tick, asking Buildbucket for the build status
[09:18:16.112] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:16.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:55:0) after 1m0s
[09:19:16.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:55:0)
[09:19:16.341] Timer tick, asking Buildbucket for the build status
[09:19:16.473] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:16.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:56:0) after 1m0s
[09:20:16.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:56:0)
[09:20:16.635] Timer tick, asking Buildbucket for the build status
[09:20:16.807] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:16.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:57:0) after 1m0s
[09:21:16.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:57:0)
[09:21:16.819] Timer tick, asking Buildbucket for the build status
[09:21:16.933] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:16.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:58:0) after 1m0s
[09:22:16.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:58:0)
[09:22:16.970] Timer tick, asking Buildbucket for the build status
[09:22:17.073] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:17.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:59:0) after 1m0s
[09:23:17.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:59:0)
[09:23:17.235] Timer tick, asking Buildbucket for the build status
[09:23:17.495] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:17.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:60:0) after 1m0s
[09:24:17.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:60:0)
[09:24:17.608] Timer tick, asking Buildbucket for the build status
[09:24:17.802] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:17.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:61:0) after 1m0s
[09:25:17.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:61:0)
[09:25:17.969] Timer tick, asking Buildbucket for the build status
[09:25:18.134] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:18.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:62:0) after 1m0s
[09:26:18.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:62:0)
[09:26:18.161] Timer tick, asking Buildbucket for the build status
[09:26:18.749] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:18.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:63:0) after 1m0s
[09:27:18.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:63:0)
[09:27:18.784] Timer tick, asking Buildbucket for the build status
[09:27:18.991] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:18.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:64:0) after 1m0s
[09:28:19.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:64:0)
[09:28:19.021] Timer tick, asking Buildbucket for the build status
[09:28:19.433] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:19.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:65:0) after 1m0s
[09:29:19.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:65:0)
[09:29:19.460] Timer tick, asking Buildbucket for the build status
[09:29:19.573] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:19.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:66:0) after 1m0s
[09:30:19.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:66:0)
[09:30:19.594] Timer tick, asking Buildbucket for the build status
[09:30:19.837] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:19.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:67:0) after 1m0s
[09:31:19.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:67:0)
[09:31:19.869] Timer tick, asking Buildbucket for the build status
[09:31:20.103] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:20.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:68:0) after 1m0s
[09:32:20.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:68:0)
[09:32:20.107] Timer tick, asking Buildbucket for the build status
[09:32:20.843] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:20.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:69:0) after 1m0s
[09:33:20.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:69:0)
[09:33:20.868] Timer tick, asking Buildbucket for the build status
[09:33:21.012] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:21.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:70:0) after 1m0s
[09:34:21.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:70:0)
[09:34:21.082] Timer tick, asking Buildbucket for the build status
[09:34:21.213] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:21.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:71:0) after 1m0s
[09:35:21.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:71:0)
[09:35:21.308] Timer tick, asking Buildbucket for the build status
[09:35:21.802] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:21.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:72:0) after 1m0s
[09:36:21.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:72:0)
[09:36:21.849] Timer tick, asking Buildbucket for the build status
[09:36:22.051] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:22.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:73:0) after 1m0s
[09:37:22.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:73:0)
[09:37:22.143] Timer tick, asking Buildbucket for the build status
[09:37:22.269] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:22.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:74:0) after 1m0s
[09:38:22.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:74:0)
[09:38:22.291] Timer tick, asking Buildbucket for the build status
[09:38:22.454] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:22.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:75:0) after 1m0s
[09:39:22.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:75:0)
[09:39:22.552] Timer tick, asking Buildbucket for the build status
[09:39:22.916] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:22.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:76:0) after 1m0s
[09:40:23.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:76:0)
[09:40:23.032] Timer tick, asking Buildbucket for the build status
[09:40:23.215] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:23.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:77:0) after 1m0s
[09:41:23.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:77:0)
[09:41:23.314] Timer tick, asking Buildbucket for the build status
[09:41:23.523] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:23.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:78:0) after 1m0s
[09:42:23.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:78:0)
[09:42:23.592] Timer tick, asking Buildbucket for the build status
[09:42:23.743] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:23.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:79:0) after 1m0s
[09:43:24.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:79:0)
[09:43:24.015] Timer tick, asking Buildbucket for the build status
[09:43:24.139] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:24.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:80:0) after 1m0s
[09:44:24.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:80:0)
[09:44:24.248] Timer tick, asking Buildbucket for the build status
[09:44:24.607] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:24.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:81:0) after 1m0s
[09:45:24.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:81:0)
[09:45:24.780] Timer tick, asking Buildbucket for the build status
[09:45:24.901] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:24.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:82:0) after 1m0s
[09:46:24.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:82:0)
[09:46:24.915] Timer tick, asking Buildbucket for the build status
[09:46:25.183] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:25.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:83:0) after 1m0s
[09:47:25.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:83:0)
[09:47:25.187] Timer tick, asking Buildbucket for the build status
[09:47:25.355] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:25.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:84:0) after 1m0s
[09:48:25.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:84:0)
[09:48:25.467] Timer tick, asking Buildbucket for the build status
[09:48:26.047] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:26.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:85:0) after 1m0s
[09:49:26.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:85:0)
[09:49:26.106] Timer tick, asking Buildbucket for the build status
[09:49:26.439] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:26.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:86:0) after 1m0s
[09:50:26.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:86:0)
[09:50:26.530] Timer tick, asking Buildbucket for the build status
[09:50:26.666] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:26.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:87:0) after 1m0s
[09:51:26.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:87:0)
[09:51:26.743] Timer tick, asking Buildbucket for the build status
[09:51:26.885] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:26.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:88:0) after 1m0s
[09:52:27.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:88:0)
[09:52:27.028] Timer tick, asking Buildbucket for the build status
[09:52:27.571] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:27.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:89:0) after 1m0s
[09:53:27.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:89:0)
[09:53:27.687] Timer tick, asking Buildbucket for the build status
[09:53:27.930] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:27.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:90:0) after 1m0s
[09:54:27.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:90:0)
[09:54:27.954] Timer tick, asking Buildbucket for the build status
[09:54:28.405] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:28.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:91:0) after 1m0s
[09:55:28.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:91:0)
[09:55:28.470] Timer tick, asking Buildbucket for the build status
[09:55:28.916] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:28.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:92:0) after 1m0s
[09:56:28.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:92:0)
[09:56:28.939] Timer tick, asking Buildbucket for the build status
[09:56:29.160] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:29.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:93:0) after 1m0s
[09:57:29.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:93:0)
[09:57:29.214] Timer tick, asking Buildbucket for the build status
[09:57:29.705] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:29.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:94:0) after 1m0s
[09:58:29.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:94:0)
[09:58:29.766] Timer tick, asking Buildbucket for the build status
[09:58:29.995] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:29.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:95:0) after 1m0s
[09:59:30.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:95:0)
[09:59:30.045] Timer tick, asking Buildbucket for the build status
[09:59:30.183] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:30.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:96:0) after 1m0s
[10:00:30.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:96:0)
[10:00:30.222] Timer tick, asking Buildbucket for the build status
[10:00:31.000] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:31.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:97:0) after 1m0s
[10:01:31.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:97:0)
[10:01:31.077] Timer tick, asking Buildbucket for the build status
[10:01:31.193] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:31.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:98:0) after 1m0s
[10:02:31.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:98:0)
[10:02:31.266] Timer tick, asking Buildbucket for the build status
[10:02:34.085] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:34.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:99:0) after 1m0s
[10:03:34.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:99:0)
[10:03:34.296] Timer tick, asking Buildbucket for the build status
[10:03:34.466] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:34.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:100:0) after 1m0s
[10:04:34.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:100:0)
[10:04:34.593] Timer tick, asking Buildbucket for the build status
[10:04:34.745] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:34.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:101:0) after 1m0s
[10:05:34.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:101:0)
[10:05:34.766] Timer tick, asking Buildbucket for the build status
[10:05:34.987] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:34.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:102:0) after 1m0s
[10:06:35.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:102:0)
[10:06:35.011] Timer tick, asking Buildbucket for the build status
[10:06:35.156] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:35.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:103:0) after 1m0s
[10:07:35.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:103:0)
[10:07:35.252] Timer tick, asking Buildbucket for the build status
[10:07:35.435] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:35.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:104:0) after 1m0s
[10:08:35.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:104:0)
[10:08:35.532] Timer tick, asking Buildbucket for the build status
[10:08:35.671] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:35.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:105:0) after 1m0s
[10:09:35.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:105:0)
[10:09:35.752] Timer tick, asking Buildbucket for the build status
[10:09:35.904] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:35.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:106:0) after 1m0s
[10:10:35.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:106:0)
[10:10:35.993] Timer tick, asking Buildbucket for the build status
[10:10:36.133] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:36.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:107:0) after 1m0s
[10:11:36.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:107:0)
[10:11:36.208] Timer tick, asking Buildbucket for the build status
[10:11:36.348] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:36.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:108:0) after 1m0s
[10:12:36.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:108:0)
[10:12:36.369] Timer tick, asking Buildbucket for the build status
[10:12:36.588] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:36.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:109:0) after 1m0s
[10:13:36.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:109:0)
[10:13:36.580] Timer tick, asking Buildbucket for the build status
[10:13:36.709] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:36.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:110:0) after 1m0s
[10:14:36.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:110:0)
[10:14:36.779] Timer tick, asking Buildbucket for the build status
[10:14:36.999] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:36.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:111:0) after 1m0s
[10:15:36.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:111:0)
[10:15:36.984] Timer tick, asking Buildbucket for the build status
[10:15:37.095] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:37.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:112:0) after 1m0s
[10:16:37.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:112:0)
[10:16:37.124] Timer tick, asking Buildbucket for the build status
[10:16:37.250] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:37.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:113:0) after 1m0s
[10:17:37.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:113:0)
[10:17:37.277] Timer tick, asking Buildbucket for the build status
[10:17:37.399] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:37.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:114:0) after 1m0s
[10:18:37.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:114:0)
[10:18:37.464] Timer tick, asking Buildbucket for the build status
[10:18:37.612] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:37.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:115:0) after 1m0s
[10:19:37.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:115:0)
[10:19:37.599] Timer tick, asking Buildbucket for the build status
[10:19:37.763] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:37.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:116:0) after 1m0s
[10:20:37.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:116:0)
[10:20:37.770] Timer tick, asking Buildbucket for the build status
[10:20:38.154] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:38.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:117:0) after 1m0s
[10:21:38.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:117:0)
[10:21:38.280] Timer tick, asking Buildbucket for the build status
[10:21:38.583] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:38.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:118:0) after 1m0s
[10:22:38.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:118:0)
[10:22:38.725] Timer tick, asking Buildbucket for the build status
[10:22:38.950] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:38.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:119:0) after 1m0s
[10:23:39.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:119:0)
[10:23:39.042] Timer tick, asking Buildbucket for the build status
[10:23:39.150] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:39.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:120:0) after 1m0s
[10:24:39.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:120:0)
[10:24:39.174] Timer tick, asking Buildbucket for the build status
[10:24:39.345] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:39.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:121:0) after 1m0s
[10:25:39.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:121:0)
[10:25:39.337] Timer tick, asking Buildbucket for the build status
[10:25:39.791] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:39.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:122:0) after 1m0s
[10:26:39.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:122:0)
[10:26:39.830] Timer tick, asking Buildbucket for the build status
[10:26:39.974] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:39.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:123:0) after 1m0s
[10:27:40.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:123:0)
[10:27:40.000] Timer tick, asking Buildbucket for the build status
[10:27:41.347] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:41.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:124:0) after 1m0s
[10:28:41.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:124:0)
[10:28:41.442] Timer tick, asking Buildbucket for the build status
[10:28:41.695] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:41.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:125:0) after 1m0s
[10:29:41.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:125:0)
[10:29:41.724] Timer tick, asking Buildbucket for the build status
[10:29:41.855] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:41.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:126:0) after 1m0s
[10:30:41.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:126:0)
[10:30:41.961] Timer tick, asking Buildbucket for the build status
[10:30:42.202] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:42.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:127:0) after 1m0s
[10:31:42.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:127:0)
[10:31:42.258] Timer tick, asking Buildbucket for the build status
[10:31:42.478] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:42.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:128:0) after 1m0s
[10:32:42.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:128:0)
[10:32:42.553] Timer tick, asking Buildbucket for the build status
[10:32:44.277] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:44.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:129:0) after 1m0s
[10:33:44.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:129:0)
[10:33:44.308] Timer tick, asking Buildbucket for the build status
[10:33:44.427] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:44.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:130:0) after 1m0s
[10:34:44.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:130:0)
[10:34:44.493] Timer tick, asking Buildbucket for the build status
[10:34:44.831] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:44.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:131:0) after 1m0s
[10:35:44.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:131:0)
[10:35:44.866] Timer tick, asking Buildbucket for the build status
[10:35:45.003] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:45.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:132:0) after 1m0s
[10:36:45.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:132:0)
[10:36:45.048] Timer tick, asking Buildbucket for the build status
[10:36:45.207] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:45.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:133:0) after 1m0s
[10:37:45.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:133:0)
[10:37:45.291] Timer tick, asking Buildbucket for the build status
[10:37:45.492] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:45.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:134:0) after 1m0s
[10:38:45.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:134:0)
[10:38:45.557] Timer tick, asking Buildbucket for the build status
[10:38:45.652] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:45.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:135:0) after 1m0s
[10:39:45.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:135:0)
[10:39:45.802] Timer tick, asking Buildbucket for the build status
[10:39:46.131] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:46.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:136:0) after 1m0s
[10:40:46.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:136:0)
[10:40:46.248] Timer tick, asking Buildbucket for the build status
[10:40:46.380] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:46.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:137:0) after 1m0s
[10:41:46.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:137:0)
[10:41:46.426] Timer tick, asking Buildbucket for the build status
[10:41:46.514] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:46.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:138:0) after 1m0s
[10:42:46.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:138:0)
[10:42:46.595] Timer tick, asking Buildbucket for the build status
[10:42:47.688] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:47.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:139:0) after 1m0s
[10:43:47.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:139:0)
[10:43:47.753] Timer tick, asking Buildbucket for the build status
[10:43:48.058] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:48.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:140:0) after 1m0s
[10:44:48.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:140:0)
[10:44:48.133] Timer tick, asking Buildbucket for the build status
[10:44:48.413] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:48.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:141:0) after 1m0s
[10:45:48.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:141:0)
[10:45:48.465] Timer tick, asking Buildbucket for the build status
[10:45:49.022] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:49.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:142:0) after 1m0s
[10:46:49.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:142:0)
[10:46:49.059] Timer tick, asking Buildbucket for the build status
[10:46:49.351] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:49.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:143:0) after 1m0s
[10:47:49.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:143:0)
[10:47:49.366] Timer tick, asking Buildbucket for the build status
[10:47:50.562] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:50.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:144:0) after 1m0s
[10:48:50.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:144:0)
[10:48:50.601] Timer tick, asking Buildbucket for the build status
[10:48:51.104] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:51.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:145:0) after 1m0s
[10:49:51.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:145:0)
[10:49:51.125] Timer tick, asking Buildbucket for the build status
[10:49:51.276] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:51.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:146:0) after 1m0s
[10:50:51.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:146:0)
[10:50:51.312] Timer tick, asking Buildbucket for the build status
[10:50:51.782] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:51.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:147:0) after 1m0s
[10:51:51.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:147:0)
[10:51:51.795] Timer tick, asking Buildbucket for the build status
[10:51:52.402] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:52.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:148:0) after 1m0s
[10:52:52.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:148:0)
[10:52:52.562] Timer tick, asking Buildbucket for the build status
[10:52:53.111] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:53.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:149:0) after 1m0s
[10:53:53.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:149:0)
[10:53:53.152] Timer tick, asking Buildbucket for the build status
[10:53:53.359] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:53.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:150:0) after 1m0s
[10:54:53.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:150:0)
[10:54:53.475] Timer tick, asking Buildbucket for the build status
[10:54:53.694] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:53.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:151:0) after 1m0s
[10:55:53.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:151:0)
[10:55:53.779] Timer tick, asking Buildbucket for the build status
[10:55:54.108] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:54.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:152:0) after 1m0s
[10:56:54.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:152:0)
[10:56:54.290] Timer tick, asking Buildbucket for the build status
[10:56:54.627] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:54.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:153:0) after 1m0s
[10:57:54.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:153:0)
[10:57:54.750] Timer tick, asking Buildbucket for the build status
[10:57:56.757] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:56.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:154:0) after 1m0s
[10:58:57.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:154:0)
[10:58:57.549] Timer tick, asking Buildbucket for the build status
[10:58:57.825] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:57.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:155:0) after 1m0s
[10:59:57.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:155:0)
[10:59:57.969] Timer tick, asking Buildbucket for the build status
[10:59:58.111] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:58.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:156:0) after 1m0s
[11:00:58.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:156:0)
[11:00:58.161] Timer tick, asking Buildbucket for the build status
[11:00:58.493] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:58.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:157:0) after 1m0s
[11:01:58.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:157:0)
[11:01:58.500] Timer tick, asking Buildbucket for the build status
[11:01:58.695] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:58.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:158:0) after 1m0s
[11:02:58.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:158:0)
[11:02:58.781] Timer tick, asking Buildbucket for the build status
[11:03:00.147] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:00.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:159:0) after 1m0s
[11:04:00.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:159:0)
[11:04:00.143] Timer tick, asking Buildbucket for the build status
[11:04:00.325] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:00.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:160:0) after 1m0s
[11:05:00.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:160:0)
[11:05:00.396] Timer tick, asking Buildbucket for the build status
[11:05:00.528] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:00.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:161:0) after 1m0s
[11:06:01.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:161:0)
[11:06:01.145] Timer tick, asking Buildbucket for the build status
[11:06:02.291] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:02.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:162:0) after 1m0s
[11:07:02.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:162:0)
[11:07:02.350] Timer tick, asking Buildbucket for the build status
[11:07:02.575] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:02.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:163:0) after 1m0s
[11:08:02.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:163:0)
[11:08:02.655] Timer tick, asking Buildbucket for the build status
[11:08:03.314] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:03.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:164:0) after 1m0s
[11:09:03.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:164:0)
[11:09:03.717] Timer tick, asking Buildbucket for the build status
[11:09:03.830] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:03.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:165:0) after 1m0s
[11:10:03.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:165:0)
[11:10:03.917] Timer tick, asking Buildbucket for the build status
[11:10:04.709] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:04.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:166:0) after 1m0s
[11:11:04.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:166:0)
[11:11:04.742] Timer tick, asking Buildbucket for the build status
[11:11:04.875] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:04.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:167:0) after 1m0s
[11:12:04.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:167:0)
[11:12:04.937] Timer tick, asking Buildbucket for the build status
[11:12:05.040] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:05.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:168:0) after 1m0s
[11:13:05.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:168:0)
[11:13:05.127] Timer tick, asking Buildbucket for the build status
[11:13:05.349] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:05.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:169:0) after 1m0s
[11:14:05.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:169:0)
[11:14:05.389] Timer tick, asking Buildbucket for the build status
[11:14:05.692] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:05.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:170:0) after 1m0s
[11:15:05.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:170:0)
[11:15:05.814] Timer tick, asking Buildbucket for the build status
[11:15:06.114] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:06.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:171:0) after 1m0s
[11:16:06.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:171:0)
[11:16:06.258] Timer tick, asking Buildbucket for the build status
[11:16:06.423] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:06.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:172:0) after 1m0s
[11:17:06.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:172:0)
[11:17:06.603] Timer tick, asking Buildbucket for the build status
[11:17:06.749] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:06.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:173:0) after 1m0s
[11:18:07.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:173:0)
[11:18:07.192] Timer tick, asking Buildbucket for the build status
[11:18:07.629] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:07.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:174:0) after 1m0s
[11:19:07.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:174:0)
[11:19:07.802] Timer tick, asking Buildbucket for the build status
[11:19:08.018] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:08.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:175:0) after 1m0s
[11:20:08.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:175:0)
[11:20:08.123] Timer tick, asking Buildbucket for the build status
[11:20:08.272] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:08.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:176:0) after 1m0s
[11:21:08.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:176:0)
[11:21:08.642] Timer tick, asking Buildbucket for the build status
[11:21:09.115] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:09.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:177:0) after 1m0s
[11:22:09.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:177:0)
[11:22:09.202] Timer tick, asking Buildbucket for the build status
[11:22:10.555] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:10.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:178:0) after 1m0s
[11:23:10.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:178:0)
[11:23:10.794] Timer tick, asking Buildbucket for the build status
[11:23:10.958] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:10.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:179:0) after 1m0s
[11:24:11.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:179:0)
[11:24:11.144] Timer tick, asking Buildbucket for the build status
[11:24:11.271] Build 8875810244851930128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:11.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9041240307311194912:180:0) after 1m0s
[11:24:22.166] Received PubSub notification, asking Buildbucket for the build status
[11:24:22.227] Build 8875810244851930128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:24:22.227] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1593775461262995",
    "created_by": "project:infra",
    "created_ts": "1593764754397909",
    "id": "8875810244851930128",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4190.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"72f9a5f1-a020-46c6-ae60-827b5437c6ec\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4190.2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_292036ca8cd0711903e829a3c5417e821d204af08fdef2e4b6d3affbee40eeb2_v2\", \"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-45b8\"], \"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\": [\"5320-752003c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1593764782882819",
    "status": "COMPLETED",
    "status_changed_ts": "1593775461802315",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9041240307311194912",
      "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/8875810244851930128/+/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:4d2c99c1a708d710",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1593775461818363",
    "url": "https://ci.chromium.org/b/8875810244851930128",
    "utcnow_ts": "1593775462219310"
  }
}
[11:24:22.227] Invocation finished in 2h58m29.558797621s with status SUCCEEDED