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

Incoming tags

parent_buildername:publish_tarball
user_agent:recipe

Debug log (UTC)

[07:38:06.222] New invocation is queued and will start shortly
[07:38:06.222] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[07:38:07.395] Starting the invocation (attempt 1)
[07:38:07.429] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:38:07.429] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:38:07.429] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9098138207913240496",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"71.0.3578.7\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9098138207913240496",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[07:38:08.934] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1539502687604165",
    "id": "8932708145801437648",
    "lease_expiration_ts": "1539621488855105",
    "lease_key": "282388088",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"71.0.3578.7\"}}",
    "project": "infra",
    "result_details_json": "null",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1539502688856172",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9098138207913240496",
      "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:8932708145801437648",
      "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/8932708145801437648/+/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:9098138207913240496",
      "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:9d4e55f09f057cb6c6ed423e82fe5a8bae98ab2b",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:408a5451177ef510",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1539502688856261",
    "url": "https://ci.chromium.org/p/infra/builds/b8932708145801437648",
    "utcnow_ts": "1539502688923686"
  }
}
[07:38:08.934] Task URL: https://ci.chromium.org/p/infra/builds/b8932708145801437648
[07:38:08.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:2:0) after 1m0s
[07:38:10.810] Received PubSub notification, asking Buildbucket for the build status
[07:38:10.853] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:08.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:2:0)
[07:39:08.952] Timer tick, asking Buildbucket for the build status
[07:39:09.002] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:09.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:4:0) after 1m0s
[07:40:09.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:4:0)
[07:40:09.023] Timer tick, asking Buildbucket for the build status
[07:40:09.107] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:09.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:5:0) after 1m0s
[07:41:09.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:5:0)
[07:41:09.135] Timer tick, asking Buildbucket for the build status
[07:41:09.434] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:09.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:6:0) after 1m0s
[07:42:09.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:6:0)
[07:42:09.450] Timer tick, asking Buildbucket for the build status
[07:42:09.524] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:09.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:7:0) after 1m0s
[07:43:09.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:7:0)
[07:43:09.560] Timer tick, asking Buildbucket for the build status
[07:43:09.788] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:09.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:8:0) after 1m0s
[07:44:10.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:8:0)
[07:44:10.271] Timer tick, asking Buildbucket for the build status
[07:44:10.392] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:10.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:9:0) after 1m0s
[07:45:10.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:9:0)
[07:45:10.485] Timer tick, asking Buildbucket for the build status
[07:45:10.608] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:10.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:10:0) after 1m0s
[07:46:11.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:10:0)
[07:46:11.436] Timer tick, asking Buildbucket for the build status
[07:46:11.485] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:11.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:11:0) after 1m0s
[07:47:11.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:11:0)
[07:47:11.504] Timer tick, asking Buildbucket for the build status
[07:47:11.742] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:11.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:12:0) after 1m0s
[07:48:11.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:12:0)
[07:48:11.789] Timer tick, asking Buildbucket for the build status
[07:48:11.855] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:11.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:13:0) after 1m0s
[07:49:11.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:13:0)
[07:49:11.874] Timer tick, asking Buildbucket for the build status
[07:49:12.151] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:12.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:14:0) after 1m0s
[07:50:12.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:14:0)
[07:50:12.180] Timer tick, asking Buildbucket for the build status
[07:50:12.276] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:12.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:15:0) after 1m0s
[07:51:12.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:15:0)
[07:51:12.295] Timer tick, asking Buildbucket for the build status
[07:51:12.373] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:12.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:16:0) after 1m0s
[07:52:12.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:16:0)
[07:52:12.446] Timer tick, asking Buildbucket for the build status
[07:52:12.643] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:12.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:17:0) after 1m0s
[07:53:13.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:17:0)
[07:53:13.183] Timer tick, asking Buildbucket for the build status
[07:53:13.251] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:13.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:18:0) after 1m0s
[07:54:13.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:18:0)
[07:54:13.888] Timer tick, asking Buildbucket for the build status
[07:54:14.064] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:14.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:19:0) after 1m0s
[07:55:14.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:19:0)
[07:55:14.862] Timer tick, asking Buildbucket for the build status
[07:55:15.027] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:15.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:20:0) after 1m0s
[07:56:16.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:20:0)
[07:56:16.245] Timer tick, asking Buildbucket for the build status
[07:56:16.490] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:16.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:21:0) after 1m0s
[07:57:17.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:21:0)
[07:57:17.798] Timer tick, asking Buildbucket for the build status
[07:57:17.861] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:17.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:22:0) after 1m0s
[07:58:18.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:22:0)
[07:58:18.156] Timer tick, asking Buildbucket for the build status
[07:58:18.252] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:18.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:23:0) after 1m0s
[07:59:19.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:23:0)
[07:59:19.193] Timer tick, asking Buildbucket for the build status
[07:59:19.248] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:19.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:24:0) after 1m0s
[08:00:20.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:24:0)
[08:00:20.247] Timer tick, asking Buildbucket for the build status
[08:00:20.307] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:20.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:25:0) after 1m0s
[08:01:20.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:25:0)
[08:01:20.803] Timer tick, asking Buildbucket for the build status
[08:01:20.871] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:20.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:26:0) after 1m0s
[08:02:21.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:26:0)
[08:02:21.839] Timer tick, asking Buildbucket for the build status
[08:02:21.893] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:21.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:27:0) after 1m0s
[08:03:21.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:27:0)
[08:03:21.957] Timer tick, asking Buildbucket for the build status
[08:03:22.021] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:22.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:28:0) after 1m0s
[08:04:22.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:28:0)
[08:04:22.737] Timer tick, asking Buildbucket for the build status
[08:04:22.918] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:22.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:29:0) after 1m0s
[08:05:22.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:29:0)
[08:05:22.991] Timer tick, asking Buildbucket for the build status
[08:05:23.133] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:23.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:30:0) after 1m0s
[08:06:23.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:30:0)
[08:06:23.774] Timer tick, asking Buildbucket for the build status
[08:06:23.899] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:23.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:31:0) after 1m0s
[08:07:24.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:31:0)
[08:07:24.140] Timer tick, asking Buildbucket for the build status
[08:07:24.332] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:24.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:32:0) after 1m0s
[08:08:25.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:32:0)
[08:08:25.081] Timer tick, asking Buildbucket for the build status
[08:08:25.151] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:25.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:33:0) after 1m0s
[08:09:25.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:33:0)
[08:09:25.979] Timer tick, asking Buildbucket for the build status
[08:09:26.043] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:26.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:34:0) after 1m0s
[08:10:26.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:34:0)
[08:10:26.677] Timer tick, asking Buildbucket for the build status
[08:10:26.793] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:26.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:35:0) after 1m0s
[08:11:27.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:35:0)
[08:11:27.502] Timer tick, asking Buildbucket for the build status
[08:11:27.570] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:27.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:36:0) after 1m0s
[08:12:28.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:36:0)
[08:12:28.278] Timer tick, asking Buildbucket for the build status
[08:12:28.402] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:28.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:37:0) after 1m0s
[08:13:29.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:37:0)
[08:13:29.002] Timer tick, asking Buildbucket for the build status
[08:13:29.079] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:29.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:38:0) after 1m0s
[08:14:29.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:38:0)
[08:14:29.887] Timer tick, asking Buildbucket for the build status
[08:14:30.104] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:30.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:39:0) after 1m0s
[08:15:30.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:39:0)
[08:15:30.842] Timer tick, asking Buildbucket for the build status
[08:15:30.984] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:30.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:40:0) after 1m0s
[08:16:31.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:40:0)
[08:16:31.633] Timer tick, asking Buildbucket for the build status
[08:16:31.794] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:31.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:41:0) after 1m0s
[08:17:32.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:41:0)
[08:17:32.620] Timer tick, asking Buildbucket for the build status
[08:17:32.706] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:32.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:42:0) after 1m0s
[08:18:33.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:42:0)
[08:18:33.439] Timer tick, asking Buildbucket for the build status
[08:18:33.577] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:33.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:43:0) after 1m0s
[08:19:34.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:43:0)
[08:19:34.297] Timer tick, asking Buildbucket for the build status
[08:19:34.350] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:34.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:44:0) after 1m0s
[08:20:35.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:44:0)
[08:20:35.048] Timer tick, asking Buildbucket for the build status
[08:20:35.136] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:35.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:45:0) after 1m0s
[08:21:35.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:45:0)
[08:21:35.852] Timer tick, asking Buildbucket for the build status
[08:21:36.005] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:36.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:46:0) after 1m0s
[08:22:36.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:46:0)
[08:22:36.574] Timer tick, asking Buildbucket for the build status
[08:22:36.800] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:36.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:47:0) after 1m0s
[08:23:37.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:47:0)
[08:23:37.553] Timer tick, asking Buildbucket for the build status
[08:23:37.619] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:37.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:48:0) after 1m0s
[08:24:38.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:48:0)
[08:24:38.364] Timer tick, asking Buildbucket for the build status
[08:24:38.502] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:38.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:49:0) after 1m0s
[08:25:39.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:49:0)
[08:25:39.234] Timer tick, asking Buildbucket for the build status
[08:25:39.305] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:39.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:50:0) after 1m0s
[08:26:40.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:50:0)
[08:26:40.040] Timer tick, asking Buildbucket for the build status
[08:26:40.144] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:40.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:51:0) after 1m0s
[08:27:40.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:51:0)
[08:27:40.882] Timer tick, asking Buildbucket for the build status
[08:27:40.962] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:40.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:52:0) after 1m0s
[08:28:41.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:52:0)
[08:28:41.797] Timer tick, asking Buildbucket for the build status
[08:28:41.967] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:41.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:53:0) after 1m0s
[08:29:42.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:53:0)
[08:29:42.655] Timer tick, asking Buildbucket for the build status
[08:29:42.709] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:42.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:54:0) after 1m0s
[08:30:43.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:54:0)
[08:30:43.311] Timer tick, asking Buildbucket for the build status
[08:30:43.434] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:43.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:55:0) after 1m0s
[08:31:44.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:55:0)
[08:31:44.232] Timer tick, asking Buildbucket for the build status
[08:31:44.299] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:44.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:56:0) after 1m0s
[08:32:45.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:56:0)
[08:32:45.013] Timer tick, asking Buildbucket for the build status
[08:32:45.079] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:45.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:57:0) after 1m0s
[08:33:45.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:57:0)
[08:33:45.915] Timer tick, asking Buildbucket for the build status
[08:33:45.957] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:45.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:58:0) after 1m0s
[08:34:46.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:58:0)
[08:34:46.843] Timer tick, asking Buildbucket for the build status
[08:34:46.905] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:46.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:59:0) after 1m0s
[08:35:46.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:59:0)
[08:35:46.943] Timer tick, asking Buildbucket for the build status
[08:35:47.009] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:47.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:60:0) after 1m0s
[08:36:47.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:60:0)
[08:36:47.769] Timer tick, asking Buildbucket for the build status
[08:36:47.817] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:47.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:61:0) after 1m0s
[08:37:48.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:61:0)
[08:37:48.643] Timer tick, asking Buildbucket for the build status
[08:37:48.728] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:48.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:62:0) after 1m0s
[08:38:49.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:62:0)
[08:38:49.505] Timer tick, asking Buildbucket for the build status
[08:38:49.647] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:49.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:63:0) after 1m0s
[08:39:50.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:63:0)
[08:39:50.365] Timer tick, asking Buildbucket for the build status
[08:39:50.436] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:50.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:64:0) after 1m0s
[08:40:51.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:64:0)
[08:40:51.468] Timer tick, asking Buildbucket for the build status
[08:40:51.651] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:51.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:65:0) after 1m0s
[08:41:52.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:65:0)
[08:41:52.500] Timer tick, asking Buildbucket for the build status
[08:41:52.564] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:52.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:66:0) after 1m0s
[08:42:53.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:66:0)
[08:42:53.386] Timer tick, asking Buildbucket for the build status
[08:42:53.468] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:53.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:67:0) after 1m0s
[08:43:53.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:67:0)
[08:43:53.511] Timer tick, asking Buildbucket for the build status
[08:43:53.585] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:53.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:68:0) after 1m0s
[08:44:54.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:68:0)
[08:44:54.269] Timer tick, asking Buildbucket for the build status
[08:44:54.347] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:54.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:69:0) after 1m0s
[08:45:55.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:69:0)
[08:45:55.126] Timer tick, asking Buildbucket for the build status
[08:45:55.200] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:55.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:70:0) after 1m0s
[08:46:56.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:70:0)
[08:46:56.012] Timer tick, asking Buildbucket for the build status
[08:46:56.076] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:56.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:71:0) after 1m0s
[08:47:56.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:71:0)
[08:47:56.183] Timer tick, asking Buildbucket for the build status
[08:47:56.229] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:56.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:72:0) after 1m0s
[08:48:57.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:72:0)
[08:48:57.052] Timer tick, asking Buildbucket for the build status
[08:48:57.114] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:57.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:73:0) after 1m0s
[08:49:57.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:73:0)
[08:49:57.924] Timer tick, asking Buildbucket for the build status
[08:49:57.984] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:57.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:74:0) after 1m0s
[08:50:58.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:74:0)
[08:50:58.796] Timer tick, asking Buildbucket for the build status
[08:50:59.003] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:59.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:75:0) after 1m0s
[08:51:59.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:75:0)
[08:51:59.614] Timer tick, asking Buildbucket for the build status
[08:51:59.744] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:59.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:76:0) after 1m0s
[08:53:00.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:76:0)
[08:53:00.500] Timer tick, asking Buildbucket for the build status
[08:53:00.676] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:00.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:77:0) after 1m0s
[08:54:01.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:77:0)
[08:54:01.446] Timer tick, asking Buildbucket for the build status
[08:54:01.559] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:01.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:78:0) after 1m0s
[08:55:02.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:78:0)
[08:55:02.277] Timer tick, asking Buildbucket for the build status
[08:55:02.465] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:02.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:79:0) after 1m0s
[08:56:03.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:79:0)
[08:56:03.096] Timer tick, asking Buildbucket for the build status
[08:56:03.160] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:03.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:80:0) after 1m0s
[08:57:03.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:80:0)
[08:57:03.944] Timer tick, asking Buildbucket for the build status
[08:57:04.059] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:04.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:81:0) after 1m0s
[08:58:04.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:81:0)
[08:58:04.841] Timer tick, asking Buildbucket for the build status
[08:58:04.911] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:04.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:82:0) after 1m0s
[08:59:05.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:82:0)
[08:59:05.371] Timer tick, asking Buildbucket for the build status
[08:59:05.449] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:05.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:83:0) after 1m0s
[09:00:06.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:83:0)
[09:00:06.102] Timer tick, asking Buildbucket for the build status
[09:00:06.177] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:06.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:84:0) after 1m0s
[09:01:06.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:84:0)
[09:01:06.251] Timer tick, asking Buildbucket for the build status
[09:01:06.311] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:06.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:85:0) after 1m0s
[09:02:06.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:85:0)
[09:02:06.535] Timer tick, asking Buildbucket for the build status
[09:02:06.709] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:06.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:86:0) after 1m0s
[09:03:07.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:86:0)
[09:03:07.445] Timer tick, asking Buildbucket for the build status
[09:03:07.531] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:07.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:87:0) after 1m0s
[09:04:08.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:87:0)
[09:04:08.277] Timer tick, asking Buildbucket for the build status
[09:04:08.597] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:08.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:88:0) after 1m0s
[09:05:09.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:88:0)
[09:05:09.300] Timer tick, asking Buildbucket for the build status
[09:05:09.372] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:09.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:89:0) after 1m0s
[09:06:10.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:89:0)
[09:06:10.205] Timer tick, asking Buildbucket for the build status
[09:06:10.273] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:10.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:90:0) after 1m0s
[09:07:11.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:90:0)
[09:07:11.025] Timer tick, asking Buildbucket for the build status
[09:07:11.110] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:11.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:91:0) after 1m0s
[09:08:11.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:91:0)
[09:08:11.828] Timer tick, asking Buildbucket for the build status
[09:08:11.896] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:11.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:92:0) after 1m0s
[09:09:12.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:92:0)
[09:09:12.590] Timer tick, asking Buildbucket for the build status
[09:09:12.737] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:12.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:93:0) after 1m0s
[09:10:13.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:93:0)
[09:10:13.482] Timer tick, asking Buildbucket for the build status
[09:10:13.631] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:13.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:94:0) after 1m0s
[09:11:14.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:94:0)
[09:11:14.328] Timer tick, asking Buildbucket for the build status
[09:11:14.584] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:14.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:95:0) after 1m0s
[09:12:15.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:95:0)
[09:12:15.254] Timer tick, asking Buildbucket for the build status
[09:12:15.332] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:15.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:96:0) after 1m0s
[09:13:16.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:96:0)
[09:13:16.000] Timer tick, asking Buildbucket for the build status
[09:13:16.063] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:16.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:97:0) after 1m0s
[09:14:16.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:97:0)
[09:14:16.789] Timer tick, asking Buildbucket for the build status
[09:14:16.835] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:16.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:98:0) after 1m0s
[09:15:17.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:98:0)
[09:15:17.669] Timer tick, asking Buildbucket for the build status
[09:15:17.735] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:17.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:99:0) after 1m0s
[09:16:18.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:99:0)
[09:16:18.477] Timer tick, asking Buildbucket for the build status
[09:16:18.643] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:18.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:100:0) after 1m0s
[09:17:19.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:100:0)
[09:17:19.415] Timer tick, asking Buildbucket for the build status
[09:17:19.458] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:19.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:101:0) after 1m0s
[09:18:20.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:101:0)
[09:18:20.267] Timer tick, asking Buildbucket for the build status
[09:18:20.370] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:20.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:102:0) after 1m0s
[09:19:21.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:102:0)
[09:19:21.110] Timer tick, asking Buildbucket for the build status
[09:19:21.176] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:21.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:103:0) after 1m0s
[09:20:21.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:103:0)
[09:20:21.222] Timer tick, asking Buildbucket for the build status
[09:20:21.329] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:21.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:104:0) after 1m0s
[09:21:21.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:104:0)
[09:21:21.871] Timer tick, asking Buildbucket for the build status
[09:21:21.936] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:21.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:105:0) after 1m0s
[09:22:22.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:105:0)
[09:22:22.676] Timer tick, asking Buildbucket for the build status
[09:22:22.767] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:22.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:106:0) after 1m0s
[09:23:23.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:106:0)
[09:23:23.534] Timer tick, asking Buildbucket for the build status
[09:23:23.648] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:23.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:107:0) after 1m0s
[09:24:23.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:107:0)
[09:24:23.675] Timer tick, asking Buildbucket for the build status
[09:24:23.733] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:23.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:108:0) after 1m0s
[09:25:24.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:108:0)
[09:25:24.508] Timer tick, asking Buildbucket for the build status
[09:25:24.674] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:24.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:109:0) after 1m0s
[09:26:25.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:109:0)
[09:26:25.348] Timer tick, asking Buildbucket for the build status
[09:26:25.454] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:25.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:110:0) after 1m0s
[09:27:26.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:110:0)
[09:27:26.428] Timer tick, asking Buildbucket for the build status
[09:27:26.535] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:26.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:111:0) after 1m0s
[09:28:26.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:111:0)
[09:28:26.554] Timer tick, asking Buildbucket for the build status
[09:28:26.684] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:26.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:112:0) after 1m0s
[09:29:26.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:112:0)
[09:29:26.823] Timer tick, asking Buildbucket for the build status
[09:29:26.879] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:26.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:113:0) after 1m0s
[09:30:26.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:113:0)
[09:30:26.939] Timer tick, asking Buildbucket for the build status
[09:30:27.027] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:27.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:114:0) after 1m0s
[09:31:27.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:114:0)
[09:31:27.046] Timer tick, asking Buildbucket for the build status
[09:31:27.115] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:27.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:115:0) after 1m0s
[09:32:27.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:115:0)
[09:32:27.668] Timer tick, asking Buildbucket for the build status
[09:32:27.741] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:27.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:116:0) after 1m0s
[09:33:28.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:116:0)
[09:33:28.485] Timer tick, asking Buildbucket for the build status
[09:33:28.571] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:28.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:117:0) after 1m0s
[09:34:29.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:117:0)
[09:34:29.404] Timer tick, asking Buildbucket for the build status
[09:34:29.485] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:29.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:118:0) after 1m0s
[09:35:30.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:118:0)
[09:35:30.313] Timer tick, asking Buildbucket for the build status
[09:35:30.405] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:30.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:119:0) after 1m0s
[09:36:30.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:119:0)
[09:36:30.431] Timer tick, asking Buildbucket for the build status
[09:36:30.706] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:30.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:120:0) after 1m0s
[09:37:31.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:120:0)
[09:37:31.400] Timer tick, asking Buildbucket for the build status
[09:37:31.614] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:31.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:121:0) after 1m0s
[09:38:32.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:121:0)
[09:38:32.308] Timer tick, asking Buildbucket for the build status
[09:38:32.379] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:32.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:122:0) after 1m0s
[09:39:32.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:122:0)
[09:39:32.962] Timer tick, asking Buildbucket for the build status
[09:39:33.020] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:33.020] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:123:0) after 1m0s
[09:40:33.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:123:0)
[09:40:33.781] Timer tick, asking Buildbucket for the build status
[09:40:33.848] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:33.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:124:0) after 1m0s
[09:41:34.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:124:0)
[09:41:34.954] Timer tick, asking Buildbucket for the build status
[09:41:35.128] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:35.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:125:0) after 1m0s
[09:42:35.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:125:0)
[09:42:35.932] Timer tick, asking Buildbucket for the build status
[09:42:35.994] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:35.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:126:0) after 1m0s
[09:43:36.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:126:0)
[09:43:36.727] Timer tick, asking Buildbucket for the build status
[09:43:36.793] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:36.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:127:0) after 1m0s
[09:44:37.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:127:0)
[09:44:37.570] Timer tick, asking Buildbucket for the build status
[09:44:37.623] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:37.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:128:0) after 1m0s
[09:45:38.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:128:0)
[09:45:38.266] Timer tick, asking Buildbucket for the build status
[09:45:38.334] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:38.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:129:0) after 1m0s
[09:46:38.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:129:0)
[09:46:38.424] Timer tick, asking Buildbucket for the build status
[09:46:38.616] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:38.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:130:0) after 1m0s
[09:47:39.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:130:0)
[09:47:39.055] Timer tick, asking Buildbucket for the build status
[09:47:39.223] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:39.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:131:0) after 1m0s
[09:48:39.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:131:0)
[09:48:39.986] Timer tick, asking Buildbucket for the build status
[09:48:40.077] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:40.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:132:0) after 1m0s
[09:49:40.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:132:0)
[09:49:40.955] Timer tick, asking Buildbucket for the build status
[09:49:41.029] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:41.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:133:0) after 1m0s
[09:50:41.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:133:0)
[09:50:41.810] Timer tick, asking Buildbucket for the build status
[09:50:41.869] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:41.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:134:0) after 1m0s
[09:51:41.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:134:0)
[09:51:41.893] Timer tick, asking Buildbucket for the build status
[09:51:42.006] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:42.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:135:0) after 1m0s
[09:52:42.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:135:0)
[09:52:42.050] Timer tick, asking Buildbucket for the build status
[09:52:42.119] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:42.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:136:0) after 1m0s
[09:53:42.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:136:0)
[09:53:42.199] Timer tick, asking Buildbucket for the build status
[09:53:42.306] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:42.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:137:0) after 1m0s
[09:54:42.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:137:0)
[09:54:42.399] Timer tick, asking Buildbucket for the build status
[09:54:42.466] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:42.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:138:0) after 1m0s
[09:55:43.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:138:0)
[09:55:43.218] Timer tick, asking Buildbucket for the build status
[09:55:43.305] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:43.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:139:0) after 1m0s
[09:56:44.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:139:0)
[09:56:44.148] Timer tick, asking Buildbucket for the build status
[09:56:44.233] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:44.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:140:0) after 1m0s
[09:57:44.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:140:0)
[09:57:44.254] Timer tick, asking Buildbucket for the build status
[09:57:44.309] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:44.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:141:0) after 1m0s
[09:58:44.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:141:0)
[09:58:44.354] Timer tick, asking Buildbucket for the build status
[09:58:44.472] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:44.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:142:0) after 1m0s
[09:59:44.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:142:0)
[09:59:44.541] Timer tick, asking Buildbucket for the build status
[09:59:44.602] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:44.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:143:0) after 1m0s
[10:00:44.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:143:0)
[10:00:44.645] Timer tick, asking Buildbucket for the build status
[10:00:44.802] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:44.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:144:0) after 1m0s
[10:01:44.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:144:0)
[10:01:44.821] Timer tick, asking Buildbucket for the build status
[10:01:45.005] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:45.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:145:0) after 1m0s
[10:02:45.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:145:0)
[10:02:45.029] Timer tick, asking Buildbucket for the build status
[10:02:45.093] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:45.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:146:0) after 1m0s
[10:03:45.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:146:0)
[10:03:45.238] Timer tick, asking Buildbucket for the build status
[10:03:45.296] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:45.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:147:0) after 1m0s
[10:04:45.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:147:0)
[10:04:45.324] Timer tick, asking Buildbucket for the build status
[10:04:45.389] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:45.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:148:0) after 1m0s
[10:05:45.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:148:0)
[10:05:45.431] Timer tick, asking Buildbucket for the build status
[10:05:45.583] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:45.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:149:0) after 1m0s
[10:06:46.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:149:0)
[10:06:46.336] Timer tick, asking Buildbucket for the build status
[10:06:46.407] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:46.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:150:0) after 1m0s
[10:07:46.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:150:0)
[10:07:46.440] Timer tick, asking Buildbucket for the build status
[10:07:46.621] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:46.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:151:0) after 1m0s
[10:08:46.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:151:0)
[10:08:46.694] Timer tick, asking Buildbucket for the build status
[10:08:46.872] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:46.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:152:0) after 1m0s
[10:09:46.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:152:0)
[10:09:46.892] Timer tick, asking Buildbucket for the build status
[10:09:47.103] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:47.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:153:0) after 1m0s
[10:10:47.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:153:0)
[10:10:47.140] Timer tick, asking Buildbucket for the build status
[10:10:47.302] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:47.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:154:0) after 1m0s
[10:11:47.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:154:0)
[10:11:47.326] Timer tick, asking Buildbucket for the build status
[10:11:47.542] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:47.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:155:0) after 1m0s
[10:12:47.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:155:0)
[10:12:47.925] Timer tick, asking Buildbucket for the build status
[10:12:48.056] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:48.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:156:0) after 1m0s
[10:13:48.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:156:0)
[10:13:48.600] Timer tick, asking Buildbucket for the build status
[10:13:48.715] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:48.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:157:0) after 1m0s
[10:14:49.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:157:0)
[10:14:49.492] Timer tick, asking Buildbucket for the build status
[10:14:49.528] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:49.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:158:0) after 1m0s
[10:15:50.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:158:0)
[10:15:50.280] Timer tick, asking Buildbucket for the build status
[10:15:50.363] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:50.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:159:0) after 1m0s
[10:16:50.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:159:0)
[10:16:50.386] Timer tick, asking Buildbucket for the build status
[10:16:50.511] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:50.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:160:0) after 1m0s
[10:17:50.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:160:0)
[10:17:50.572] Timer tick, asking Buildbucket for the build status
[10:17:50.629] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:50.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:161:0) after 1m0s
[10:18:51.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:161:0)
[10:18:51.348] Timer tick, asking Buildbucket for the build status
[10:18:51.426] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:51.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:162:0) after 1m0s
[10:19:52.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:162:0)
[10:19:52.200] Timer tick, asking Buildbucket for the build status
[10:19:52.255] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:52.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:163:0) after 1m0s
[10:20:53.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:163:0)
[10:20:53.034] Timer tick, asking Buildbucket for the build status
[10:20:53.110] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:53.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:164:0) after 1m0s
[10:21:53.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:164:0)
[10:21:53.886] Timer tick, asking Buildbucket for the build status
[10:21:54.011] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:54.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:165:0) after 1m0s
[10:22:54.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:165:0)
[10:22:54.783] Timer tick, asking Buildbucket for the build status
[10:22:54.846] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:54.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:166:0) after 1m0s
[10:23:55.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:166:0)
[10:23:55.550] Timer tick, asking Buildbucket for the build status
[10:23:55.616] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:55.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:167:0) after 1m0s
[10:24:56.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:167:0)
[10:24:56.498] Timer tick, asking Buildbucket for the build status
[10:24:56.634] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:56.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:168:0) after 1m0s
[10:25:56.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:168:0)
[10:25:56.665] Timer tick, asking Buildbucket for the build status
[10:25:56.699] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:56.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:169:0) after 1m0s
[10:26:56.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:169:0)
[10:26:56.723] Timer tick, asking Buildbucket for the build status
[10:26:56.786] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:56.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:170:0) after 1m0s
[10:27:57.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:170:0)
[10:27:57.397] Timer tick, asking Buildbucket for the build status
[10:27:57.453] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:57.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:171:0) after 1m0s
[10:28:58.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:171:0)
[10:28:58.243] Timer tick, asking Buildbucket for the build status
[10:28:58.328] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:58.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:172:0) after 1m0s
[10:29:59.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:172:0)
[10:29:59.093] Timer tick, asking Buildbucket for the build status
[10:29:59.165] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:59.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:173:0) after 1m0s
[10:30:59.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:173:0)
[10:30:59.968] Timer tick, asking Buildbucket for the build status
[10:31:00.132] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:00.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:174:0) after 1m0s
[10:32:00.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:174:0)
[10:32:00.159] Timer tick, asking Buildbucket for the build status
[10:32:00.517] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:00.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:175:0) after 1m0s
[10:33:01.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:175:0)
[10:33:01.165] Timer tick, asking Buildbucket for the build status
[10:33:01.231] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:01.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:176:0) after 1m0s
[10:34:01.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:176:0)
[10:34:01.955] Timer tick, asking Buildbucket for the build status
[10:34:02.032] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:02.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:177:0) after 1m0s
[10:35:02.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:177:0)
[10:35:02.861] Timer tick, asking Buildbucket for the build status
[10:35:02.935] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:02.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:178:0) after 1m0s
[10:36:03.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:178:0)
[10:36:03.787] Timer tick, asking Buildbucket for the build status
[10:36:03.841] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:03.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:179:0) after 1m0s
[10:37:04.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:179:0)
[10:37:04.737] Timer tick, asking Buildbucket for the build status
[10:37:04.897] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:04.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:180:0) after 1m0s
[10:38:05.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:180:0)
[10:38:05.414] Timer tick, asking Buildbucket for the build status
[10:38:05.639] Build 8932708145801437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:05.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098138207913240496:181:0) after 1m0s
[10:38:37.766] Received PubSub notification, asking Buildbucket for the build status
[10:38:37.898] Build 8932708145801437648: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[10:38:37.898] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "completed_ts": "1539513516827522",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1539502687604165",
    "failure_reason": "INFRA_FAILURE",
    "id": "8932708145801437648",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"71.0.3578.7\"}}",
    "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-west\", \"us-west1\", \"us-west1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-b-m9jz\"], \"pool\": [\"luci.infra.cron\"]}}, \"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1539502689629260",
    "status": "COMPLETED",
    "status_changed_ts": "1539513517266326",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9098138207913240496",
      "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:8932708145801437648",
      "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/8932708145801437648/+/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:9098138207913240496",
      "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:9d4e55f09f057cb6c6ed423e82fe5a8bae98ab2b",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:408a5451177ef510",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1539513517267259",
    "url": "https://ci.chromium.org/p/infra/builds/b8932708145801437648",
    "utcnow_ts": "1539513517872972"
  }
}
[10:38:37.898] Invocation finished in 3h0m31.692755261s with status FAILED