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

• parent_buildername:publish_tarball
• user_agent:recipe

[01:17:51.100] New invocation is queued and will start shortly
[01:17:51.100] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[01:17:52.318] Starting the invocation (attempt 1)
[01:17:52.358] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:17:52.358] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:17:52.358] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9031301570101424224",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4240.111\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"5828b205-c541-433f-80fa-3ebcc86e5e64\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4240.111\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9031301570101424224",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[01:17:52.777] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1603243072441169",
    "id": "8865871508030208320",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4240.111\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5828b205-c541-433f-80fa-3ebcc86e5e64\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4240.111\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1603243072651129",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9031301570101424224",
      "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/8865871508030208320/+/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": "1603243072651243",
    "url": "https://ci.chromium.org/b/8865871508030208320",
    "utcnow_ts": "1603243072769609"
  }
}
[01:17:52.777] Task URL: https://ci.chromium.org/b/8865871508030208320
[01:17:52.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:2:0) after 1m0s
[01:18:14.576] Received PubSub notification, asking Buildbucket for the build status
[01:18:16.927] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:52.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:2:0)
[01:18:52.906] Timer tick, asking Buildbucket for the build status
[01:18:52.995] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:52.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:4:0) after 1m0s
[01:19:53.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:4:0)
[01:19:53.019] Timer tick, asking Buildbucket for the build status
[01:19:53.178] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:53.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:5:0) after 1m0s
[01:20:53.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:5:0)
[01:20:53.241] Timer tick, asking Buildbucket for the build status
[01:20:53.503] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:53.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:6:0) after 1m0s
[01:21:53.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:6:0)
[01:21:53.559] Timer tick, asking Buildbucket for the build status
[01:21:53.624] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:53.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:7:0) after 1m0s
[01:22:53.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:7:0)
[01:22:53.755] Timer tick, asking Buildbucket for the build status
[01:22:53.856] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:53.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:8:0) after 1m0s
[01:23:53.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:8:0)
[01:23:53.976] Timer tick, asking Buildbucket for the build status
[01:23:54.045] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:54.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:9:0) after 1m0s
[01:24:54.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:9:0)
[01:24:54.131] Timer tick, asking Buildbucket for the build status
[01:24:54.207] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:54.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:10:0) after 1m0s
[01:25:54.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:10:0)
[01:25:54.240] Timer tick, asking Buildbucket for the build status
[01:25:54.368] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:54.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:11:0) after 1m0s
[01:26:54.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:11:0)
[01:26:54.416] Timer tick, asking Buildbucket for the build status
[01:26:54.776] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:54.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:12:0) after 1m0s
[01:27:54.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:12:0)
[01:27:54.905] Timer tick, asking Buildbucket for the build status
[01:27:55.085] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:55.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:13:0) after 1m0s
[01:28:55.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:13:0)
[01:28:55.154] Timer tick, asking Buildbucket for the build status
[01:28:55.213] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:55.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:14:0) after 1m0s
[01:29:55.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:14:0)
[01:29:55.426] Timer tick, asking Buildbucket for the build status
[01:29:55.496] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:55.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:15:0) after 1m0s
[01:30:55.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:15:0)
[01:30:55.600] Timer tick, asking Buildbucket for the build status
[01:30:55.693] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:55.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:16:0) after 1m0s
[01:31:55.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:16:0)
[01:31:55.774] Timer tick, asking Buildbucket for the build status
[01:31:55.851] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:55.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:17:0) after 1m0s
[01:32:55.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:17:0)
[01:32:55.864] Timer tick, asking Buildbucket for the build status
[01:32:56.367] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:56.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:18:0) after 1m0s
[01:33:56.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:18:0)
[01:33:56.445] Timer tick, asking Buildbucket for the build status
[01:33:56.512] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:56.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:19:0) after 1m0s
[01:34:56.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:19:0)
[01:34:56.585] Timer tick, asking Buildbucket for the build status
[01:34:56.654] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:56.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:20:0) after 1m0s
[01:35:56.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:20:0)
[01:35:56.725] Timer tick, asking Buildbucket for the build status
[01:35:56.971] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:56.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:21:0) after 1m0s
[01:36:57.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:21:0)
[01:36:57.131] Timer tick, asking Buildbucket for the build status
[01:36:57.213] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:57.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:22:0) after 1m0s
[01:37:57.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:22:0)
[01:37:57.316] Timer tick, asking Buildbucket for the build status
[01:37:57.449] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:57.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:23:0) after 1m0s
[01:38:57.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:23:0)
[01:38:57.508] Timer tick, asking Buildbucket for the build status
[01:38:57.605] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:57.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:24:0) after 1m0s
[01:39:57.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:24:0)
[01:39:57.710] Timer tick, asking Buildbucket for the build status
[01:39:57.844] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:57.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:25:0) after 1m0s
[01:40:58.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:25:0)
[01:40:58.004] Timer tick, asking Buildbucket for the build status
[01:40:58.332] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:58.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:26:0) after 1m0s
[01:41:58.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:26:0)
[01:41:58.425] Timer tick, asking Buildbucket for the build status
[01:41:58.485] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:58.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:27:0) after 1m0s
[01:42:58.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:27:0)
[01:42:58.535] Timer tick, asking Buildbucket for the build status
[01:42:58.984] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:58.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:28:0) after 1m0s
[01:43:59.010] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:28:0)
[01:43:59.010] Timer tick, asking Buildbucket for the build status
[01:43:59.078] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:59.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:29:0) after 1m0s
[01:44:59.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:29:0)
[01:44:59.111] Timer tick, asking Buildbucket for the build status
[01:44:59.527] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:59.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:30:0) after 1m0s
[01:45:59.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:30:0)
[01:45:59.582] Timer tick, asking Buildbucket for the build status
[01:45:59.783] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:59.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:31:0) after 1m0s
[01:46:59.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:31:0)
[01:46:59.810] Timer tick, asking Buildbucket for the build status
[01:46:59.866] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:59.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:32:0) after 1m0s
[01:47:59.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:32:0)
[01:47:59.926] Timer tick, asking Buildbucket for the build status
[01:48:00.218] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:00.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:33:0) after 1m0s
[01:49:00.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:33:0)
[01:49:00.274] Timer tick, asking Buildbucket for the build status
[01:49:00.375] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:00.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:34:0) after 1m0s
[01:50:01.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:34:0)
[01:50:01.556] Timer tick, asking Buildbucket for the build status
[01:50:01.697] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:01.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:35:0) after 1m0s
[01:51:02.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:35:0)
[01:51:02.142] Timer tick, asking Buildbucket for the build status
[01:51:02.206] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:02.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:36:0) after 1m0s
[01:52:02.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:36:0)
[01:52:02.969] Timer tick, asking Buildbucket for the build status
[01:52:03.038] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:03.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:37:0) after 1m0s
[01:53:03.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:37:0)
[01:53:03.146] Timer tick, asking Buildbucket for the build status
[01:53:03.306] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:03.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:38:0) after 1m0s
[01:54:03.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:38:0)
[01:54:03.388] Timer tick, asking Buildbucket for the build status
[01:54:03.698] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:03.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:39:0) after 1m0s
[01:55:03.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:39:0)
[01:55:03.791] Timer tick, asking Buildbucket for the build status
[01:55:03.925] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:03.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:40:0) after 1m0s
[01:56:03.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:40:0)
[01:56:03.992] Timer tick, asking Buildbucket for the build status
[01:56:04.083] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:04.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:41:0) after 1m0s
[01:57:04.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:41:0)
[01:57:04.367] Timer tick, asking Buildbucket for the build status
[01:57:04.435] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:04.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:42:0) after 1m0s
[01:58:04.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:42:0)
[01:58:04.555] Timer tick, asking Buildbucket for the build status
[01:58:04.693] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:04.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:43:0) after 1m0s
[01:59:04.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:43:0)
[01:59:04.720] Timer tick, asking Buildbucket for the build status
[01:59:04.782] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:04.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:44:0) after 1m0s
[02:00:04.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:44:0)
[02:00:04.932] Timer tick, asking Buildbucket for the build status
[02:00:05.225] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:05.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:45:0) after 1m0s
[02:01:05.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:45:0)
[02:01:05.638] Timer tick, asking Buildbucket for the build status
[02:01:06.305] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:06.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:46:0) after 1m0s
[02:02:06.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:46:0)
[02:02:06.385] Timer tick, asking Buildbucket for the build status
[02:02:06.519] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:06.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:47:0) after 1m0s
[02:03:06.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:47:0)
[02:03:06.581] Timer tick, asking Buildbucket for the build status
[02:03:07.047] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:07.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:48:0) after 1m0s
[02:04:07.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:48:0)
[02:04:07.049] Timer tick, asking Buildbucket for the build status
[02:04:07.141] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:07.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:49:0) after 1m0s
[02:05:07.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:49:0)
[02:05:07.282] Timer tick, asking Buildbucket for the build status
[02:05:07.352] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:07.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:50:0) after 1m0s
[02:06:07.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:50:0)
[02:06:07.448] Timer tick, asking Buildbucket for the build status
[02:06:07.615] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:07.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:51:0) after 1m0s
[02:07:07.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:51:0)
[02:07:07.643] Timer tick, asking Buildbucket for the build status
[02:07:07.714] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:07.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:52:0) after 1m0s
[02:08:07.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:52:0)
[02:08:07.787] Timer tick, asking Buildbucket for the build status
[02:08:07.893] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:07.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:53:0) after 1m0s
[02:09:07.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:53:0)
[02:09:07.970] Timer tick, asking Buildbucket for the build status
[02:09:08.052] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:08.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:54:0) after 1m0s
[02:10:08.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:54:0)
[02:10:08.226] Timer tick, asking Buildbucket for the build status
[02:10:08.696] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:08.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:55:0) after 1m0s
[02:11:08.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:55:0)
[02:11:08.816] Timer tick, asking Buildbucket for the build status
[02:11:08.890] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:08.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:56:0) after 1m0s
[02:12:08.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:56:0)
[02:12:08.990] Timer tick, asking Buildbucket for the build status
[02:12:09.201] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:09.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:57:0) after 1m0s
[02:13:09.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:57:0)
[02:13:09.296] Timer tick, asking Buildbucket for the build status
[02:13:09.513] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:09.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:58:0) after 1m0s
[02:14:09.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:58:0)
[02:14:09.650] Timer tick, asking Buildbucket for the build status
[02:14:09.716] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:09.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:59:0) after 1m0s
[02:15:10.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:59:0)
[02:15:10.075] Timer tick, asking Buildbucket for the build status
[02:15:10.197] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:10.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:60:0) after 1m0s
[02:16:10.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:60:0)
[02:16:10.316] Timer tick, asking Buildbucket for the build status
[02:16:10.378] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:10.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:61:0) after 1m0s
[02:17:10.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:61:0)
[02:17:10.601] Timer tick, asking Buildbucket for the build status
[02:17:10.790] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:10.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:62:0) after 1m0s
[02:18:11.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:62:0)
[02:18:11.491] Timer tick, asking Buildbucket for the build status
[02:18:11.601] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:11.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:63:0) after 1m0s
[02:19:11.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:63:0)
[02:19:11.932] Timer tick, asking Buildbucket for the build status
[02:19:11.997] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:11.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:64:0) after 1m0s
[02:20:12.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:64:0)
[02:20:12.134] Timer tick, asking Buildbucket for the build status
[02:20:12.221] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:12.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:65:0) after 1m0s
[02:21:12.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:65:0)
[02:21:12.439] Timer tick, asking Buildbucket for the build status
[02:21:12.516] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:12.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:66:0) after 1m0s
[02:22:12.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:66:0)
[02:22:12.730] Timer tick, asking Buildbucket for the build status
[02:22:12.786] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:12.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:67:0) after 1m0s
[02:23:12.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:67:0)
[02:23:12.935] Timer tick, asking Buildbucket for the build status
[02:23:13.061] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:13.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:68:0) after 1m0s
[02:24:13.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:68:0)
[02:24:13.215] Timer tick, asking Buildbucket for the build status
[02:24:13.382] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:13.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:69:0) after 1m0s
[02:25:13.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:69:0)
[02:25:13.433] Timer tick, asking Buildbucket for the build status
[02:25:13.545] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:13.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:70:0) after 1m0s
[02:26:13.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:70:0)
[02:26:13.619] Timer tick, asking Buildbucket for the build status
[02:26:13.712] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:13.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:71:0) after 1m0s
[02:27:13.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:71:0)
[02:27:13.801] Timer tick, asking Buildbucket for the build status
[02:27:13.867] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:13.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:72:0) after 1m0s
[02:28:13.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:72:0)
[02:28:13.964] Timer tick, asking Buildbucket for the build status
[02:28:14.191] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:14.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:73:0) after 1m0s
[02:29:14.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:73:0)
[02:29:14.365] Timer tick, asking Buildbucket for the build status
[02:29:14.423] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:14.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:74:0) after 1m0s
[02:30:14.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:74:0)
[02:30:14.512] Timer tick, asking Buildbucket for the build status
[02:30:14.695] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:14.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:75:0) after 1m0s
[02:31:14.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:75:0)
[02:31:14.720] Timer tick, asking Buildbucket for the build status
[02:31:14.787] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:14.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:76:0) after 1m0s
[02:32:15.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:76:0)
[02:32:15.053] Timer tick, asking Buildbucket for the build status
[02:32:15.187] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:15.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:77:0) after 1m0s
[02:33:15.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:77:0)
[02:33:15.211] Timer tick, asking Buildbucket for the build status
[02:33:15.396] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:15.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:78:0) after 1m0s
[02:34:15.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:78:0)
[02:34:15.583] Timer tick, asking Buildbucket for the build status
[02:34:15.653] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:15.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:79:0) after 1m0s
[02:35:15.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:79:0)
[02:35:15.826] Timer tick, asking Buildbucket for the build status
[02:35:15.896] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:15.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:80:0) after 1m0s
[02:36:15.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:80:0)
[02:36:15.995] Timer tick, asking Buildbucket for the build status
[02:36:16.064] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:16.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:81:0) after 1m0s
[02:37:16.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:81:0)
[02:37:16.227] Timer tick, asking Buildbucket for the build status
[02:37:16.306] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:16.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:82:0) after 1m0s
[02:38:16.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:82:0)
[02:38:16.400] Timer tick, asking Buildbucket for the build status
[02:38:16.503] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:16.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:83:0) after 1m0s
[02:39:16.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:83:0)
[02:39:16.544] Timer tick, asking Buildbucket for the build status
[02:39:16.634] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:16.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:84:0) after 1m0s
[02:40:16.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:84:0)
[02:40:16.830] Timer tick, asking Buildbucket for the build status
[02:40:16.890] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:16.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:85:0) after 1m0s
[02:41:17.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:85:0)
[02:41:17.007] Timer tick, asking Buildbucket for the build status
[02:41:17.082] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:17.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:86:0) after 1m0s
[02:42:17.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:86:0)
[02:42:17.146] Timer tick, asking Buildbucket for the build status
[02:42:17.212] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:17.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:87:0) after 1m0s
[02:43:17.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:87:0)
[02:43:17.271] Timer tick, asking Buildbucket for the build status
[02:43:17.470] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:17.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:88:0) after 1m0s
[02:44:17.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:88:0)
[02:44:17.597] Timer tick, asking Buildbucket for the build status
[02:44:17.696] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:17.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:89:0) after 1m0s
[02:45:17.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:89:0)
[02:45:17.762] Timer tick, asking Buildbucket for the build status
[02:45:17.898] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:17.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:90:0) after 1m0s
[02:46:17.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:90:0)
[02:46:17.991] Timer tick, asking Buildbucket for the build status
[02:46:18.059] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:18.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:91:0) after 1m0s
[02:47:18.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:91:0)
[02:47:18.061] Timer tick, asking Buildbucket for the build status
[02:47:18.141] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:18.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:92:0) after 1m0s
[02:48:18.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:92:0)
[02:48:18.188] Timer tick, asking Buildbucket for the build status
[02:48:18.298] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:18.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:93:0) after 1m0s
[02:49:18.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:93:0)
[02:49:18.421] Timer tick, asking Buildbucket for the build status
[02:49:18.483] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:18.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:94:0) after 1m0s
[02:50:18.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:94:0)
[02:50:18.660] Timer tick, asking Buildbucket for the build status
[02:50:18.733] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:18.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:95:0) after 1m0s
[02:51:18.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:95:0)
[02:51:18.853] Timer tick, asking Buildbucket for the build status
[02:51:18.920] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:18.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:96:0) after 1m0s
[02:52:19.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:96:0)
[02:52:19.144] Timer tick, asking Buildbucket for the build status
[02:52:19.340] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:19.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:97:0) after 1m0s
[02:53:19.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:97:0)
[02:53:19.489] Timer tick, asking Buildbucket for the build status
[02:53:19.633] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:19.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:98:0) after 1m0s
[02:54:19.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:98:0)
[02:54:19.841] Timer tick, asking Buildbucket for the build status
[02:54:19.905] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:19.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:99:0) after 1m0s
[02:55:20.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:99:0)
[02:55:20.036] Timer tick, asking Buildbucket for the build status
[02:55:20.103] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:20.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:100:0) after 1m0s
[02:56:20.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:100:0)
[02:56:20.233] Timer tick, asking Buildbucket for the build status
[02:56:20.292] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:20.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:101:0) after 1m0s
[02:57:20.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:101:0)
[02:57:20.277] Timer tick, asking Buildbucket for the build status
[02:57:20.372] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:20.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:102:0) after 1m0s
[02:58:20.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:102:0)
[02:58:20.388] Timer tick, asking Buildbucket for the build status
[02:58:20.523] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:20.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:103:0) after 1m0s
[02:59:20.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:103:0)
[02:59:20.610] Timer tick, asking Buildbucket for the build status
[02:59:20.725] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:20.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:104:0) after 1m0s
[03:00:20.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:104:0)
[03:00:20.805] Timer tick, asking Buildbucket for the build status
[03:00:20.986] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:20.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:105:0) after 1m0s
[03:01:21.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:105:0)
[03:01:21.063] Timer tick, asking Buildbucket for the build status
[03:01:21.152] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:21.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:106:0) after 1m0s
[03:02:21.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:106:0)
[03:02:21.249] Timer tick, asking Buildbucket for the build status
[03:02:21.503] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:21.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:107:0) after 1m0s
[03:03:21.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:107:0)
[03:03:21.566] Timer tick, asking Buildbucket for the build status
[03:03:22.229] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:22.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:108:0) after 1m0s
[03:04:22.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:108:0)
[03:04:22.259] Timer tick, asking Buildbucket for the build status
[03:04:22.323] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:22.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:109:0) after 1m0s
[03:05:22.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:109:0)
[03:05:22.353] Timer tick, asking Buildbucket for the build status
[03:05:22.426] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:22.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:110:0) after 1m0s
[03:06:22.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:110:0)
[03:06:22.572] Timer tick, asking Buildbucket for the build status
[03:06:22.778] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:22.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:111:0) after 1m0s
[03:07:22.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:111:0)
[03:07:22.846] Timer tick, asking Buildbucket for the build status
[03:07:23.692] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:23.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:112:0) after 1m0s
[03:08:23.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:112:0)
[03:08:23.718] Timer tick, asking Buildbucket for the build status
[03:08:24.138] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:24.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:113:0) after 1m0s
[03:09:24.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:113:0)
[03:09:24.224] Timer tick, asking Buildbucket for the build status
[03:09:24.297] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:24.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:114:0) after 1m0s
[03:10:24.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:114:0)
[03:10:24.327] Timer tick, asking Buildbucket for the build status
[03:10:24.727] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:24.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:115:0) after 1m0s
[03:11:24.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:115:0)
[03:11:24.784] Timer tick, asking Buildbucket for the build status
[03:11:25.066] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:25.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:116:0) after 1m0s
[03:12:25.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:116:0)
[03:12:25.094] Timer tick, asking Buildbucket for the build status
[03:12:25.163] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:25.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:117:0) after 1m0s
[03:13:25.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:117:0)
[03:13:25.203] Timer tick, asking Buildbucket for the build status
[03:13:25.359] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:25.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:118:0) after 1m0s
[03:14:25.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:118:0)
[03:14:25.382] Timer tick, asking Buildbucket for the build status
[03:14:25.751] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:25.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:119:0) after 1m0s
[03:15:25.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:119:0)
[03:15:25.850] Timer tick, asking Buildbucket for the build status
[03:15:25.923] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:25.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:120:0) after 1m0s
[03:16:26.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:120:0)
[03:16:26.009] Timer tick, asking Buildbucket for the build status
[03:16:26.311] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:26.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:121:0) after 1m0s
[03:17:26.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:121:0)
[03:17:26.421] Timer tick, asking Buildbucket for the build status
[03:17:26.622] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:26.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:122:0) after 1m0s
[03:18:26.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:122:0)
[03:18:26.752] Timer tick, asking Buildbucket for the build status
[03:18:26.854] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:26.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:123:0) after 1m0s
[03:19:26.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:123:0)
[03:19:26.942] Timer tick, asking Buildbucket for the build status
[03:19:27.008] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:27.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:124:0) after 1m0s
[03:20:27.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:124:0)
[03:20:27.225] Timer tick, asking Buildbucket for the build status
[03:20:27.379] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:27.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:125:0) after 1m0s
[03:21:27.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:125:0)
[03:21:27.512] Timer tick, asking Buildbucket for the build status
[03:21:27.684] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:27.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:126:0) after 1m0s
[03:22:27.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:126:0)
[03:22:27.810] Timer tick, asking Buildbucket for the build status
[03:22:27.881] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:27.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:127:0) after 1m0s
[03:23:27.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:127:0)
[03:23:27.950] Timer tick, asking Buildbucket for the build status
[03:23:28.093] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:28.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:128:0) after 1m0s
[03:24:28.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:128:0)
[03:24:28.312] Timer tick, asking Buildbucket for the build status
[03:24:28.382] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:28.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:129:0) after 1m0s
[03:25:28.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:129:0)
[03:25:28.489] Timer tick, asking Buildbucket for the build status
[03:25:28.551] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:28.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:130:0) after 1m0s
[03:26:28.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:130:0)
[03:26:28.646] Timer tick, asking Buildbucket for the build status
[03:26:28.704] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:28.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:131:0) after 1m0s
[03:27:28.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:131:0)
[03:27:28.745] Timer tick, asking Buildbucket for the build status
[03:27:28.841] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:28.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:132:0) after 1m0s
[03:28:28.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:132:0)
[03:28:28.901] Timer tick, asking Buildbucket for the build status
[03:28:29.284] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:29.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:133:0) after 1m0s
[03:29:29.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:133:0)
[03:29:29.313] Timer tick, asking Buildbucket for the build status
[03:29:29.507] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:29.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:134:0) after 1m0s
[03:30:29.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:134:0)
[03:30:29.498] Timer tick, asking Buildbucket for the build status
[03:30:29.560] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:29.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:135:0) after 1m0s
[03:31:29.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:135:0)
[03:31:29.615] Timer tick, asking Buildbucket for the build status
[03:31:29.676] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:29.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:136:0) after 1m0s
[03:32:29.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:136:0)
[03:32:29.697] Timer tick, asking Buildbucket for the build status
[03:32:29.763] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:29.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:137:0) after 1m0s
[03:33:29.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:137:0)
[03:33:29.797] Timer tick, asking Buildbucket for the build status
[03:33:30.293] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:30.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:138:0) after 1m0s
[03:34:30.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:138:0)
[03:34:30.321] Timer tick, asking Buildbucket for the build status
[03:34:30.391] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:30.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:139:0) after 1m0s
[03:35:30.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:139:0)
[03:35:30.430] Timer tick, asking Buildbucket for the build status
[03:35:30.506] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:30.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:140:0) after 1m0s
[03:36:30.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:140:0)
[03:36:30.565] Timer tick, asking Buildbucket for the build status
[03:36:30.631] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:30.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:141:0) after 1m0s
[03:37:30.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:141:0)
[03:37:30.667] Timer tick, asking Buildbucket for the build status
[03:37:30.770] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:30.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:142:0) after 1m0s
[03:38:30.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:142:0)
[03:38:30.859] Timer tick, asking Buildbucket for the build status
[03:38:30.979] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:30.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:143:0) after 1m0s
[03:39:31.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:143:0)
[03:39:31.012] Timer tick, asking Buildbucket for the build status
[03:39:31.394] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:31.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:144:0) after 1m0s
[03:40:31.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:144:0)
[03:40:31.419] Timer tick, asking Buildbucket for the build status
[03:40:31.474] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:31.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:145:0) after 1m0s
[03:41:31.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:145:0)
[03:41:31.492] Timer tick, asking Buildbucket for the build status
[03:41:31.549] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:31.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:146:0) after 1m0s
[03:42:31.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:146:0)
[03:42:31.612] Timer tick, asking Buildbucket for the build status
[03:42:31.674] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:31.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:147:0) after 1m0s
[03:43:31.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:147:0)
[03:43:31.712] Timer tick, asking Buildbucket for the build status
[03:43:32.310] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:32.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:148:0) after 1m0s
[03:44:32.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:148:0)
[03:44:32.347] Timer tick, asking Buildbucket for the build status
[03:44:32.529] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:32.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:149:0) after 1m0s
[03:45:32.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:149:0)
[03:45:32.594] Timer tick, asking Buildbucket for the build status
[03:45:32.667] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:32.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:150:0) after 1m0s
[03:46:32.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:150:0)
[03:46:32.728] Timer tick, asking Buildbucket for the build status
[03:46:32.805] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:32.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:151:0) after 1m0s
[03:47:32.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:151:0)
[03:47:32.831] Timer tick, asking Buildbucket for the build status
[03:47:32.903] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:32.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:152:0) after 1m0s
[03:48:32.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:152:0)
[03:48:32.965] Timer tick, asking Buildbucket for the build status
[03:48:33.082] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:33.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:153:0) after 1m0s
[03:49:33.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:153:0)
[03:49:33.204] Timer tick, asking Buildbucket for the build status
[03:49:33.272] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:33.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:154:0) after 1m0s
[03:50:33.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:154:0)
[03:50:33.344] Timer tick, asking Buildbucket for the build status
[03:50:33.405] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:33.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:155:0) after 1m0s
[03:51:33.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:155:0)
[03:51:33.466] Timer tick, asking Buildbucket for the build status
[03:51:33.530] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:33.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:156:0) after 1m0s
[03:52:33.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:156:0)
[03:52:33.563] Timer tick, asking Buildbucket for the build status
[03:52:33.635] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:33.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:157:0) after 1m0s
[03:53:33.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:157:0)
[03:53:33.733] Timer tick, asking Buildbucket for the build status
[03:53:34.206] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:34.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:158:0) after 1m0s
[03:54:34.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:158:0)
[03:54:34.342] Timer tick, asking Buildbucket for the build status
[03:54:34.410] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:34.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:159:0) after 1m0s
[03:55:34.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:159:0)
[03:55:34.424] Timer tick, asking Buildbucket for the build status
[03:55:34.483] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:34.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:160:0) after 1m0s
[03:56:34.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:160:0)
[03:56:34.512] Timer tick, asking Buildbucket for the build status
[03:56:34.815] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:34.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:161:0) after 1m0s
[03:57:34.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:161:0)
[03:57:34.881] Timer tick, asking Buildbucket for the build status
[03:57:34.951] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:34.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:162:0) after 1m0s
[03:58:35.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:162:0)
[03:58:35.031] Timer tick, asking Buildbucket for the build status
[03:58:35.353] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:35.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:163:0) after 1m0s
[03:59:35.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:163:0)
[03:59:35.423] Timer tick, asking Buildbucket for the build status
[03:59:35.486] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:35.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:164:0) after 1m0s
[04:00:35.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:164:0)
[04:00:35.618] Timer tick, asking Buildbucket for the build status
[04:00:35.951] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:35.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:165:0) after 1m0s
[04:01:36.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:165:0)
[04:01:36.215] Timer tick, asking Buildbucket for the build status
[04:01:36.301] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:36.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:166:0) after 1m0s
[04:02:36.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:166:0)
[04:02:36.472] Timer tick, asking Buildbucket for the build status
[04:02:36.538] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:36.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:167:0) after 1m0s
[04:03:36.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:167:0)
[04:03:36.713] Timer tick, asking Buildbucket for the build status
[04:03:36.836] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:36.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:168:0) after 1m0s
[04:04:36.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:168:0)
[04:04:36.869] Timer tick, asking Buildbucket for the build status
[04:04:36.932] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:36.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:169:0) after 1m0s
[04:05:36.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:169:0)
[04:05:36.958] Timer tick, asking Buildbucket for the build status
[04:05:37.030] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:37.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:170:0) after 1m0s
[04:06:37.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:170:0)
[04:06:37.140] Timer tick, asking Buildbucket for the build status
[04:06:37.192] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:37.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:171:0) after 1m0s
[04:07:37.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:171:0)
[04:07:37.225] Timer tick, asking Buildbucket for the build status
[04:07:37.593] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:37.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:172:0) after 1m0s
[04:08:37.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:172:0)
[04:08:37.624] Timer tick, asking Buildbucket for the build status
[04:08:37.725] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:37.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:173:0) after 1m0s
[04:09:37.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:173:0)
[04:09:37.811] Timer tick, asking Buildbucket for the build status
[04:09:37.873] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:37.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:174:0) after 1m0s
[04:10:37.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:174:0)
[04:10:37.890] Timer tick, asking Buildbucket for the build status
[04:10:38.074] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:38.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:175:0) after 1m0s
[04:11:38.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:175:0)
[04:11:38.122] Timer tick, asking Buildbucket for the build status
[04:11:38.202] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:38.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:176:0) after 1m0s
[04:12:38.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:176:0)
[04:12:38.314] Timer tick, asking Buildbucket for the build status
[04:12:38.384] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:38.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:177:0) after 1m0s
[04:13:38.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:177:0)
[04:13:38.487] Timer tick, asking Buildbucket for the build status
[04:13:38.591] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:38.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:178:0) after 1m0s
[04:14:38.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:178:0)
[04:14:38.744] Timer tick, asking Buildbucket for the build status
[04:14:38.807] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:38.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:179:0) after 1m0s
[04:15:38.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:179:0)
[04:15:38.905] Timer tick, asking Buildbucket for the build status
[04:15:39.127] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:39.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:180:0) after 1m0s
[04:16:39.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:180:0)
[04:16:39.119] Timer tick, asking Buildbucket for the build status
[04:16:39.178] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:39.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:181:0) after 1m0s
[04:17:39.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:181:0)
[04:17:39.231] Timer tick, asking Buildbucket for the build status
[04:17:39.289] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:39.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:182:0) after 1m0s
[04:18:39.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:182:0)
[04:18:39.351] Timer tick, asking Buildbucket for the build status
[04:18:39.562] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:39.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:183:0) after 1m0s
[04:19:39.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:183:0)
[04:19:39.686] Timer tick, asking Buildbucket for the build status
[04:19:39.921] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:39.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:184:0) after 1m0s
[04:20:39.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:184:0)
[04:20:39.987] Timer tick, asking Buildbucket for the build status
[04:20:40.206] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:40.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:185:0) after 1m0s
[04:21:40.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:185:0)
[04:21:40.225] Timer tick, asking Buildbucket for the build status
[04:21:40.307] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:40.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:186:0) after 1m0s
[04:22:40.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:186:0)
[04:22:40.376] Timer tick, asking Buildbucket for the build status
[04:22:40.434] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:40.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:187:0) after 1m0s
[04:23:40.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:187:0)
[04:23:40.432] Timer tick, asking Buildbucket for the build status
[04:23:40.528] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:40.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:188:0) after 1m0s
[04:24:40.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:188:0)
[04:24:40.609] Timer tick, asking Buildbucket for the build status
[04:24:40.714] Build 8865871508030208320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:40.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031301570101424224:189:0) after 1m0s
[04:24:43.457] Received PubSub notification, asking Buildbucket for the build status
[04:24:43.795] Build 8865871508030208320: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:24:43.795] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1603254282706290",
    "created_by": "project:infra",
    "created_ts": "1603243072441169",
    "id": "8865871508030208320",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4240.111\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5828b205-c541-433f-80fa-3ebcc86e5e64\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4240.111\"}}",
    "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-b92w\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"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\": [\"5601-97d7e7d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1603243094010594",
    "status": "COMPLETED",
    "status_changed_ts": "1603254283061990",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9031301570101424224",
      "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/8865871508030208320/+/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:4f618d65e355bf10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1603254283075104",
    "url": "https://ci.chromium.org/b/8865871508030208320",
    "utcnow_ts": "1603254283769089"
  }
}
[04:24:43.795] Invocation finished in 3h6m52.713200163s with status SUCCEEDED