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

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

{
  "version": "70.0.3538.45"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[22:39:22.164] New invocation is queued and will start shortly
[22:39:22.164] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:39:23.452] Starting the invocation (attempt 1)
[22:39:23.487] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:39:23.525] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:39:23.525] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9099078071810510720",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"70.0.3538.45\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9099078071810510720",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[22:39:25.266] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1538606363609345",
    "id": "8933648009630098784",
    "lease_expiration_ts": "1538725165011818",
    "lease_key": "1486075745",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"70.0.3538.45\"}}",
    "project": "infra",
    "result_details_json": "null",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1538606365013172",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9099078071810510720",
      "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:8933648009630098784",
      "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/8933648009630098784/+/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:9099078071810510720",
      "swarming_tag:scheduler_job_id:infra/Build From Tarball",
      "swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d431f79a1f9890266786724a5b445a445f332a3a",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4054e77ec45da410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1538606365013294",
    "url": "https://ci.chromium.org/p/infra/builds/b8933648009630098784",
    "utcnow_ts": "1538606365193951"
  }
}
[22:39:25.266] Task URL: https://ci.chromium.org/p/infra/builds/b8933648009630098784
[22:39:25.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:2:0) after 1m0s
[22:39:53.244] Received PubSub notification, asking Buildbucket for the build status
[22:39:53.319] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:25.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:2:0)
[22:40:25.288] Timer tick, asking Buildbucket for the build status
[22:40:25.402] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:25.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:4:0) after 1m0s
[22:41:25.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:4:0)
[22:41:25.417] Timer tick, asking Buildbucket for the build status
[22:41:25.467] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:25.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:5:0) after 1m0s
[22:42:25.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:5:0)
[22:42:25.508] Timer tick, asking Buildbucket for the build status
[22:42:25.567] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:25.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:6:0) after 1m0s
[22:43:25.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:6:0)
[22:43:25.585] Timer tick, asking Buildbucket for the build status
[22:43:25.633] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:25.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:7:0) after 1m0s
[22:44:25.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:7:0)
[22:44:25.655] Timer tick, asking Buildbucket for the build status
[22:44:25.900] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:25.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:8:0) after 1m0s
[22:45:25.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:8:0)
[22:45:25.923] Timer tick, asking Buildbucket for the build status
[22:45:25.973] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:25.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:9:0) after 1m0s
[22:46:25.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:9:0)
[22:46:25.987] Timer tick, asking Buildbucket for the build status
[22:46:26.045] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:26.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:10:0) after 1m0s
[22:47:26.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:10:0)
[22:47:26.067] Timer tick, asking Buildbucket for the build status
[22:47:26.158] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:26.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:11:0) after 1m0s
[22:48:26.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:11:0)
[22:48:26.177] Timer tick, asking Buildbucket for the build status
[22:48:26.231] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:26.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:12:0) after 1m0s
[22:49:26.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:12:0)
[22:49:26.248] Timer tick, asking Buildbucket for the build status
[22:49:26.352] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:26.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:13:0) after 1m0s
[22:50:26.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:13:0)
[22:50:26.369] Timer tick, asking Buildbucket for the build status
[22:50:26.482] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:26.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:14:0) after 1m0s
[22:51:26.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:14:0)
[22:51:26.503] Timer tick, asking Buildbucket for the build status
[22:51:26.553] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:26.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:15:0) after 1m0s
[22:52:26.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:15:0)
[22:52:26.575] Timer tick, asking Buildbucket for the build status
[22:52:26.642] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:26.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:16:0) after 1m0s
[22:53:26.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:16:0)
[22:53:26.660] Timer tick, asking Buildbucket for the build status
[22:53:26.734] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:26.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:17:0) after 1m0s
[22:54:26.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:17:0)
[22:54:26.760] Timer tick, asking Buildbucket for the build status
[22:54:26.838] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:26.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:18:0) after 1m0s
[22:55:26.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:18:0)
[22:55:26.867] Timer tick, asking Buildbucket for the build status
[22:55:26.939] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:26.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:19:0) after 1m0s
[22:56:26.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:19:0)
[22:56:26.959] Timer tick, asking Buildbucket for the build status
[22:56:27.051] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:27.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:20:0) after 1m0s
[22:57:27.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:20:0)
[22:57:27.081] Timer tick, asking Buildbucket for the build status
[22:57:27.144] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:27.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:21:0) after 1m0s
[22:58:27.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:21:0)
[22:58:27.167] Timer tick, asking Buildbucket for the build status
[22:58:27.252] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:27.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:22:0) after 1m0s
[22:59:27.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:22:0)
[22:59:27.278] Timer tick, asking Buildbucket for the build status
[22:59:27.317] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:27.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:23:0) after 1m0s
[23:00:27.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:23:0)
[23:00:27.421] Timer tick, asking Buildbucket for the build status
[23:00:27.657] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:27.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:24:0) after 1m0s
[23:01:27.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:24:0)
[23:01:27.679] Timer tick, asking Buildbucket for the build status
[23:01:27.729] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:27.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:25:0) after 1m0s
[23:02:27.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:25:0)
[23:02:27.758] Timer tick, asking Buildbucket for the build status
[23:02:27.824] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:27.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:26:0) after 1m0s
[23:03:27.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:26:0)
[23:03:27.848] Timer tick, asking Buildbucket for the build status
[23:03:27.892] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:27.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:27:0) after 1m0s
[23:04:27.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:27:0)
[23:04:27.913] Timer tick, asking Buildbucket for the build status
[23:04:28.094] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:28.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:28:0) after 1m0s
[23:05:28.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:28:0)
[23:05:28.112] Timer tick, asking Buildbucket for the build status
[23:05:28.244] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:28.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:29:0) after 1m0s
[23:06:28.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:29:0)
[23:06:28.335] Timer tick, asking Buildbucket for the build status
[23:06:28.628] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:28.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:30:0) after 1m0s
[23:07:28.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:30:0)
[23:07:28.644] Timer tick, asking Buildbucket for the build status
[23:07:28.677] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:28.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:31:0) after 1m0s
[23:08:28.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:31:0)
[23:08:28.693] Timer tick, asking Buildbucket for the build status
[23:08:28.758] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:28.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:32:0) after 1m0s
[23:09:28.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:32:0)
[23:09:28.796] Timer tick, asking Buildbucket for the build status
[23:09:28.849] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:28.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:33:0) after 1m0s
[23:10:28.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:33:0)
[23:10:28.866] Timer tick, asking Buildbucket for the build status
[23:10:28.977] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:28.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:34:0) after 1m0s
[23:11:29.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:34:0)
[23:11:29.035] Timer tick, asking Buildbucket for the build status
[23:11:29.065] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:29.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:35:0) after 1m0s
[23:12:29.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:35:0)
[23:12:29.087] Timer tick, asking Buildbucket for the build status
[23:12:29.204] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:29.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:36:0) after 1m0s
[23:13:29.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:36:0)
[23:13:29.261] Timer tick, asking Buildbucket for the build status
[23:13:29.361] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:29.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:37:0) after 1m0s
[23:14:29.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:37:0)
[23:14:29.378] Timer tick, asking Buildbucket for the build status
[23:14:29.443] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:29.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:38:0) after 1m0s
[23:15:29.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:38:0)
[23:15:29.523] Timer tick, asking Buildbucket for the build status
[23:15:29.571] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:29.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:39:0) after 1m0s
[23:16:29.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:39:0)
[23:16:29.699] Timer tick, asking Buildbucket for the build status
[23:16:29.772] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:29.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:40:0) after 1m0s
[23:17:29.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:40:0)
[23:17:29.811] Timer tick, asking Buildbucket for the build status
[23:17:29.967] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:29.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:41:0) after 1m0s
[23:18:29.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:41:0)
[23:18:29.986] Timer tick, asking Buildbucket for the build status
[23:18:30.117] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:30.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:42:0) after 1m0s
[23:19:30.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:42:0)
[23:19:30.179] Timer tick, asking Buildbucket for the build status
[23:19:30.414] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:30.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:43:0) after 1m0s
[23:20:30.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:43:0)
[23:20:30.431] Timer tick, asking Buildbucket for the build status
[23:20:30.504] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:30.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:44:0) after 1m0s
[23:21:30.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:44:0)
[23:21:30.546] Timer tick, asking Buildbucket for the build status
[23:21:30.592] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:30.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:45:0) after 1m0s
[23:22:30.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:45:0)
[23:22:30.625] Timer tick, asking Buildbucket for the build status
[23:22:30.696] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:30.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:46:0) after 1m0s
[23:23:30.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:46:0)
[23:23:30.710] Timer tick, asking Buildbucket for the build status
[23:23:30.743] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:30.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:47:0) after 1m0s
[23:24:30.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:47:0)
[23:24:30.761] Timer tick, asking Buildbucket for the build status
[23:24:30.975] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:30.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:48:0) after 1m0s
[23:25:31.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:48:0)
[23:25:31.000] Timer tick, asking Buildbucket for the build status
[23:25:31.179] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:31.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:49:0) after 1m0s
[23:26:31.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:49:0)
[23:26:31.196] Timer tick, asking Buildbucket for the build status
[23:26:31.406] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:31.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:50:0) after 1m0s
[23:27:31.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:50:0)
[23:27:31.438] Timer tick, asking Buildbucket for the build status
[23:27:31.593] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:31.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:51:0) after 1m0s
[23:28:31.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:51:0)
[23:28:31.608] Timer tick, asking Buildbucket for the build status
[23:28:31.681] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:31.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:52:0) after 1m0s
[23:29:31.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:52:0)
[23:29:31.738] Timer tick, asking Buildbucket for the build status
[23:29:31.797] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:31.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:53:0) after 1m0s
[23:30:31.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:53:0)
[23:30:31.824] Timer tick, asking Buildbucket for the build status
[23:30:32.089] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:32.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:54:0) after 1m0s
[23:31:32.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:54:0)
[23:31:32.374] Timer tick, asking Buildbucket for the build status
[23:31:32.491] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:32.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:55:0) after 1m0s
[23:34:41.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:55:0)
[23:34:41.220] Timer tick, asking Buildbucket for the build status
[23:34:41.291] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:41.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:56:0) after 1m0s
[23:35:41.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:56:0)
[23:35:41.314] Timer tick, asking Buildbucket for the build status
[23:35:41.355] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:41.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:57:0) after 1m0s
[23:36:41.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:57:0)
[23:36:41.386] Timer tick, asking Buildbucket for the build status
[23:36:41.453] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:41.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:58:0) after 1m0s
[23:37:41.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:58:0)
[23:37:41.479] Timer tick, asking Buildbucket for the build status
[23:37:41.552] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:41.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:59:0) after 1m0s
[23:38:41.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:59:0)
[23:38:41.596] Timer tick, asking Buildbucket for the build status
[23:38:41.639] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:41.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:60:0) after 1m0s
[23:39:41.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:60:0)
[23:39:41.660] Timer tick, asking Buildbucket for the build status
[23:39:41.912] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:41.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:61:0) after 1m0s
[23:40:41.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:61:0)
[23:40:41.980] Timer tick, asking Buildbucket for the build status
[23:40:42.025] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:42.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:62:0) after 1m0s
[23:41:42.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:62:0)
[23:41:42.046] Timer tick, asking Buildbucket for the build status
[23:41:42.112] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:42.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:63:0) after 1m0s
[23:42:42.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:63:0)
[23:42:42.153] Timer tick, asking Buildbucket for the build status
[23:42:42.211] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:42.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:64:0) after 1m0s
[23:43:42.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:64:0)
[23:43:42.234] Timer tick, asking Buildbucket for the build status
[23:43:42.396] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:42.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:65:0) after 1m0s
[23:44:42.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:65:0)
[23:44:42.414] Timer tick, asking Buildbucket for the build status
[23:44:42.495] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:42.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:66:0) after 1m0s
[23:45:42.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:66:0)
[23:45:42.525] Timer tick, asking Buildbucket for the build status
[23:45:42.819] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:42.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:67:0) after 1m0s
[23:46:42.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:67:0)
[23:46:42.894] Timer tick, asking Buildbucket for the build status
[23:46:43.098] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:43.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:68:0) after 1m0s
[23:47:43.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:68:0)
[23:47:43.119] Timer tick, asking Buildbucket for the build status
[23:47:43.163] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:43.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:69:0) after 1m0s
[23:48:43.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:69:0)
[23:48:43.207] Timer tick, asking Buildbucket for the build status
[23:48:43.326] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:43.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:70:0) after 1m0s
[23:49:43.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:70:0)
[23:49:43.362] Timer tick, asking Buildbucket for the build status
[23:49:43.479] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:43.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:71:0) after 1m0s
[23:50:43.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:71:0)
[23:50:43.494] Timer tick, asking Buildbucket for the build status
[23:50:43.542] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:43.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:72:0) after 1m0s
[23:51:43.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:72:0)
[23:51:43.562] Timer tick, asking Buildbucket for the build status
[23:51:43.696] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:43.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:73:0) after 1m0s
[23:52:43.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:73:0)
[23:52:43.729] Timer tick, asking Buildbucket for the build status
[23:52:43.800] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:43.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:74:0) after 1m0s
[23:53:43.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:74:0)
[23:53:43.819] Timer tick, asking Buildbucket for the build status
[23:53:43.874] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:43.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:75:0) after 1m0s
[23:54:43.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:75:0)
[23:54:43.896] Timer tick, asking Buildbucket for the build status
[23:54:43.963] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:43.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:76:0) after 1m0s
[23:55:43.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:76:0)
[23:55:43.983] Timer tick, asking Buildbucket for the build status
[23:55:44.030] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:44.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:77:0) after 1m0s
[23:56:44.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:77:0)
[23:56:44.129] Timer tick, asking Buildbucket for the build status
[23:56:44.209] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:44.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:78:0) after 1m0s
[23:57:44.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:78:0)
[23:57:44.233] Timer tick, asking Buildbucket for the build status
[23:57:53.318] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:53.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:79:0) after 1m0s
[23:58:53.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:79:0)
[23:58:53.348] Timer tick, asking Buildbucket for the build status
[23:58:53.384] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:53.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:80:0) after 1m0s
[23:59:53.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:80:0)
[23:59:53.417] Timer tick, asking Buildbucket for the build status
[23:59:53.489] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:53.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:81:0) after 1m0s
[00:00:53.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:81:0)
[00:00:53.517] Timer tick, asking Buildbucket for the build status
[00:00:53.578] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:53.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:82:0) after 1m0s
[00:01:53.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:82:0)
[00:01:53.604] Timer tick, asking Buildbucket for the build status
[00:01:53.686] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:53.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:83:0) after 1m0s
[00:02:53.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:83:0)
[00:02:53.705] Timer tick, asking Buildbucket for the build status
[00:02:53.755] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:53.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:84:0) after 1m0s
[00:03:53.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:84:0)
[00:03:53.771] Timer tick, asking Buildbucket for the build status
[00:03:53.815] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:53.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:85:0) after 1m0s
[00:04:53.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:85:0)
[00:04:53.862] Timer tick, asking Buildbucket for the build status
[00:04:53.926] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:53.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:86:0) after 1m0s
[00:05:54.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:86:0)
[00:05:54.026] Timer tick, asking Buildbucket for the build status
[00:05:54.196] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:54.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:87:0) after 1m0s
[00:06:54.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:87:0)
[00:06:54.220] Timer tick, asking Buildbucket for the build status
[00:06:54.288] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:54.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:88:0) after 1m0s
[00:07:54.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:88:0)
[00:07:54.335] Timer tick, asking Buildbucket for the build status
[00:07:54.399] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:54.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:89:0) after 1m0s
[00:08:54.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:89:0)
[00:08:54.424] Timer tick, asking Buildbucket for the build status
[00:08:54.494] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:54.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:90:0) after 1m0s
[00:09:54.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:90:0)
[00:09:54.533] Timer tick, asking Buildbucket for the build status
[00:09:54.604] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:54.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:91:0) after 1m0s
[00:10:54.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:91:0)
[00:10:54.625] Timer tick, asking Buildbucket for the build status
[00:10:54.660] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:54.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:92:0) after 1m0s
[00:11:54.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:92:0)
[00:11:54.688] Timer tick, asking Buildbucket for the build status
[00:11:54.728] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:54.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:93:0) after 1m0s
[00:12:54.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:93:0)
[00:12:54.797] Timer tick, asking Buildbucket for the build status
[00:12:54.909] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:54.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:94:0) after 1m0s
[00:13:54.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:94:0)
[00:13:54.930] Timer tick, asking Buildbucket for the build status
[00:13:54.998] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:54.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:95:0) after 1m0s
[00:14:55.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:95:0)
[00:14:55.041] Timer tick, asking Buildbucket for the build status
[00:14:55.095] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:55.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:96:0) after 1m0s
[00:15:55.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:96:0)
[00:15:55.135] Timer tick, asking Buildbucket for the build status
[00:15:55.263] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:55.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:97:0) after 1m0s
[00:16:55.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:97:0)
[00:16:55.282] Timer tick, asking Buildbucket for the build status
[00:16:55.317] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:55.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:98:0) after 1m0s
[00:17:55.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:98:0)
[00:17:55.343] Timer tick, asking Buildbucket for the build status
[00:17:55.412] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:55.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:99:0) after 1m0s
[00:18:55.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:99:0)
[00:18:55.455] Timer tick, asking Buildbucket for the build status
[00:18:55.534] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:55.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:100:0) after 1m0s
[00:19:55.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:100:0)
[00:19:55.552] Timer tick, asking Buildbucket for the build status
[00:19:55.584] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:55.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:101:0) after 1m0s
[00:20:55.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:101:0)
[00:20:55.608] Timer tick, asking Buildbucket for the build status
[00:20:55.664] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:55.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:102:0) after 1m0s
[00:21:55.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:102:0)
[00:21:55.694] Timer tick, asking Buildbucket for the build status
[00:21:55.766] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:55.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:103:0) after 1m0s
[00:22:56.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:103:0)
[00:22:56.100] Timer tick, asking Buildbucket for the build status
[00:22:56.142] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:56.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:104:0) after 1m0s
[00:23:56.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:104:0)
[00:23:56.445] Timer tick, asking Buildbucket for the build status
[00:23:56.636] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:56.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:105:0) after 1m0s
[00:24:56.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:105:0)
[00:24:56.789] Timer tick, asking Buildbucket for the build status
[00:24:56.850] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:56.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:106:0) after 1m0s
[00:25:56.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:106:0)
[00:25:56.888] Timer tick, asking Buildbucket for the build status
[00:25:56.926] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:56.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:107:0) after 1m0s
[00:26:57.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:107:0)
[00:26:57.236] Timer tick, asking Buildbucket for the build status
[00:26:57.309] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:57.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:108:0) after 1m0s
[00:27:57.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:108:0)
[00:27:57.331] Timer tick, asking Buildbucket for the build status
[00:27:57.475] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:57.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:109:0) after 1m0s
[00:28:57.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:109:0)
[00:28:57.687] Timer tick, asking Buildbucket for the build status
[00:28:57.761] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:57.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:110:0) after 1m0s
[00:29:58.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:110:0)
[00:29:58.038] Timer tick, asking Buildbucket for the build status
[00:29:58.410] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:58.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:111:0) after 1m0s
[00:30:58.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:111:0)
[00:30:58.550] Timer tick, asking Buildbucket for the build status
[00:30:58.620] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:58.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:112:0) after 1m0s
[00:31:58.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:112:0)
[00:31:58.939] Timer tick, asking Buildbucket for the build status
[00:31:59.016] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:59.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:113:0) after 1m0s
[00:32:59.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:113:0)
[00:32:59.441] Timer tick, asking Buildbucket for the build status
[00:32:59.592] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:59.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:114:0) after 1m0s
[00:33:59.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:114:0)
[00:33:59.902] Timer tick, asking Buildbucket for the build status
[00:34:00.013] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:00.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:115:0) after 1m0s
[00:35:00.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:115:0)
[00:35:00.346] Timer tick, asking Buildbucket for the build status
[00:35:00.386] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:00.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:116:0) after 1m0s
[00:36:00.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:116:0)
[00:36:00.603] Timer tick, asking Buildbucket for the build status
[00:36:00.753] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:00.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:117:0) after 1m0s
[00:37:01.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:117:0)
[00:37:01.020] Timer tick, asking Buildbucket for the build status
[00:37:01.107] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:01.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:118:0) after 1m0s
[00:38:01.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:118:0)
[00:38:01.446] Timer tick, asking Buildbucket for the build status
[00:38:01.597] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:01.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:119:0) after 1m0s
[00:39:01.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:119:0)
[00:39:01.941] Timer tick, asking Buildbucket for the build status
[00:39:02.233] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:02.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:120:0) after 1m0s
[00:40:02.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:120:0)
[00:40:02.515] Timer tick, asking Buildbucket for the build status
[00:40:02.870] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:02.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:121:0) after 1m0s
[00:41:02.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:121:0)
[00:41:02.893] Timer tick, asking Buildbucket for the build status
[00:41:03.089] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:03.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:122:0) after 1m0s
[00:42:03.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:122:0)
[00:42:03.110] Timer tick, asking Buildbucket for the build status
[00:42:03.195] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:03.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:123:0) after 1m0s
[00:43:03.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:123:0)
[00:43:03.217] Timer tick, asking Buildbucket for the build status
[00:43:03.292] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:03.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:124:0) after 1m0s
[00:44:03.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:124:0)
[00:44:03.834] Timer tick, asking Buildbucket for the build status
[00:44:04.015] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:04.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:125:0) after 1m0s
[00:45:04.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:125:0)
[00:45:04.038] Timer tick, asking Buildbucket for the build status
[00:45:04.118] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:04.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:126:0) after 1m0s
[00:46:04.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:126:0)
[00:46:04.154] Timer tick, asking Buildbucket for the build status
[00:46:04.270] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:04.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:127:0) after 1m0s
[00:47:04.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:127:0)
[00:47:04.344] Timer tick, asking Buildbucket for the build status
[00:47:04.378] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:04.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:128:0) after 1m0s
[00:48:04.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:128:0)
[00:48:04.401] Timer tick, asking Buildbucket for the build status
[00:48:04.595] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:04.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:129:0) after 1m0s
[00:49:04.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:129:0)
[00:49:04.644] Timer tick, asking Buildbucket for the build status
[00:49:04.808] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:04.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:130:0) after 1m0s
[00:50:05.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:130:0)
[00:50:05.245] Timer tick, asking Buildbucket for the build status
[00:50:05.373] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:05.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:131:0) after 1m0s
[00:51:05.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:131:0)
[00:51:05.398] Timer tick, asking Buildbucket for the build status
[00:51:05.475] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:05.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:132:0) after 1m0s
[00:52:05.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:132:0)
[00:52:05.494] Timer tick, asking Buildbucket for the build status
[00:52:05.599] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:05.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:133:0) after 1m0s
[00:53:05.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:133:0)
[00:53:05.620] Timer tick, asking Buildbucket for the build status
[00:53:05.802] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:05.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:134:0) after 1m0s
[00:54:06.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:134:0)
[00:54:06.063] Timer tick, asking Buildbucket for the build status
[00:54:06.127] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:06.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:135:0) after 1m0s
[00:55:06.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:135:0)
[00:55:06.200] Timer tick, asking Buildbucket for the build status
[00:55:06.335] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:06.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:136:0) after 1m0s
[00:56:06.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:136:0)
[00:56:06.366] Timer tick, asking Buildbucket for the build status
[00:56:06.420] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:06.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:137:0) after 1m0s
[00:57:06.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:137:0)
[00:57:06.447] Timer tick, asking Buildbucket for the build status
[00:57:06.510] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:06.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:138:0) after 1m0s
[00:58:06.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:138:0)
[00:58:06.551] Timer tick, asking Buildbucket for the build status
[00:58:06.629] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:06.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:139:0) after 1m0s
[00:59:06.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:139:0)
[00:59:06.675] Timer tick, asking Buildbucket for the build status
[00:59:06.728] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:06.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:140:0) after 1m0s
[01:00:06.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:140:0)
[01:00:06.750] Timer tick, asking Buildbucket for the build status
[01:00:06.880] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:06.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:141:0) after 1m0s
[01:01:06.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:141:0)
[01:01:06.911] Timer tick, asking Buildbucket for the build status
[01:01:06.972] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:06.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:142:0) after 1m0s
[01:02:06.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:142:0)
[01:02:06.994] Timer tick, asking Buildbucket for the build status
[01:02:07.057] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:07.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:143:0) after 1m0s
[01:03:07.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:143:0)
[01:03:07.120] Timer tick, asking Buildbucket for the build status
[01:03:07.165] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:07.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:144:0) after 1m0s
[01:04:07.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:144:0)
[01:04:07.233] Timer tick, asking Buildbucket for the build status
[01:04:07.307] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:07.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:145:0) after 1m0s
[01:05:07.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:145:0)
[01:05:07.333] Timer tick, asking Buildbucket for the build status
[01:05:07.397] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:07.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:146:0) after 1m0s
[01:06:07.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:146:0)
[01:06:07.431] Timer tick, asking Buildbucket for the build status
[01:06:07.475] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:07.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:147:0) after 1m0s
[01:07:07.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:147:0)
[01:07:07.856] Timer tick, asking Buildbucket for the build status
[01:07:08.004] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:08.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:148:0) after 1m0s
[01:08:08.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:148:0)
[01:08:08.029] Timer tick, asking Buildbucket for the build status
[01:08:08.072] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:08.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:149:0) after 1m0s
[01:09:08.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:149:0)
[01:09:08.091] Timer tick, asking Buildbucket for the build status
[01:09:08.239] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:08.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:150:0) after 1m0s
[01:10:08.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:150:0)
[01:10:08.270] Timer tick, asking Buildbucket for the build status
[01:10:08.359] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:08.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:151:0) after 1m0s
[01:11:08.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:151:0)
[01:11:08.389] Timer tick, asking Buildbucket for the build status
[01:11:08.448] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:08.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:152:0) after 1m0s
[01:12:08.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:152:0)
[01:12:08.536] Timer tick, asking Buildbucket for the build status
[01:12:08.584] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:08.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:153:0) after 1m0s
[01:13:08.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:153:0)
[01:13:08.686] Timer tick, asking Buildbucket for the build status
[01:13:08.736] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:08.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:154:0) after 1m0s
[01:14:09.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:154:0)
[01:14:09.078] Timer tick, asking Buildbucket for the build status
[01:14:09.137] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:09.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:155:0) after 1m0s
[01:15:09.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:155:0)
[01:15:09.175] Timer tick, asking Buildbucket for the build status
[01:15:09.271] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:09.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:156:0) after 1m0s
[01:16:09.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:156:0)
[01:16:09.293] Timer tick, asking Buildbucket for the build status
[01:16:09.373] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:09.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:157:0) after 1m0s
[01:17:09.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:157:0)
[01:17:09.671] Timer tick, asking Buildbucket for the build status
[01:17:09.716] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:09.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:158:0) after 1m0s
[01:18:09.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:158:0)
[01:18:09.758] Timer tick, asking Buildbucket for the build status
[01:18:09.835] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:09.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:159:0) after 1m0s
[01:19:09.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:159:0)
[01:19:09.962] Timer tick, asking Buildbucket for the build status
[01:19:10.065] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:10.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:160:0) after 1m0s
[01:20:10.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:160:0)
[01:20:10.437] Timer tick, asking Buildbucket for the build status
[01:20:10.564] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:10.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:161:0) after 1m0s
[01:21:10.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:161:0)
[01:21:10.635] Timer tick, asking Buildbucket for the build status
[01:21:10.705] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:10.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:162:0) after 1m0s
[01:22:10.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:162:0)
[01:22:10.747] Timer tick, asking Buildbucket for the build status
[01:22:10.821] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:10.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:163:0) after 1m0s
[01:23:10.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:163:0)
[01:23:10.852] Timer tick, asking Buildbucket for the build status
[01:23:11.004] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:11.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:164:0) after 1m0s
[01:24:11.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:164:0)
[01:24:11.024] Timer tick, asking Buildbucket for the build status
[01:24:11.106] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:11.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:165:0) after 1m0s
[01:25:11.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:165:0)
[01:25:11.137] Timer tick, asking Buildbucket for the build status
[01:25:11.207] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:11.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:166:0) after 1m0s
[01:26:11.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:166:0)
[01:26:11.240] Timer tick, asking Buildbucket for the build status
[01:26:11.428] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:11.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:167:0) after 1m0s
[01:27:11.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:167:0)
[01:27:11.455] Timer tick, asking Buildbucket for the build status
[01:27:11.538] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:11.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:168:0) after 1m0s
[01:28:11.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:168:0)
[01:28:11.565] Timer tick, asking Buildbucket for the build status
[01:28:11.617] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:11.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:169:0) after 1m0s
[01:29:11.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:169:0)
[01:29:11.756] Timer tick, asking Buildbucket for the build status
[01:29:12.008] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:12.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:170:0) after 1m0s
[01:30:12.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:170:0)
[01:30:12.298] Timer tick, asking Buildbucket for the build status
[01:30:12.358] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:12.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:171:0) after 1m0s
[01:31:12.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:171:0)
[01:31:12.390] Timer tick, asking Buildbucket for the build status
[01:31:12.458] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:12.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:172:0) after 1m0s
[01:32:12.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:172:0)
[01:32:12.484] Timer tick, asking Buildbucket for the build status
[01:32:12.539] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:12.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:173:0) after 1m0s
[01:33:12.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:173:0)
[01:33:12.560] Timer tick, asking Buildbucket for the build status
[01:33:12.624] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:12.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:174:0) after 1m0s
[01:34:12.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:174:0)
[01:34:12.657] Timer tick, asking Buildbucket for the build status
[01:34:12.705] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:12.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:175:0) after 1m0s
[01:35:12.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:175:0)
[01:35:12.904] Timer tick, asking Buildbucket for the build status
[01:35:13.101] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:13.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:176:0) after 1m0s
[01:36:13.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:176:0)
[01:36:13.266] Timer tick, asking Buildbucket for the build status
[01:36:13.382] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:13.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:177:0) after 1m0s
[01:37:13.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:177:0)
[01:37:13.704] Timer tick, asking Buildbucket for the build status
[01:37:13.762] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:13.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:178:0) after 1m0s
[01:38:13.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:178:0)
[01:38:13.784] Timer tick, asking Buildbucket for the build status
[01:38:13.852] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:13.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:179:0) after 1m0s
[01:39:13.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:179:0)
[01:39:13.902] Timer tick, asking Buildbucket for the build status
[01:39:14.091] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:14.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:180:0) after 1m0s
[01:40:14.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:180:0)
[01:40:14.120] Timer tick, asking Buildbucket for the build status
[01:40:14.257] Build 8933648009630098784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:14.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9099078071810510720:181:0) after 1m0s
[01:40:24.509] Received PubSub notification, asking Buildbucket for the build status
[01:40:24.602] Build 8933648009630098784: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[01:40:24.602] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "completed_ts": "1538617223629450",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1538606363609345",
    "failure_reason": "INFRA_FAILURE",
    "id": "8933648009630098784",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"70.0.3538.45\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"kitchen crashed or got killed\", \"bootstrapperCallStack\": [], \"type\": \"BOOTSTRAPPER_ERROR\"}, \"recipeResult\": null, \"recipeExitCode\": null, \"recipe\": null, \"annotationUrl\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3801-8761768\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-b-w9dc\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1538606392393202",
    "status": "COMPLETED",
    "status_changed_ts": "1538617223931495",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9099078071810510720",
      "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:8933648009630098784",
      "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/8933648009630098784/+/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:9099078071810510720",
      "swarming_tag:scheduler_job_id:infra/Build From Tarball",
      "swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d431f79a1f9890266786724a5b445a445f332a3a",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4054e77ec45da410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1538617223932913",
    "url": "https://ci.chromium.org/p/infra/builds/b8933648009630098784",
    "utcnow_ts": "1538617224575859"
  }
}
[01:40:24.602] Invocation finished in 3h1m2.454976126s with status FAILED