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

Incoming tags

parent_buildername:publish_tarball
user_agent:recipe

Debug log (UTC)

[13:45:46.247] New invocation is queued and will start shortly
[13:45:46.247] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[13:45:47.557] Starting the invocation (attempt 1)
[13:45:47.598] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:45:47.598] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:45:47.598] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9097933882388616944",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"72.0.3582.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9097933882388616944",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[13:45:49.091] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1539697547673119",
    "id": "8932503820209341712",
    "lease_expiration_ts": "1539816348892394",
    "lease_key": "714176080",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3582.0\"}}",
    "project": "infra",
    "result_details_json": "null",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1539697548893604",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9097933882388616944",
      "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:8932503820209341712",
      "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/8932503820209341712/+/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:9097933882388616944",
      "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:5e02f6736b5d25d7bb06d6ad547d0269e7eab96e",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4095f1a503c61910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1539697548893708",
    "url": "https://ci.chromium.org/p/infra/builds/b8932503820209341712",
    "utcnow_ts": "1539697549079188"
  }
}
[13:45:49.091] Task URL: https://ci.chromium.org/p/infra/builds/b8932503820209341712
[13:45:49.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:2:0) after 1m0s
[13:46:49.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:2:0)
[13:46:49.111] Timer tick, asking Buildbucket for the build status
[13:46:49.147] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:49.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:3:0) after 1m0s
[13:46:49.239] Received PubSub notification, asking Buildbucket for the build status
[13:46:49.341] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:49.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:3:0)
[13:47:49.162] Timer tick, asking Buildbucket for the build status
[13:47:49.235] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:49.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:5:0) after 1m0s
[13:48:49.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:5:0)
[13:48:49.335] Timer tick, asking Buildbucket for the build status
[13:48:49.445] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:49.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:6:0) after 1m0s
[13:49:49.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:6:0)
[13:49:49.464] Timer tick, asking Buildbucket for the build status
[13:49:49.528] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:49.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:7:0) after 1m0s
[13:50:49.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:7:0)
[13:50:49.544] Timer tick, asking Buildbucket for the build status
[13:50:49.618] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:49.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:8:0) after 1m0s
[13:51:49.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:8:0)
[13:51:49.642] Timer tick, asking Buildbucket for the build status
[13:51:49.710] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:49.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:9:0) after 1m0s
[13:52:49.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:9:0)
[13:52:49.726] Timer tick, asking Buildbucket for the build status
[13:52:49.798] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:49.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:10:0) after 1m0s
[13:53:49.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:10:0)
[13:53:49.814] Timer tick, asking Buildbucket for the build status
[13:53:49.998] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:49.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:11:0) after 1m0s
[13:54:50.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:11:0)
[13:54:50.028] Timer tick, asking Buildbucket for the build status
[13:54:50.189] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:50.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:12:0) after 1m0s
[13:55:50.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:12:0)
[13:55:50.205] Timer tick, asking Buildbucket for the build status
[13:55:50.318] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:50.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:13:0) after 1m0s
[13:56:50.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:13:0)
[13:56:50.339] Timer tick, asking Buildbucket for the build status
[13:56:50.388] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:50.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:14:0) after 1m0s
[13:57:50.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:14:0)
[13:57:50.406] Timer tick, asking Buildbucket for the build status
[13:57:50.549] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:50.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:15:0) after 1m0s
[13:58:50.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:15:0)
[13:58:50.591] Timer tick, asking Buildbucket for the build status
[13:58:50.688] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:50.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:16:0) after 1m0s
[13:59:50.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:16:0)
[13:59:50.861] Timer tick, asking Buildbucket for the build status
[13:59:50.899] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:50.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:17:0) after 1m0s
[14:00:50.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:17:0)
[14:00:50.924] Timer tick, asking Buildbucket for the build status
[14:00:50.996] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:50.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:18:0) after 1m0s
[14:01:51.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:18:0)
[14:01:51.035] Timer tick, asking Buildbucket for the build status
[14:01:51.135] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:51.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:19:0) after 1m0s
[14:02:51.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:19:0)
[14:02:51.157] Timer tick, asking Buildbucket for the build status
[14:02:51.266] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:51.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:20:0) after 1m0s
[14:03:51.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:20:0)
[14:03:51.284] Timer tick, asking Buildbucket for the build status
[14:03:51.359] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:51.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:21:0) after 1m0s
[14:04:51.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:21:0)
[14:04:51.384] Timer tick, asking Buildbucket for the build status
[14:04:51.435] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:51.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:22:0) after 1m0s
[14:05:51.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:22:0)
[14:05:51.459] Timer tick, asking Buildbucket for the build status
[14:05:51.663] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:51.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:23:0) after 1m0s
[14:06:51.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:23:0)
[14:06:51.683] Timer tick, asking Buildbucket for the build status
[14:06:51.815] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:51.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:24:0) after 1m0s
[14:07:51.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:24:0)
[14:07:51.833] Timer tick, asking Buildbucket for the build status
[14:07:51.940] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:51.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:25:0) after 1m0s
[14:08:51.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:25:0)
[14:08:51.960] Timer tick, asking Buildbucket for the build status
[14:08:52.120] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:52.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:26:0) after 1m0s
[14:09:52.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:26:0)
[14:09:52.146] Timer tick, asking Buildbucket for the build status
[14:09:52.204] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:52.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:27:0) after 1m0s
[14:10:52.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:27:0)
[14:10:52.220] Timer tick, asking Buildbucket for the build status
[14:10:52.306] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:52.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:28:0) after 1m0s
[14:11:52.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:28:0)
[14:11:52.366] Timer tick, asking Buildbucket for the build status
[14:11:52.442] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:52.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:29:0) after 1m0s
[14:12:52.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:29:0)
[14:12:52.460] Timer tick, asking Buildbucket for the build status
[14:12:52.562] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:52.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:30:0) after 1m0s
[14:13:52.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:30:0)
[14:13:52.578] Timer tick, asking Buildbucket for the build status
[14:13:52.667] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:52.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:31:0) after 1m0s
[14:14:52.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:31:0)
[14:14:52.684] Timer tick, asking Buildbucket for the build status
[14:14:52.797] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:52.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:32:0) after 1m0s
[14:15:52.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:32:0)
[14:15:52.814] Timer tick, asking Buildbucket for the build status
[14:15:52.886] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:52.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:33:0) after 1m0s
[14:16:52.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:33:0)
[14:16:52.915] Timer tick, asking Buildbucket for the build status
[14:16:52.972] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:52.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:34:0) after 1m0s
[14:17:52.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:34:0)
[14:17:52.993] Timer tick, asking Buildbucket for the build status
[14:17:53.040] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:53.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:35:0) after 1m0s
[14:18:53.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:35:0)
[14:18:53.060] Timer tick, asking Buildbucket for the build status
[14:18:53.151] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:53.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:36:0) after 1m0s
[14:19:53.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:36:0)
[14:19:53.188] Timer tick, asking Buildbucket for the build status
[14:19:53.268] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:53.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:37:0) after 1m0s
[14:20:53.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:37:0)
[14:20:53.298] Timer tick, asking Buildbucket for the build status
[14:20:53.339] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:53.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:38:0) after 1m0s
[14:21:53.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:38:0)
[14:21:53.358] Timer tick, asking Buildbucket for the build status
[14:21:53.441] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:53.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:39:0) after 1m0s
[14:22:53.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:39:0)
[14:22:53.461] Timer tick, asking Buildbucket for the build status
[14:22:53.537] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:53.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:40:0) after 1m0s
[14:23:53.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:40:0)
[14:23:53.583] Timer tick, asking Buildbucket for the build status
[14:23:53.710] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:53.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:41:0) after 1m0s
[14:24:53.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:41:0)
[14:24:53.728] Timer tick, asking Buildbucket for the build status
[14:24:53.837] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:53.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:42:0) after 1m0s
[14:25:53.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:42:0)
[14:25:53.860] Timer tick, asking Buildbucket for the build status
[14:25:53.973] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:53.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:43:0) after 1m0s
[14:26:53.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:43:0)
[14:26:53.990] Timer tick, asking Buildbucket for the build status
[14:26:54.050] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:54.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:44:0) after 1m0s
[14:27:54.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:44:0)
[14:27:54.074] Timer tick, asking Buildbucket for the build status
[14:27:54.222] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:54.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:45:0) after 1m0s
[14:28:54.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:45:0)
[14:28:54.243] Timer tick, asking Buildbucket for the build status
[14:28:54.301] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:54.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:46:0) after 1m0s
[14:29:54.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:46:0)
[14:29:54.344] Timer tick, asking Buildbucket for the build status
[14:29:54.402] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:54.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:47:0) after 1m0s
[14:30:54.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:47:0)
[14:30:54.458] Timer tick, asking Buildbucket for the build status
[14:30:54.613] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:54.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:48:0) after 1m0s
[14:31:54.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:48:0)
[14:31:54.631] Timer tick, asking Buildbucket for the build status
[14:31:54.712] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:54.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:49:0) after 1m0s
[14:32:54.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:49:0)
[14:32:54.734] Timer tick, asking Buildbucket for the build status
[14:32:54.794] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:54.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:50:0) after 1m0s
[14:33:54.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:50:0)
[14:33:54.817] Timer tick, asking Buildbucket for the build status
[14:33:54.860] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:54.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:51:0) after 1m0s
[14:34:54.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:51:0)
[14:34:54.884] Timer tick, asking Buildbucket for the build status
[14:34:54.923] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:54.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:52:0) after 1m0s
[14:35:54.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:52:0)
[14:35:54.959] Timer tick, asking Buildbucket for the build status
[14:35:55.031] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:55.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:53:0) after 1m0s
[14:36:55.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:53:0)
[14:36:55.072] Timer tick, asking Buildbucket for the build status
[14:36:55.122] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:55.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:54:0) after 1m0s
[14:37:55.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:54:0)
[14:37:55.142] Timer tick, asking Buildbucket for the build status
[14:37:55.240] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:55.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:55:0) after 1m0s
[14:38:55.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:55:0)
[14:38:55.257] Timer tick, asking Buildbucket for the build status
[14:38:55.321] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:55.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:56:0) after 1m0s
[14:39:55.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:56:0)
[14:39:55.342] Timer tick, asking Buildbucket for the build status
[14:39:55.540] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:55.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:57:0) after 1m0s
[14:40:55.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:57:0)
[14:40:55.568] Timer tick, asking Buildbucket for the build status
[14:40:55.745] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:55.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:58:0) after 1m0s
[14:41:55.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:58:0)
[14:41:55.802] Timer tick, asking Buildbucket for the build status
[14:41:55.996] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:55.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:59:0) after 1m0s
[14:42:56.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:59:0)
[14:42:56.019] Timer tick, asking Buildbucket for the build status
[14:42:56.054] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:56.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:60:0) after 1m0s
[14:43:56.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:60:0)
[14:43:56.106] Timer tick, asking Buildbucket for the build status
[14:43:56.203] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:56.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:61:0) after 1m0s
[14:44:56.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:61:0)
[14:44:56.220] Timer tick, asking Buildbucket for the build status
[14:44:56.257] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:56.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:62:0) after 1m0s
[14:45:56.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:62:0)
[14:45:56.280] Timer tick, asking Buildbucket for the build status
[14:45:56.396] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:56.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:63:0) after 1m0s
[14:46:56.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:63:0)
[14:46:56.448] Timer tick, asking Buildbucket for the build status
[14:46:56.589] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:56.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:64:0) after 1m0s
[14:47:56.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:64:0)
[14:47:56.607] Timer tick, asking Buildbucket for the build status
[14:47:56.675] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:56.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:65:0) after 1m0s
[14:48:56.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:65:0)
[14:48:56.695] Timer tick, asking Buildbucket for the build status
[14:48:56.755] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:56.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:66:0) after 1m0s
[14:49:56.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:66:0)
[14:49:56.818] Timer tick, asking Buildbucket for the build status
[14:49:56.890] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:56.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:67:0) after 1m0s
[14:50:56.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:67:0)
[14:50:56.912] Timer tick, asking Buildbucket for the build status
[14:50:57.012] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:57.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:68:0) after 1m0s
[14:51:57.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:68:0)
[14:51:57.061] Timer tick, asking Buildbucket for the build status
[14:51:57.115] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:57.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:69:0) after 1m0s
[14:52:57.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:69:0)
[14:52:57.136] Timer tick, asking Buildbucket for the build status
[14:52:57.170] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:57.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:70:0) after 1m0s
[14:53:57.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:70:0)
[14:53:57.192] Timer tick, asking Buildbucket for the build status
[14:53:57.268] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:57.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:71:0) after 1m0s
[14:54:57.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:71:0)
[14:54:57.286] Timer tick, asking Buildbucket for the build status
[14:54:57.321] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:57.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:72:0) after 1m0s
[14:55:57.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:72:0)
[14:55:57.413] Timer tick, asking Buildbucket for the build status
[14:55:57.485] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:57.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:73:0) after 1m0s
[14:56:57.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:73:0)
[14:56:57.551] Timer tick, asking Buildbucket for the build status
[14:56:57.635] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:57.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:74:0) after 1m0s
[14:57:57.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:74:0)
[14:57:57.657] Timer tick, asking Buildbucket for the build status
[14:57:58.044] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:58.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:75:0) after 1m0s
[14:58:58.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:75:0)
[14:58:58.070] Timer tick, asking Buildbucket for the build status
[14:58:58.154] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:58.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:76:0) after 1m0s
[14:59:58.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:76:0)
[14:59:58.175] Timer tick, asking Buildbucket for the build status
[14:59:58.243] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:58.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:77:0) after 1m0s
[15:00:58.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:77:0)
[15:00:58.265] Timer tick, asking Buildbucket for the build status
[15:00:58.342] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:58.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:78:0) after 1m0s
[15:01:58.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:78:0)
[15:01:58.388] Timer tick, asking Buildbucket for the build status
[15:01:58.449] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:58.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:79:0) after 1m0s
[15:02:58.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:79:0)
[15:02:58.467] Timer tick, asking Buildbucket for the build status
[15:02:58.554] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:58.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:80:0) after 1m0s
[15:03:58.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:80:0)
[15:03:58.573] Timer tick, asking Buildbucket for the build status
[15:03:58.616] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:58.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:81:0) after 1m0s
[15:04:58.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:81:0)
[15:04:58.653] Timer tick, asking Buildbucket for the build status
[15:04:58.746] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:58.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:82:0) after 1m0s
[15:05:58.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:82:0)
[15:05:58.800] Timer tick, asking Buildbucket for the build status
[15:05:58.831] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:58.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:83:0) after 1m0s
[15:06:58.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:83:0)
[15:06:58.853] Timer tick, asking Buildbucket for the build status
[15:06:58.934] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:58.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:84:0) after 1m0s
[15:07:59.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:84:0)
[15:07:59.060] Timer tick, asking Buildbucket for the build status
[15:07:59.133] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:59.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:85:0) after 1m0s
[15:08:59.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:85:0)
[15:08:59.249] Timer tick, asking Buildbucket for the build status
[15:08:59.310] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:59.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:86:0) after 1m0s
[15:09:59.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:86:0)
[15:09:59.344] Timer tick, asking Buildbucket for the build status
[15:09:59.465] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:59.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:87:0) after 1m0s
[15:10:59.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:87:0)
[15:10:59.484] Timer tick, asking Buildbucket for the build status
[15:10:59.521] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:59.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:88:0) after 1m0s
[15:11:59.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:88:0)
[15:11:59.744] Timer tick, asking Buildbucket for the build status
[15:12:01.912] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:01.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:89:0) after 1m0s
[15:13:01.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:89:0)
[15:13:01.938] Timer tick, asking Buildbucket for the build status
[15:13:01.996] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:01.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:90:0) after 1m0s
[15:14:02.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:90:0)
[15:14:02.016] Timer tick, asking Buildbucket for the build status
[15:14:02.142] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:02.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:91:0) after 1m0s
[15:15:02.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:91:0)
[15:15:02.160] Timer tick, asking Buildbucket for the build status
[15:15:02.274] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:02.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:92:0) after 1m0s
[15:16:02.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:92:0)
[15:16:02.408] Timer tick, asking Buildbucket for the build status
[15:16:02.511] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:02.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:93:0) after 1m0s
[15:17:02.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:93:0)
[15:17:02.676] Timer tick, asking Buildbucket for the build status
[15:17:06.044] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:06.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:94:0) after 1m0s
[15:18:06.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:94:0)
[15:18:06.064] Timer tick, asking Buildbucket for the build status
[15:18:06.142] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:06.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:95:0) after 1m0s
[15:19:06.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:95:0)
[15:19:06.243] Timer tick, asking Buildbucket for the build status
[15:19:06.308] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:06.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:96:0) after 1m0s
[15:20:06.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:96:0)
[15:20:06.359] Timer tick, asking Buildbucket for the build status
[15:20:06.401] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:06.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:97:0) after 1m0s
[15:21:06.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:97:0)
[15:21:06.432] Timer tick, asking Buildbucket for the build status
[15:21:06.590] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:06.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:98:0) after 1m0s
[15:22:06.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:98:0)
[15:22:06.726] Timer tick, asking Buildbucket for the build status
[15:22:06.760] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:06.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:99:0) after 1m0s
[15:23:06.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:99:0)
[15:23:06.781] Timer tick, asking Buildbucket for the build status
[15:23:06.877] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:06.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:100:0) after 1m0s
[15:24:06.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:100:0)
[15:24:06.898] Timer tick, asking Buildbucket for the build status
[15:24:07.040] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:07.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:101:0) after 1m0s
[15:25:07.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:101:0)
[15:25:07.121] Timer tick, asking Buildbucket for the build status
[15:25:07.184] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:07.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:102:0) after 1m0s
[15:26:07.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:102:0)
[15:26:07.203] Timer tick, asking Buildbucket for the build status
[15:26:07.268] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:07.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:103:0) after 1m0s
[15:27:07.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:103:0)
[15:27:07.291] Timer tick, asking Buildbucket for the build status
[15:27:07.356] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:07.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:104:0) after 1m0s
[15:28:07.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:104:0)
[15:28:07.427] Timer tick, asking Buildbucket for the build status
[15:28:07.466] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:07.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:105:0) after 1m0s
[15:29:07.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:105:0)
[15:29:07.486] Timer tick, asking Buildbucket for the build status
[15:29:07.556] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:07.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:106:0) after 1m0s
[15:30:07.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:106:0)
[15:30:07.581] Timer tick, asking Buildbucket for the build status
[15:30:07.622] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:07.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:107:0) after 1m0s
[15:31:07.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:107:0)
[15:31:07.663] Timer tick, asking Buildbucket for the build status
[15:31:07.772] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:07.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:108:0) after 1m0s
[15:32:07.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:108:0)
[15:32:07.869] Timer tick, asking Buildbucket for the build status
[15:32:07.906] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:07.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:109:0) after 1m0s
[15:33:08.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:109:0)
[15:33:08.037] Timer tick, asking Buildbucket for the build status
[15:33:08.113] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:08.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:110:0) after 1m0s
[15:34:08.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:110:0)
[15:34:08.460] Timer tick, asking Buildbucket for the build status
[15:34:08.566] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:08.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:111:0) after 1m0s
[15:35:08.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:111:0)
[15:35:08.648] Timer tick, asking Buildbucket for the build status
[15:35:08.875] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:08.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:112:0) after 1m0s
[15:36:08.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:112:0)
[15:36:08.895] Timer tick, asking Buildbucket for the build status
[15:36:09.005] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:09.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:113:0) after 1m0s
[15:37:09.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:113:0)
[15:37:09.026] Timer tick, asking Buildbucket for the build status
[15:37:09.082] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:09.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:114:0) after 1m0s
[15:38:09.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:114:0)
[15:38:09.106] Timer tick, asking Buildbucket for the build status
[15:38:09.143] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:09.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:115:0) after 1m0s
[15:39:09.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:115:0)
[15:39:09.169] Timer tick, asking Buildbucket for the build status
[15:39:09.453] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:09.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:116:0) after 1m0s
[15:40:09.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:116:0)
[15:40:09.578] Timer tick, asking Buildbucket for the build status
[15:40:09.644] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:09.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:117:0) after 1m0s
[15:41:09.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:117:0)
[15:41:09.691] Timer tick, asking Buildbucket for the build status
[15:41:09.787] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:09.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:118:0) after 1m0s
[15:42:09.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:118:0)
[15:42:09.820] Timer tick, asking Buildbucket for the build status
[15:42:09.936] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:09.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:119:0) after 1m0s
[15:43:09.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:119:0)
[15:43:09.954] Timer tick, asking Buildbucket for the build status
[15:43:10.025] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:10.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:120:0) after 1m0s
[15:44:10.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:120:0)
[15:44:10.247] Timer tick, asking Buildbucket for the build status
[15:44:10.334] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:10.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:121:0) after 1m0s
[15:45:10.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:121:0)
[15:45:10.381] Timer tick, asking Buildbucket for the build status
[15:45:10.673] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:10.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:122:0) after 1m0s
[15:46:10.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:122:0)
[15:46:10.741] Timer tick, asking Buildbucket for the build status
[15:46:10.823] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:10.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:123:0) after 1m0s
[15:47:10.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:123:0)
[15:47:10.950] Timer tick, asking Buildbucket for the build status
[15:47:11.052] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:11.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:124:0) after 1m0s
[15:48:11.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:124:0)
[15:48:11.070] Timer tick, asking Buildbucket for the build status
[15:48:11.153] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:11.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:125:0) after 1m0s
[15:49:11.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:125:0)
[15:49:11.265] Timer tick, asking Buildbucket for the build status
[15:49:11.421] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:11.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:126:0) after 1m0s
[15:50:11.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:126:0)
[15:50:11.442] Timer tick, asking Buildbucket for the build status
[15:50:11.496] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:11.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:127:0) after 1m0s
[15:51:11.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:127:0)
[15:51:11.516] Timer tick, asking Buildbucket for the build status
[15:51:11.603] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:11.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:128:0) after 1m0s
[15:52:11.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:128:0)
[15:52:11.624] Timer tick, asking Buildbucket for the build status
[15:52:11.670] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:11.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:129:0) after 1m0s
[15:53:11.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:129:0)
[15:53:11.688] Timer tick, asking Buildbucket for the build status
[15:53:11.783] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:11.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:130:0) after 1m0s
[15:54:11.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:130:0)
[15:54:11.806] Timer tick, asking Buildbucket for the build status
[15:54:11.898] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:11.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:131:0) after 1m0s
[15:55:11.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:131:0)
[15:55:11.945] Timer tick, asking Buildbucket for the build status
[15:55:12.081] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:12.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:132:0) after 1m0s
[15:56:12.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:132:0)
[15:56:12.101] Timer tick, asking Buildbucket for the build status
[15:56:12.153] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:12.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:133:0) after 1m0s
[15:57:12.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:133:0)
[15:57:12.178] Timer tick, asking Buildbucket for the build status
[15:57:12.407] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:12.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:134:0) after 1m0s
[15:58:12.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:134:0)
[15:58:12.441] Timer tick, asking Buildbucket for the build status
[15:58:12.558] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:12.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:135:0) after 1m0s
[15:59:12.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:135:0)
[15:59:12.600] Timer tick, asking Buildbucket for the build status
[15:59:12.809] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:12.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:136:0) after 1m0s
[16:00:12.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:136:0)
[16:00:12.882] Timer tick, asking Buildbucket for the build status
[16:00:12.932] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:12.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:137:0) after 1m0s
[16:01:13.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:137:0)
[16:01:13.009] Timer tick, asking Buildbucket for the build status
[16:01:13.094] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:13.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:138:0) after 1m0s
[16:02:13.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:138:0)
[16:02:13.137] Timer tick, asking Buildbucket for the build status
[16:02:13.188] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:13.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:139:0) after 1m0s
[16:03:13.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:139:0)
[16:03:13.216] Timer tick, asking Buildbucket for the build status
[16:03:13.298] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:13.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:140:0) after 1m0s
[16:04:13.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:140:0)
[16:04:13.317] Timer tick, asking Buildbucket for the build status
[16:04:13.361] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:13.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:141:0) after 1m0s
[16:05:13.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:141:0)
[16:05:13.395] Timer tick, asking Buildbucket for the build status
[16:05:13.481] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:13.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:142:0) after 1m0s
[16:06:13.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:142:0)
[16:06:13.502] Timer tick, asking Buildbucket for the build status
[16:06:13.549] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:13.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:143:0) after 1m0s
[16:07:13.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:143:0)
[16:07:13.606] Timer tick, asking Buildbucket for the build status
[16:07:13.809] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:13.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:144:0) after 1m0s
[16:08:13.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:144:0)
[16:08:13.826] Timer tick, asking Buildbucket for the build status
[16:08:13.943] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:13.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:145:0) after 1m0s
[16:09:14.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:145:0)
[16:09:14.013] Timer tick, asking Buildbucket for the build status
[16:09:14.235] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:14.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:146:0) after 1m0s
[16:10:14.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:146:0)
[16:10:14.256] Timer tick, asking Buildbucket for the build status
[16:10:14.354] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:14.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:147:0) after 1m0s
[16:11:14.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:147:0)
[16:11:14.374] Timer tick, asking Buildbucket for the build status
[16:11:14.672] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:14.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:148:0) after 1m0s
[16:12:14.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:148:0)
[16:12:14.693] Timer tick, asking Buildbucket for the build status
[16:12:14.809] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:14.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:149:0) after 1m0s
[16:13:14.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:149:0)
[16:13:14.845] Timer tick, asking Buildbucket for the build status
[16:13:14.896] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:14.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:150:0) after 1m0s
[16:14:14.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:150:0)
[16:14:14.925] Timer tick, asking Buildbucket for the build status
[16:14:15.001] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:15.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:151:0) after 1m0s
[16:15:15.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:151:0)
[16:15:15.040] Timer tick, asking Buildbucket for the build status
[16:15:15.110] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:15.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:152:0) after 1m0s
[16:16:15.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:152:0)
[16:16:15.349] Timer tick, asking Buildbucket for the build status
[16:16:15.545] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:15.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:153:0) after 1m0s
[16:17:15.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:153:0)
[16:17:15.571] Timer tick, asking Buildbucket for the build status
[16:17:15.618] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:15.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:154:0) after 1m0s
[16:18:15.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:154:0)
[16:18:15.643] Timer tick, asking Buildbucket for the build status
[16:18:15.737] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:15.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:155:0) after 1m0s
[16:19:15.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:155:0)
[16:19:15.773] Timer tick, asking Buildbucket for the build status
[16:19:15.851] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:15.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:156:0) after 1m0s
[16:20:15.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:156:0)
[16:20:15.874] Timer tick, asking Buildbucket for the build status
[16:20:16.005] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:16.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:157:0) after 1m0s
[16:21:16.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:157:0)
[16:21:16.033] Timer tick, asking Buildbucket for the build status
[16:21:16.145] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:16.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:158:0) after 1m0s
[16:22:16.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:158:0)
[16:22:16.189] Timer tick, asking Buildbucket for the build status
[16:22:16.257] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:16.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:159:0) after 1m0s
[16:23:16.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:159:0)
[16:23:16.343] Timer tick, asking Buildbucket for the build status
[16:23:16.674] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:16.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:160:0) after 1m0s
[16:24:16.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:160:0)
[16:24:16.696] Timer tick, asking Buildbucket for the build status
[16:24:16.770] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:16.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:161:0) after 1m0s
[16:25:16.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:161:0)
[16:25:16.792] Timer tick, asking Buildbucket for the build status
[16:25:16.852] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:16.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:162:0) after 1m0s
[16:26:16.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:162:0)
[16:26:16.886] Timer tick, asking Buildbucket for the build status
[16:26:16.944] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:16.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:163:0) after 1m0s
[16:27:17.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:163:0)
[16:27:17.039] Timer tick, asking Buildbucket for the build status
[16:27:17.089] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:17.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:164:0) after 1m0s
[16:28:17.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:164:0)
[16:28:17.118] Timer tick, asking Buildbucket for the build status
[16:28:17.195] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:17.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:165:0) after 1m0s
[16:29:17.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:165:0)
[16:29:17.216] Timer tick, asking Buildbucket for the build status
[16:29:17.347] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:17.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:166:0) after 1m0s
[16:30:17.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:166:0)
[16:30:17.425] Timer tick, asking Buildbucket for the build status
[16:30:17.526] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:17.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:167:0) after 1m0s
[16:31:17.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:167:0)
[16:31:17.561] Timer tick, asking Buildbucket for the build status
[16:31:17.645] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:17.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:168:0) after 1m0s
[16:32:17.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:168:0)
[16:32:17.695] Timer tick, asking Buildbucket for the build status
[16:32:17.806] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:17.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:169:0) after 1m0s
[16:33:17.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:169:0)
[16:33:17.851] Timer tick, asking Buildbucket for the build status
[16:33:17.937] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:17.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:170:0) after 1m0s
[16:34:17.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:170:0)
[16:34:17.960] Timer tick, asking Buildbucket for the build status
[16:34:18.118] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:18.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:171:0) after 1m0s
[16:35:18.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:171:0)
[16:35:18.198] Timer tick, asking Buildbucket for the build status
[16:35:18.295] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:18.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:172:0) after 1m0s
[16:36:18.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:172:0)
[16:36:18.388] Timer tick, asking Buildbucket for the build status
[16:36:18.595] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:18.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:173:0) after 1m0s
[16:37:18.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:173:0)
[16:37:18.706] Timer tick, asking Buildbucket for the build status
[16:37:18.763] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:18.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:174:0) after 1m0s
[16:38:18.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:174:0)
[16:38:18.786] Timer tick, asking Buildbucket for the build status
[16:38:19.367] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:19.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:175:0) after 1m0s
[16:39:19.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:175:0)
[16:39:19.391] Timer tick, asking Buildbucket for the build status
[16:39:19.454] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:19.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:176:0) after 1m0s
[16:40:19.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:176:0)
[16:40:19.477] Timer tick, asking Buildbucket for the build status
[16:40:19.549] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:19.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:177:0) after 1m0s
[16:41:19.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:177:0)
[16:41:19.573] Timer tick, asking Buildbucket for the build status
[16:41:19.756] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:19.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:178:0) after 1m0s
[16:42:19.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:178:0)
[16:42:19.785] Timer tick, asking Buildbucket for the build status
[16:42:19.885] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:19.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:179:0) after 1m0s
[16:43:19.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:179:0)
[16:43:19.914] Timer tick, asking Buildbucket for the build status
[16:43:19.999] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:19.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:180:0) after 1m0s
[16:44:20.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:180:0)
[16:44:20.021] Timer tick, asking Buildbucket for the build status
[16:44:20.057] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:20.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:181:0) after 1m0s
[16:45:20.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:181:0)
[16:45:20.145] Timer tick, asking Buildbucket for the build status
[16:45:20.221] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:20.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:182:0) after 1m0s
[16:46:20.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:182:0)
[16:46:20.270] Timer tick, asking Buildbucket for the build status
[16:46:20.351] Build 8932503820209341712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:20.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9097933882388616944:183:0) after 1m0s
[16:46:46.203] Received PubSub notification, asking Buildbucket for the build status
[16:46:46.240] Build 8932503820209341712: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[16:46:46.240] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "completed_ts": "1539708405192107",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1539697547673119",
    "failure_reason": "INFRA_FAILURE",
    "id": "8932503820209341712",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3582.0\"}}",
    "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\": [\"3809-a31bbe1\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-canary-e973df7c-us-central1-c-1f7c\"], \"pool\": [\"luci.infra.cron\"]}}, \"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1539697581578340",
    "status": "COMPLETED",
    "status_changed_ts": "1539708405579476",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9097933882388616944",
      "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:8932503820209341712",
      "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/8932503820209341712/+/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:9097933882388616944",
      "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:5e02f6736b5d25d7bb06d6ad547d0269e7eab96e",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4095f1a503c61910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1539708405580610",
    "url": "https://ci.chromium.org/p/infra/builds/b8932503820209341712",
    "utcnow_ts": "1539708406230960"
  }
}
[16:46:46.240] Invocation finished in 3h1m0.029241082s with status FAILED