LUCI Scheduler :: infra :: Build From Tarball

Status: FAILED

Triggered by: chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com

Duration: 3 hours

Actions:

Definition (rev d0acf8648771c75c4d75c08daf9c0088a362343f)

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

Incoming triggers

publish_tarball/None (7 years ago by chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com)

Incoming properties

{
  "version": "70.0.3538.41"
}

Incoming tags

parent_buildername:publish_tarball
user_agent:recipe

Debug log (UTC)

[19:38:32.849] New invocation is queued and will start shortly
[19:38:32.849] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[19:38:34.029] Starting the invocation (attempt 1)
[19:38:34.062] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:38:34.062] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:38:34.062] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9099089448140496432",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"70.0.3538.41\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9099089448140496432",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[19:38:35.287] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1538595514188133",
    "id": "8933659386073200272",
    "lease_expiration_ts": "1538714315108378",
    "lease_key": "1015315399",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"70.0.3538.41\"}}",
    "project": "infra",
    "result_details_json": "null",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1538595515109098",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9099089448140496432",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_dimension:builder:Build From Tarball",
      "swarming_dimension:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.infra.cron",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:buildbucket_bucket:luci.infra.cron",
      "swarming_tag:buildbucket_build_id:8933659386073200272",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
      "swarming_tag:builder:Build From Tarball",
      "swarming_tag:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8933659386073200272/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:parent_buildername:publish_tarball",
      "swarming_tag:pool:luci.infra.cron",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_tag:scheduler_invocation_id:9099089448140496432",
      "swarming_tag:scheduler_job_id:infra/Build From Tarball",
      "swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d431f79a1f9890266786724a5b445a445f332a3a",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:405441f199ba7410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1538595515109177",
    "url": "https://ci.chromium.org/p/infra/builds/b8933659386073200272",
    "utcnow_ts": "1538595515277302"
  }
}
[19:38:35.287] Task URL: https://ci.chromium.org/p/infra/builds/b8933659386073200272
[19:38:35.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:2:0) after 1m0s
[19:38:48.262] Received PubSub notification, asking Buildbucket for the build status
[19:38:48.310] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:35.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:2:0)
[19:39:35.359] Timer tick, asking Buildbucket for the build status
[19:39:35.395] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:35.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:4:0) after 1m0s
[19:40:35.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:4:0)
[19:40:35.447] Timer tick, asking Buildbucket for the build status
[19:40:35.485] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:35.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:5:0) after 1m0s
[19:41:35.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:5:0)
[19:41:35.511] Timer tick, asking Buildbucket for the build status
[19:41:35.587] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:35.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:6:0) after 1m0s
[19:42:35.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:6:0)
[19:42:35.611] Timer tick, asking Buildbucket for the build status
[19:42:35.647] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:35.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:7:0) after 1m0s
[19:43:35.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:7:0)
[19:43:35.669] Timer tick, asking Buildbucket for the build status
[19:43:35.747] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:35.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:8:0) after 1m0s
[19:44:35.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:8:0)
[19:44:35.766] Timer tick, asking Buildbucket for the build status
[19:44:35.818] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:35.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:9:0) after 1m0s
[19:45:35.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:9:0)
[19:45:35.842] Timer tick, asking Buildbucket for the build status
[19:45:35.891] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:35.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:10:0) after 1m0s
[19:46:35.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:10:0)
[19:46:35.914] Timer tick, asking Buildbucket for the build status
[19:46:35.951] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:35.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:11:0) after 1m0s
[19:47:35.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:11:0)
[19:47:35.969] Timer tick, asking Buildbucket for the build status
[19:47:36.120] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:36.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:12:0) after 1m0s
[19:48:36.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:12:0)
[19:48:36.280] Timer tick, asking Buildbucket for the build status
[19:48:36.377] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:36.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:13:0) after 1m0s
[19:49:36.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:13:0)
[19:49:36.405] Timer tick, asking Buildbucket for the build status
[19:49:36.464] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:36.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:14:0) after 1m0s
[19:50:36.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:14:0)
[19:50:36.480] Timer tick, asking Buildbucket for the build status
[19:50:36.517] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:36.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:15:0) after 1m0s
[19:51:36.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:15:0)
[19:51:36.583] Timer tick, asking Buildbucket for the build status
[19:51:36.672] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:36.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:16:0) after 1m0s
[19:52:36.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:16:0)
[19:52:36.711] Timer tick, asking Buildbucket for the build status
[19:52:36.747] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:36.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:17:0) after 1m0s
[19:53:36.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:17:0)
[19:53:36.772] Timer tick, asking Buildbucket for the build status
[19:53:36.853] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:36.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:18:0) after 1m0s
[19:54:36.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:18:0)
[19:54:36.883] Timer tick, asking Buildbucket for the build status
[19:54:36.924] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:36.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:19:0) after 1m0s
[19:55:36.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:19:0)
[19:55:36.976] Timer tick, asking Buildbucket for the build status
[19:55:37.071] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:37.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:20:0) after 1m0s
[19:56:37.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:20:0)
[19:56:37.110] Timer tick, asking Buildbucket for the build status
[19:56:37.174] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:37.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:21:0) after 1m0s
[19:57:37.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:21:0)
[19:57:37.192] Timer tick, asking Buildbucket for the build status
[19:57:37.223] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:37.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:22:0) after 1m0s
[19:58:37.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:22:0)
[19:58:37.243] Timer tick, asking Buildbucket for the build status
[19:58:37.358] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:37.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:23:0) after 1m0s
[19:59:37.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:23:0)
[19:59:37.374] Timer tick, asking Buildbucket for the build status
[19:59:37.427] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:37.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:24:0) after 1m0s
[20:00:37.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:24:0)
[20:00:37.445] Timer tick, asking Buildbucket for the build status
[20:00:37.484] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:37.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:25:0) after 1m0s
[20:01:37.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:25:0)
[20:01:37.515] Timer tick, asking Buildbucket for the build status
[20:01:37.591] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:37.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:26:0) after 1m0s
[20:02:37.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:26:0)
[20:02:37.631] Timer tick, asking Buildbucket for the build status
[20:02:37.689] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:37.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:27:0) after 1m0s
[20:03:37.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:27:0)
[20:03:37.723] Timer tick, asking Buildbucket for the build status
[20:03:37.786] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:37.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:28:0) after 1m0s
[20:04:37.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:28:0)
[20:04:37.802] Timer tick, asking Buildbucket for the build status
[20:04:37.843] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:37.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:29:0) after 1m0s
[20:05:37.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:29:0)
[20:05:37.865] Timer tick, asking Buildbucket for the build status
[20:05:37.932] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:37.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:30:0) after 1m0s
[20:06:37.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:30:0)
[20:06:37.962] Timer tick, asking Buildbucket for the build status
[20:06:38.018] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:38.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:31:0) after 1m0s
[20:07:38.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:31:0)
[20:07:38.045] Timer tick, asking Buildbucket for the build status
[20:07:38.123] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:38.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:32:0) after 1m0s
[20:08:38.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:32:0)
[20:08:38.141] Timer tick, asking Buildbucket for the build status
[20:08:38.192] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:38.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:33:0) after 1m0s
[20:09:38.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:33:0)
[20:09:38.220] Timer tick, asking Buildbucket for the build status
[20:09:38.280] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:38.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:34:0) after 1m0s
[20:10:38.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:34:0)
[20:10:38.347] Timer tick, asking Buildbucket for the build status
[20:10:38.415] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:38.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:35:0) after 1m0s
[20:11:38.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:35:0)
[20:11:38.436] Timer tick, asking Buildbucket for the build status
[20:11:38.522] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:38.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:36:0) after 1m0s
[20:12:38.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:36:0)
[20:12:38.547] Timer tick, asking Buildbucket for the build status
[20:12:38.670] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:38.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:37:0) after 1m0s
[20:13:38.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:37:0)
[20:13:38.800] Timer tick, asking Buildbucket for the build status
[20:13:38.868] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:38.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:38:0) after 1m0s
[20:14:38.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:38:0)
[20:14:38.884] Timer tick, asking Buildbucket for the build status
[20:14:38.927] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:38.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:39:0) after 1m0s
[20:15:38.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:39:0)
[20:15:38.964] Timer tick, asking Buildbucket for the build status
[20:15:39.017] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:39.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:40:0) after 1m0s
[20:16:39.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:40:0)
[20:16:39.038] Timer tick, asking Buildbucket for the build status
[20:16:39.110] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:39.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:41:0) after 1m0s
[20:17:39.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:41:0)
[20:17:39.135] Timer tick, asking Buildbucket for the build status
[20:17:39.215] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:39.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:42:0) after 1m0s
[20:18:39.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:42:0)
[20:18:39.233] Timer tick, asking Buildbucket for the build status
[20:18:39.314] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:39.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:43:0) after 1m0s
[20:19:39.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:43:0)
[20:19:39.810] Timer tick, asking Buildbucket for the build status
[20:19:39.859] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:39.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:44:0) after 1m0s
[20:20:40.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:44:0)
[20:20:40.676] Timer tick, asking Buildbucket for the build status
[20:20:40.734] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:40.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:45:0) after 1m0s
[20:21:40.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:45:0)
[20:21:40.776] Timer tick, asking Buildbucket for the build status
[20:21:41.056] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:41.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:46:0) after 1m0s
[20:22:41.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:46:0)
[20:22:41.676] Timer tick, asking Buildbucket for the build status
[20:22:41.712] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:41.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:47:0) after 1m0s
[20:23:42.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:47:0)
[20:23:42.390] Timer tick, asking Buildbucket for the build status
[20:23:42.419] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:42.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:48:0) after 1m0s
[20:24:42.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:48:0)
[20:24:42.694] Timer tick, asking Buildbucket for the build status
[20:24:42.760] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:42.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:49:0) after 1m0s
[20:25:43.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:49:0)
[20:25:43.658] Timer tick, asking Buildbucket for the build status
[20:25:43.702] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:43.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:50:0) after 1m0s
[20:26:44.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:50:0)
[20:26:44.019] Timer tick, asking Buildbucket for the build status
[20:26:44.146] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:44.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:51:0) after 1m0s
[20:27:45.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:51:0)
[20:27:45.246] Timer tick, asking Buildbucket for the build status
[20:27:45.275] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:45.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:52:0) after 1m0s
[20:28:45.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:52:0)
[20:28:45.310] Timer tick, asking Buildbucket for the build status
[20:28:45.353] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:45.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:53:0) after 1m0s
[20:29:45.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:53:0)
[20:29:45.375] Timer tick, asking Buildbucket for the build status
[20:29:45.436] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:45.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:54:0) after 1m0s
[20:30:45.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:54:0)
[20:30:45.472] Timer tick, asking Buildbucket for the build status
[20:30:45.554] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:45.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:55:0) after 1m0s
[20:31:45.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:55:0)
[20:31:45.578] Timer tick, asking Buildbucket for the build status
[20:31:45.647] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:45.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:56:0) after 1m0s
[20:32:45.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:56:0)
[20:32:45.676] Timer tick, asking Buildbucket for the build status
[20:32:45.780] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:45.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:57:0) after 1m0s
[20:33:45.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:57:0)
[20:33:45.807] Timer tick, asking Buildbucket for the build status
[20:33:45.907] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:45.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:58:0) after 1m0s
[20:34:45.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:58:0)
[20:34:45.927] Timer tick, asking Buildbucket for the build status
[20:34:45.970] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:45.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:59:0) after 1m0s
[20:35:45.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:59:0)
[20:35:45.993] Timer tick, asking Buildbucket for the build status
[20:35:46.286] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:46.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:60:0) after 1m0s
[20:36:46.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:60:0)
[20:36:46.322] Timer tick, asking Buildbucket for the build status
[20:36:46.449] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:46.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:61:0) after 1m0s
[20:37:46.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:61:0)
[20:37:46.467] Timer tick, asking Buildbucket for the build status
[20:37:46.534] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:46.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:62:0) after 1m0s
[20:38:46.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:62:0)
[20:38:46.563] Timer tick, asking Buildbucket for the build status
[20:38:46.842] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:46.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:63:0) after 1m0s
[20:39:46.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:63:0)
[20:39:46.881] Timer tick, asking Buildbucket for the build status
[20:39:46.934] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:46.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:64:0) after 1m0s
[20:40:46.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:64:0)
[20:40:46.979] Timer tick, asking Buildbucket for the build status
[20:40:47.159] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:47.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:65:0) after 1m0s
[20:41:47.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:65:0)
[20:41:47.195] Timer tick, asking Buildbucket for the build status
[20:41:47.316] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:47.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:66:0) after 1m0s
[20:42:47.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:66:0)
[20:42:47.335] Timer tick, asking Buildbucket for the build status
[20:42:47.377] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:47.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:67:0) after 1m0s
[20:43:47.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:67:0)
[20:43:47.404] Timer tick, asking Buildbucket for the build status
[20:43:47.615] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:47.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:68:0) after 1m0s
[20:44:47.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:68:0)
[20:44:47.641] Timer tick, asking Buildbucket for the build status
[20:44:47.708] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:47.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:69:0) after 1m0s
[20:45:47.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:69:0)
[20:45:47.732] Timer tick, asking Buildbucket for the build status
[20:45:48.110] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:48.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:70:0) after 1m0s
[20:46:48.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:70:0)
[20:46:48.193] Timer tick, asking Buildbucket for the build status
[20:46:48.246] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:48.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:71:0) after 1m0s
[20:47:48.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:71:0)
[20:47:48.266] Timer tick, asking Buildbucket for the build status
[20:47:48.299] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:48.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:72:0) after 1m0s
[20:48:48.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:72:0)
[20:48:48.322] Timer tick, asking Buildbucket for the build status
[20:48:48.379] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:48.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:73:0) after 1m0s
[20:49:48.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:73:0)
[20:49:48.411] Timer tick, asking Buildbucket for the build status
[20:49:48.556] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:48.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:74:0) after 1m0s
[20:50:48.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:74:0)
[20:50:48.636] Timer tick, asking Buildbucket for the build status
[20:50:48.676] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:48.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:75:0) after 1m0s
[20:51:48.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:75:0)
[20:51:48.716] Timer tick, asking Buildbucket for the build status
[20:51:48.784] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:48.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:76:0) after 1m0s
[20:52:48.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:76:0)
[20:52:48.814] Timer tick, asking Buildbucket for the build status
[20:52:48.855] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:48.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:77:0) after 1m0s
[20:53:48.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:77:0)
[20:53:48.895] Timer tick, asking Buildbucket for the build status
[20:53:49.011] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:49.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:78:0) after 1m0s
[20:54:49.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:78:0)
[20:54:49.033] Timer tick, asking Buildbucket for the build status
[20:54:49.088] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:49.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:79:0) after 1m0s
[20:55:49.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:79:0)
[20:55:49.115] Timer tick, asking Buildbucket for the build status
[20:55:49.172] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:49.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:80:0) after 1m0s
[20:56:49.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:80:0)
[20:56:49.259] Timer tick, asking Buildbucket for the build status
[20:56:49.305] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:49.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:81:0) after 1m0s
[20:57:49.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:81:0)
[20:57:49.326] Timer tick, asking Buildbucket for the build status
[20:57:49.410] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:49.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:82:0) after 1m0s
[20:58:49.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:82:0)
[20:58:49.431] Timer tick, asking Buildbucket for the build status
[20:58:49.472] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:49.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:83:0) after 1m0s
[20:59:49.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:83:0)
[20:59:49.496] Timer tick, asking Buildbucket for the build status
[20:59:49.723] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:49.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:84:0) after 1m0s
[21:00:49.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:84:0)
[21:00:49.756] Timer tick, asking Buildbucket for the build status
[21:00:49.824] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:49.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:85:0) after 1m0s
[21:01:49.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:85:0)
[21:01:49.844] Timer tick, asking Buildbucket for the build status
[21:01:49.877] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:49.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:86:0) after 1m0s
[21:02:49.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:86:0)
[21:02:49.907] Timer tick, asking Buildbucket for the build status
[21:02:49.992] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:49.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:87:0) after 1m0s
[21:03:50.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:87:0)
[21:03:50.014] Timer tick, asking Buildbucket for the build status
[21:03:50.071] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:50.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:88:0) after 1m0s
[21:04:50.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:88:0)
[21:04:50.094] Timer tick, asking Buildbucket for the build status
[21:04:50.151] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:50.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:89:0) after 1m0s
[21:05:50.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:89:0)
[21:05:50.384] Timer tick, asking Buildbucket for the build status
[21:05:50.506] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:50.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:90:0) after 1m0s
[21:06:50.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:90:0)
[21:06:50.538] Timer tick, asking Buildbucket for the build status
[21:06:50.668] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:50.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:91:0) after 1m0s
[21:07:50.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:91:0)
[21:07:50.696] Timer tick, asking Buildbucket for the build status
[21:07:50.868] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:50.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:92:0) after 1m0s
[21:08:50.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:92:0)
[21:08:50.904] Timer tick, asking Buildbucket for the build status
[21:08:51.143] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:51.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:93:0) after 1m0s
[21:09:51.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:93:0)
[21:09:51.164] Timer tick, asking Buildbucket for the build status
[21:09:51.274] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:51.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:94:0) after 1m0s
[21:10:51.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:94:0)
[21:10:51.307] Timer tick, asking Buildbucket for the build status
[21:10:51.384] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:51.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:95:0) after 1m0s
[21:11:51.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:95:0)
[21:11:51.411] Timer tick, asking Buildbucket for the build status
[21:11:51.498] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:51.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:96:0) after 1m0s
[21:12:51.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:96:0)
[21:12:51.591] Timer tick, asking Buildbucket for the build status
[21:12:51.651] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:51.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:97:0) after 1m0s
[21:13:51.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:97:0)
[21:13:51.730] Timer tick, asking Buildbucket for the build status
[21:13:51.774] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:51.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:98:0) after 1m0s
[21:14:51.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:98:0)
[21:14:51.807] Timer tick, asking Buildbucket for the build status
[21:14:51.915] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:51.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:99:0) after 1m0s
[21:15:51.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:99:0)
[21:15:51.942] Timer tick, asking Buildbucket for the build status
[21:15:52.049] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:52.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:100:0) after 1m0s
[21:16:52.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:100:0)
[21:16:52.093] Timer tick, asking Buildbucket for the build status
[21:16:52.158] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:52.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:101:0) after 1m0s
[21:17:52.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:101:0)
[21:17:52.184] Timer tick, asking Buildbucket for the build status
[21:17:52.278] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:52.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:102:0) after 1m0s
[21:18:52.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:102:0)
[21:18:52.376] Timer tick, asking Buildbucket for the build status
[21:18:52.439] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:52.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:103:0) after 1m0s
[21:19:52.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:103:0)
[21:19:52.463] Timer tick, asking Buildbucket for the build status
[21:19:52.510] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:52.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:104:0) after 1m0s
[21:20:52.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:104:0)
[21:20:52.692] Timer tick, asking Buildbucket for the build status
[21:20:52.774] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:52.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:105:0) after 1m0s
[21:21:52.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:105:0)
[21:21:52.805] Timer tick, asking Buildbucket for the build status
[21:21:52.865] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:52.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:106:0) after 1m0s
[21:22:52.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:106:0)
[21:22:52.906] Timer tick, asking Buildbucket for the build status
[21:22:52.976] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:52.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:107:0) after 1m0s
[21:23:53.010] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:107:0)
[21:23:53.010] Timer tick, asking Buildbucket for the build status
[21:23:53.079] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:53.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:108:0) after 1m0s
[21:24:53.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:108:0)
[21:24:53.100] Timer tick, asking Buildbucket for the build status
[21:24:53.228] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:53.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:109:0) after 1m0s
[21:25:53.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:109:0)
[21:25:53.406] Timer tick, asking Buildbucket for the build status
[21:25:53.467] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:53.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:110:0) after 1m0s
[21:26:53.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:110:0)
[21:26:53.575] Timer tick, asking Buildbucket for the build status
[21:26:53.638] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:53.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:111:0) after 1m0s
[21:27:53.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:111:0)
[21:27:53.665] Timer tick, asking Buildbucket for the build status
[21:27:53.714] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:53.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:112:0) after 1m0s
[21:28:53.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:112:0)
[21:28:53.745] Timer tick, asking Buildbucket for the build status
[21:28:53.876] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:53.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:113:0) after 1m0s
[21:29:53.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:113:0)
[21:29:53.902] Timer tick, asking Buildbucket for the build status
[21:29:53.948] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:53.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:114:0) after 1m0s
[21:30:53.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:114:0)
[21:30:53.978] Timer tick, asking Buildbucket for the build status
[21:30:54.108] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:54.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:115:0) after 1m0s
[21:31:54.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:115:0)
[21:31:54.171] Timer tick, asking Buildbucket for the build status
[21:31:54.271] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:54.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:116:0) after 1m0s
[21:32:54.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:116:0)
[21:32:54.365] Timer tick, asking Buildbucket for the build status
[21:32:54.470] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:54.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:117:0) after 1m0s
[21:33:54.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:117:0)
[21:33:54.502] Timer tick, asking Buildbucket for the build status
[21:33:54.583] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:54.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:118:0) after 1m0s
[21:34:54.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:118:0)
[21:34:54.600] Timer tick, asking Buildbucket for the build status
[21:34:54.737] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:54.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:119:0) after 1m0s
[21:35:54.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:119:0)
[21:35:54.840] Timer tick, asking Buildbucket for the build status
[21:35:55.046] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:55.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:120:0) after 1m0s
[21:36:55.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:120:0)
[21:36:55.074] Timer tick, asking Buildbucket for the build status
[21:36:55.132] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:55.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:121:0) after 1m0s
[21:37:55.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:121:0)
[21:37:55.155] Timer tick, asking Buildbucket for the build status
[21:37:55.289] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:55.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:122:0) after 1m0s
[21:38:55.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:122:0)
[21:38:55.308] Timer tick, asking Buildbucket for the build status
[21:38:55.373] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:55.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:123:0) after 1m0s
[21:39:55.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:123:0)
[21:39:55.399] Timer tick, asking Buildbucket for the build status
[21:39:55.479] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:55.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:124:0) after 1m0s
[21:40:55.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:124:0)
[21:40:55.505] Timer tick, asking Buildbucket for the build status
[21:40:55.627] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:55.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:125:0) after 1m0s
[21:41:55.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:125:0)
[21:41:55.648] Timer tick, asking Buildbucket for the build status
[21:41:55.716] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:55.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:126:0) after 1m0s
[21:42:55.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:126:0)
[21:42:55.778] Timer tick, asking Buildbucket for the build status
[21:42:55.866] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:55.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:127:0) after 1m0s
[21:43:55.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:127:0)
[21:43:55.891] Timer tick, asking Buildbucket for the build status
[21:43:55.959] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:55.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:128:0) after 1m0s
[21:44:55.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:128:0)
[21:44:55.985] Timer tick, asking Buildbucket for the build status
[21:44:56.178] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:56.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:129:0) after 1m0s
[21:45:56.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:129:0)
[21:45:56.252] Timer tick, asking Buildbucket for the build status
[21:45:56.412] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:56.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:130:0) after 1m0s
[21:46:56.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:130:0)
[21:46:56.460] Timer tick, asking Buildbucket for the build status
[21:46:56.730] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:56.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:131:0) after 1m0s
[21:47:56.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:131:0)
[21:47:56.808] Timer tick, asking Buildbucket for the build status
[21:47:56.875] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:56.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:132:0) after 1m0s
[21:48:56.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:132:0)
[21:48:56.955] Timer tick, asking Buildbucket for the build status
[21:48:57.014] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:57.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:133:0) after 1m0s
[21:49:57.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:133:0)
[21:49:57.033] Timer tick, asking Buildbucket for the build status
[21:49:57.109] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:57.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:134:0) after 1m0s
[21:50:57.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:134:0)
[21:50:57.136] Timer tick, asking Buildbucket for the build status
[21:50:57.251] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:57.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:135:0) after 1m0s
[21:51:57.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:135:0)
[21:51:57.275] Timer tick, asking Buildbucket for the build status
[21:51:57.320] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:57.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:136:0) after 1m0s
[21:52:57.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:136:0)
[21:52:57.357] Timer tick, asking Buildbucket for the build status
[21:52:57.440] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:57.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:137:0) after 1m0s
[21:53:57.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:137:0)
[21:53:57.469] Timer tick, asking Buildbucket for the build status
[21:53:57.634] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:57.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:138:0) after 1m0s
[21:54:57.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:138:0)
[21:54:57.660] Timer tick, asking Buildbucket for the build status
[21:54:57.733] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:57.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:139:0) after 1m0s
[21:55:57.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:139:0)
[21:55:57.756] Timer tick, asking Buildbucket for the build status
[21:55:57.997] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:57.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:140:0) after 1m0s
[21:56:58.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:140:0)
[21:56:58.026] Timer tick, asking Buildbucket for the build status
[21:56:58.326] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:58.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:141:0) after 1m0s
[21:57:58.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:141:0)
[21:57:58.421] Timer tick, asking Buildbucket for the build status
[21:57:58.495] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:58.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:142:0) after 1m0s
[21:58:58.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:142:0)
[21:58:58.521] Timer tick, asking Buildbucket for the build status
[21:58:58.561] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:58.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:143:0) after 1m0s
[21:59:58.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:143:0)
[21:59:58.598] Timer tick, asking Buildbucket for the build status
[21:59:58.800] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:58.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:144:0) after 1m0s
[22:00:58.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:144:0)
[22:00:58.890] Timer tick, asking Buildbucket for the build status
[22:00:58.961] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:58.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:145:0) after 1m0s
[22:01:59.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:145:0)
[22:01:59.023] Timer tick, asking Buildbucket for the build status
[22:01:59.108] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:59.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:146:0) after 1m0s
[22:02:59.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:146:0)
[22:02:59.129] Timer tick, asking Buildbucket for the build status
[22:02:59.311] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:59.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:147:0) after 1m0s
[22:03:59.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:147:0)
[22:03:59.362] Timer tick, asking Buildbucket for the build status
[22:03:59.561] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:59.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:148:0) after 1m0s
[22:04:59.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:148:0)
[22:04:59.632] Timer tick, asking Buildbucket for the build status
[22:04:59.700] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:59.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:149:0) after 1m0s
[22:05:59.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:149:0)
[22:05:59.730] Timer tick, asking Buildbucket for the build status
[22:05:59.794] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:59.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:150:0) after 1m0s
[22:06:59.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:150:0)
[22:06:59.812] Timer tick, asking Buildbucket for the build status
[22:06:59.993] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:59.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:151:0) after 1m0s
[22:08:00.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:151:0)
[22:08:00.012] Timer tick, asking Buildbucket for the build status
[22:08:00.201] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:00.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:152:0) after 1m0s
[22:09:00.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:152:0)
[22:09:00.239] Timer tick, asking Buildbucket for the build status
[22:09:00.302] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:00.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:153:0) after 1m0s
[22:10:00.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:153:0)
[22:10:00.384] Timer tick, asking Buildbucket for the build status
[22:10:00.461] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:00.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:154:0) after 1m0s
[22:11:00.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:154:0)
[22:11:00.483] Timer tick, asking Buildbucket for the build status
[22:11:00.552] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:00.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:155:0) after 1m0s
[22:12:00.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:155:0)
[22:12:00.603] Timer tick, asking Buildbucket for the build status
[22:12:00.778] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:00.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:156:0) after 1m0s
[22:13:00.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:156:0)
[22:13:00.859] Timer tick, asking Buildbucket for the build status
[22:13:01.022] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:01.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:157:0) after 1m0s
[22:14:01.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:157:0)
[22:14:01.184] Timer tick, asking Buildbucket for the build status
[22:14:01.368] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:01.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:158:0) after 1m0s
[22:15:01.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:158:0)
[22:15:01.389] Timer tick, asking Buildbucket for the build status
[22:15:01.620] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:01.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:159:0) after 1m0s
[22:16:01.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:159:0)
[22:16:01.652] Timer tick, asking Buildbucket for the build status
[22:16:01.706] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:01.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:160:0) after 1m0s
[22:17:01.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:160:0)
[22:17:01.734] Timer tick, asking Buildbucket for the build status
[22:17:01.836] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:01.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:161:0) after 1m0s
[22:18:01.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:161:0)
[22:18:01.874] Timer tick, asking Buildbucket for the build status
[22:18:01.950] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:01.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:162:0) after 1m0s
[22:19:02.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:162:0)
[22:19:02.011] Timer tick, asking Buildbucket for the build status
[22:19:02.118] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:02.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:163:0) after 1m0s
[22:20:02.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:163:0)
[22:20:02.170] Timer tick, asking Buildbucket for the build status
[22:20:02.264] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:02.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:164:0) after 1m0s
[22:21:02.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:164:0)
[22:21:02.333] Timer tick, asking Buildbucket for the build status
[22:21:02.516] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:02.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:165:0) after 1m0s
[22:22:02.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:165:0)
[22:22:02.582] Timer tick, asking Buildbucket for the build status
[22:22:02.846] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:02.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:166:0) after 1m0s
[22:23:02.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:166:0)
[22:23:02.899] Timer tick, asking Buildbucket for the build status
[22:23:02.979] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:02.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:167:0) after 1m0s
[22:24:03.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:167:0)
[22:24:03.029] Timer tick, asking Buildbucket for the build status
[22:24:03.105] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:03.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:168:0) after 1m0s
[22:25:03.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:168:0)
[22:25:03.138] Timer tick, asking Buildbucket for the build status
[22:25:03.265] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:03.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:169:0) after 1m0s
[22:26:03.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:169:0)
[22:26:03.295] Timer tick, asking Buildbucket for the build status
[22:26:03.382] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:03.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:170:0) after 1m0s
[22:27:03.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:170:0)
[22:27:03.411] Timer tick, asking Buildbucket for the build status
[22:27:03.455] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:03.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:171:0) after 1m0s
[22:28:03.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:171:0)
[22:28:03.487] Timer tick, asking Buildbucket for the build status
[22:28:03.534] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:03.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:172:0) after 1m0s
[22:29:03.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:172:0)
[22:29:03.627] Timer tick, asking Buildbucket for the build status
[22:29:03.683] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:03.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:173:0) after 1m0s
[22:30:03.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:173:0)
[22:30:03.708] Timer tick, asking Buildbucket for the build status
[22:30:03.801] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:03.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:174:0) after 1m0s
[22:31:03.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:174:0)
[22:31:03.825] Timer tick, asking Buildbucket for the build status
[22:31:03.961] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:03.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:175:0) after 1m0s
[22:32:04.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:175:0)
[22:32:04.040] Timer tick, asking Buildbucket for the build status
[22:32:04.110] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:04.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:176:0) after 1m0s
[22:33:04.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:176:0)
[22:33:04.144] Timer tick, asking Buildbucket for the build status
[22:33:04.226] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:04.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:177:0) after 1m0s
[22:34:04.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:177:0)
[22:34:04.260] Timer tick, asking Buildbucket for the build status
[22:34:04.408] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:04.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:178:0) after 1m0s
[22:35:04.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:178:0)
[22:35:04.428] Timer tick, asking Buildbucket for the build status
[22:35:04.471] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:04.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:179:0) after 1m0s
[22:36:04.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:179:0)
[22:36:04.496] Timer tick, asking Buildbucket for the build status
[22:36:04.551] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:04.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:180:0) after 1m0s
[22:37:04.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:180:0)
[22:37:04.589] Timer tick, asking Buildbucket for the build status
[22:37:04.758] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:04.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:181:0) after 1m0s
[22:38:04.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:181:0)
[22:38:04.813] Timer tick, asking Buildbucket for the build status
[22:38:04.864] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:04.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:182:0) after 1m0s
[22:39:04.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:182:0)
[22:39:04.902] Timer tick, asking Buildbucket for the build status
[22:39:04.941] Build 8933659386073200272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:04.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099089448140496432:183:0) after 1m0s
[22:39:20.228] Received PubSub notification, asking Buildbucket for the build status
[22:39:20.297] Build 8933659386073200272: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[22:39:20.297] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "completed_ts": "1538606359309880",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1538595514188133",
    "failure_reason": "INFRA_FAILURE",
    "id": "8933659386073200272",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"70.0.3538.41\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"kitchen crashed or got killed\", \"bootstrapperCallStack\": [], \"type\": \"BOOTSTRAPPER_ERROR\"}, \"recipeResult\": null, \"recipeExitCode\": null, \"recipe\": null, \"annotationUrl\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3801-8761768\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-b-w9dc\"], \"pool\": [\"luci.infra.cron\"]}}, \"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1538595527262485",
    "status": "COMPLETED",
    "status_changed_ts": "1538606359609029",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9099089448140496432",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_dimension:builder:Build From Tarball",
      "swarming_dimension:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.infra.cron",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:buildbucket_bucket:luci.infra.cron",
      "swarming_tag:buildbucket_build_id:8933659386073200272",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
      "swarming_tag:builder:Build From Tarball",
      "swarming_tag:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8933659386073200272/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:parent_buildername:publish_tarball",
      "swarming_tag:pool:luci.infra.cron",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_tag:scheduler_invocation_id:9099089448140496432",
      "swarming_tag:scheduler_job_id:infra/Build From Tarball",
      "swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d431f79a1f9890266786724a5b445a445f332a3a",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:405441f199ba7410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1538606359610592",
    "url": "https://ci.chromium.org/p/infra/builds/b8933659386073200272",
    "utcnow_ts": "1538606360289299"
  }
}
[22:39:20.297] Invocation finished in 3h0m47.464433383s with status FAILED