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

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

{
  "version": "86.0.4205.3"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[13:44:10.974] New invocation is queued and will start shortly
[13:44:10.974] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[13:44:12.753] Starting the invocation (attempt 1)
[13:44:12.817] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:44:12.817] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:44:12.817] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9039951923778315376",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4205.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"e57bd757-1faa-4e19-8150-0a62673dd14b\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4205.3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9039951923778315376",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[13:44:13.436] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1594993452986185",
    "id": "8874521860999596864",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4205.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"e57bd757-1faa-4e19-8150-0a62673dd14b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4205.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1594993453196013",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9039951923778315376",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8874521860999596864/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_task_id:",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1594993453196143",
    "url": "https://ci.chromium.org/b/8874521860999596864",
    "utcnow_ts": "1594993453421317"
  }
}
[13:44:13.436] Task URL: https://ci.chromium.org/b/8874521860999596864
[13:44:13.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:2:0) after 1m0s
[13:45:13.617] Received PubSub notification, asking Buildbucket for the build status
[13:45:13.742] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:14.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:2:0)
[13:45:14.498] Timer tick, asking Buildbucket for the build status
[13:45:14.641] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:14.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:4:0) after 1m0s
[13:46:14.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:4:0)
[13:46:14.683] Timer tick, asking Buildbucket for the build status
[13:46:14.843] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:14.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:5:0) after 1m0s
[13:47:14.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:5:0)
[13:47:14.910] Timer tick, asking Buildbucket for the build status
[13:47:15.276] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:15.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:6:0) after 1m0s
[13:48:15.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:6:0)
[13:48:15.261] Timer tick, asking Buildbucket for the build status
[13:48:15.724] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:15.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:7:0) after 1m0s
[13:49:15.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:7:0)
[13:49:15.758] Timer tick, asking Buildbucket for the build status
[13:49:15.854] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:15.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:8:0) after 1m0s
[13:50:15.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:8:0)
[13:50:15.887] Timer tick, asking Buildbucket for the build status
[13:50:16.474] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:16.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:9:0) after 1m0s
[13:51:16.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:9:0)
[13:51:16.565] Timer tick, asking Buildbucket for the build status
[13:51:16.885] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:16.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:10:0) after 1m0s
[13:52:16.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:10:0)
[13:52:16.910] Timer tick, asking Buildbucket for the build status
[13:52:17.015] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:17.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:11:0) after 1m0s
[13:53:17.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:11:0)
[13:53:17.074] Timer tick, asking Buildbucket for the build status
[13:53:17.195] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:17.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:12:0) after 1m0s
[13:54:17.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:12:0)
[13:54:17.271] Timer tick, asking Buildbucket for the build status
[13:54:17.430] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:17.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:13:0) after 1m0s
[13:55:17.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:13:0)
[13:55:17.460] Timer tick, asking Buildbucket for the build status
[13:55:17.846] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:17.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:14:0) after 1m0s
[13:56:17.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:14:0)
[13:56:17.875] Timer tick, asking Buildbucket for the build status
[13:56:17.954] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:17.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:15:0) after 1m0s
[13:57:18.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:15:0)
[13:57:18.013] Timer tick, asking Buildbucket for the build status
[13:57:18.153] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:18.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:16:0) after 1m0s
[13:58:18.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:16:0)
[13:58:18.214] Timer tick, asking Buildbucket for the build status
[13:58:18.373] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:18.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:17:0) after 1m0s
[13:59:18.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:17:0)
[13:59:18.437] Timer tick, asking Buildbucket for the build status
[13:59:18.558] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:18.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:18:0) after 1m0s
[14:00:20.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:18:0)
[14:00:20.797] Timer tick, asking Buildbucket for the build status
[14:00:22.551] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:22.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:19:0) after 1m0s
[14:01:22.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:19:0)
[14:01:22.653] Timer tick, asking Buildbucket for the build status
[14:01:22.739] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:22.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:20:0) after 1m0s
[14:02:22.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:20:0)
[14:02:22.743] Timer tick, asking Buildbucket for the build status
[14:02:22.905] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:22.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:21:0) after 1m0s
[14:03:22.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:21:0)
[14:03:22.995] Timer tick, asking Buildbucket for the build status
[14:03:23.089] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:23.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:22:0) after 1m0s
[14:04:23.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:22:0)
[14:04:23.203] Timer tick, asking Buildbucket for the build status
[14:04:23.349] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:23.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:23:0) after 1m0s
[14:05:23.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:23:0)
[14:05:23.446] Timer tick, asking Buildbucket for the build status
[14:05:23.592] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:23.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:24:0) after 1m0s
[14:06:23.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:24:0)
[14:06:23.628] Timer tick, asking Buildbucket for the build status
[14:06:23.894] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:23.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:25:0) after 1m0s
[14:07:24.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:25:0)
[14:07:24.113] Timer tick, asking Buildbucket for the build status
[14:07:24.282] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:24.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:26:0) after 1m0s
[14:08:24.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:26:0)
[14:08:24.357] Timer tick, asking Buildbucket for the build status
[14:08:24.868] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:24.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:27:0) after 1m0s
[14:09:24.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:27:0)
[14:09:24.936] Timer tick, asking Buildbucket for the build status
[14:09:25.626] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:25.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:28:0) after 1m0s
[14:10:25.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:28:0)
[14:10:25.648] Timer tick, asking Buildbucket for the build status
[14:10:25.920] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:25.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:29:0) after 1m0s
[14:11:25.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:29:0)
[14:11:25.989] Timer tick, asking Buildbucket for the build status
[14:11:26.067] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:26.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:30:0) after 1m0s
[14:12:26.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:30:0)
[14:12:26.186] Timer tick, asking Buildbucket for the build status
[14:12:26.431] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:26.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:31:0) after 1m0s
[14:13:26.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:31:0)
[14:13:26.538] Timer tick, asking Buildbucket for the build status
[14:13:26.616] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:26.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:32:0) after 1m0s
[14:14:26.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:32:0)
[14:14:26.895] Timer tick, asking Buildbucket for the build status
[14:14:27.097] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:27.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:33:0) after 1m0s
[14:15:27.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:33:0)
[14:15:27.218] Timer tick, asking Buildbucket for the build status
[14:15:27.282] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:27.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:34:0) after 1m0s
[14:16:27.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:34:0)
[14:16:27.389] Timer tick, asking Buildbucket for the build status
[14:16:27.805] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:27.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:35:0) after 1m0s
[14:17:27.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:35:0)
[14:17:27.873] Timer tick, asking Buildbucket for the build status
[14:17:27.965] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:27.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:36:0) after 1m0s
[14:18:28.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:36:0)
[14:18:28.040] Timer tick, asking Buildbucket for the build status
[14:18:28.249] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:28.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:37:0) after 1m0s
[14:19:28.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:37:0)
[14:19:28.296] Timer tick, asking Buildbucket for the build status
[14:19:28.644] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:28.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:38:0) after 1m0s
[14:20:29.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:38:0)
[14:20:29.229] Timer tick, asking Buildbucket for the build status
[14:20:29.432] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:29.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:39:0) after 1m0s
[14:21:29.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:39:0)
[14:21:29.567] Timer tick, asking Buildbucket for the build status
[14:21:29.970] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:29.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:40:0) after 1m0s
[14:22:30.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:40:0)
[14:22:30.052] Timer tick, asking Buildbucket for the build status
[14:22:30.222] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:30.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:41:0) after 1m0s
[14:23:30.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:41:0)
[14:23:30.259] Timer tick, asking Buildbucket for the build status
[14:23:30.435] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:30.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:42:0) after 1m0s
[14:24:30.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:42:0)
[14:24:30.491] Timer tick, asking Buildbucket for the build status
[14:24:30.586] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:30.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:43:0) after 1m0s
[14:25:30.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:43:0)
[14:25:30.639] Timer tick, asking Buildbucket for the build status
[14:25:30.750] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:30.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:44:0) after 1m0s
[14:26:30.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:44:0)
[14:26:30.759] Timer tick, asking Buildbucket for the build status
[14:26:30.885] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:30.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:45:0) after 1m0s
[14:27:30.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:45:0)
[14:27:30.963] Timer tick, asking Buildbucket for the build status
[14:27:31.304] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:31.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:46:0) after 1m0s
[14:28:31.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:46:0)
[14:28:31.356] Timer tick, asking Buildbucket for the build status
[14:28:31.912] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:31.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:47:0) after 1m0s
[14:29:31.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:47:0)
[14:29:31.969] Timer tick, asking Buildbucket for the build status
[14:29:32.181] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:32.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:48:0) after 1m0s
[14:30:32.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:48:0)
[14:30:32.205] Timer tick, asking Buildbucket for the build status
[14:30:32.378] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:32.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:49:0) after 1m0s
[14:31:32.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:49:0)
[14:31:32.417] Timer tick, asking Buildbucket for the build status
[14:31:32.643] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:32.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:50:0) after 1m0s
[14:32:32.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:50:0)
[14:32:32.722] Timer tick, asking Buildbucket for the build status
[14:32:32.849] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:32.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:51:0) after 1m0s
[14:33:32.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:51:0)
[14:33:32.973] Timer tick, asking Buildbucket for the build status
[14:33:33.046] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:33.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:52:0) after 1m0s
[14:34:33.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:52:0)
[14:34:33.243] Timer tick, asking Buildbucket for the build status
[14:34:33.564] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:33.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:53:0) after 1m0s
[14:35:33.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:53:0)
[14:35:33.769] Timer tick, asking Buildbucket for the build status
[14:35:33.938] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:33.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:54:0) after 1m0s
[14:36:34.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:54:0)
[14:36:34.242] Timer tick, asking Buildbucket for the build status
[14:36:34.461] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:34.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:55:0) after 1m0s
[14:37:34.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:55:0)
[14:37:34.810] Timer tick, asking Buildbucket for the build status
[14:37:35.176] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:35.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:56:0) after 1m0s
[14:38:35.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:56:0)
[14:38:35.259] Timer tick, asking Buildbucket for the build status
[14:38:35.512] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:35.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:57:0) after 1m0s
[14:39:35.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:57:0)
[14:39:35.573] Timer tick, asking Buildbucket for the build status
[14:39:35.768] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:35.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:58:0) after 1m0s
[14:40:35.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:58:0)
[14:40:35.828] Timer tick, asking Buildbucket for the build status
[14:40:37.770] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:37.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:59:0) after 1m0s
[14:41:37.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:59:0)
[14:41:37.900] Timer tick, asking Buildbucket for the build status
[14:41:38.234] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:38.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:60:0) after 1m0s
[14:42:38.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:60:0)
[14:42:38.383] Timer tick, asking Buildbucket for the build status
[14:42:38.574] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:38.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:61:0) after 1m0s
[14:43:38.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:61:0)
[14:43:38.641] Timer tick, asking Buildbucket for the build status
[14:43:38.885] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:38.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:62:0) after 1m0s
[14:44:38.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:62:0)
[14:44:38.982] Timer tick, asking Buildbucket for the build status
[14:44:39.207] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:39.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:63:0) after 1m0s
[14:45:39.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:63:0)
[14:45:39.250] Timer tick, asking Buildbucket for the build status
[14:45:39.375] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:39.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:64:0) after 1m0s
[14:46:39.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:64:0)
[14:46:39.410] Timer tick, asking Buildbucket for the build status
[14:46:39.660] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:39.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:65:0) after 1m0s
[14:47:39.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:65:0)
[14:47:39.692] Timer tick, asking Buildbucket for the build status
[14:47:40.424] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:40.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:66:0) after 1m0s
[14:48:40.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:66:0)
[14:48:40.452] Timer tick, asking Buildbucket for the build status
[14:48:40.570] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:40.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:67:0) after 1m0s
[14:49:40.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:67:0)
[14:49:40.601] Timer tick, asking Buildbucket for the build status
[14:49:40.844] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:40.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:68:0) after 1m0s
[14:50:40.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:68:0)
[14:50:40.872] Timer tick, asking Buildbucket for the build status
[14:50:40.993] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:40.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:69:0) after 1m0s
[14:51:41.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:69:0)
[14:51:41.086] Timer tick, asking Buildbucket for the build status
[14:51:41.165] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:41.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:70:0) after 1m0s
[14:52:41.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:70:0)
[14:52:41.211] Timer tick, asking Buildbucket for the build status
[14:52:41.389] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:41.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:71:0) after 1m0s
[14:53:41.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:71:0)
[14:53:41.407] Timer tick, asking Buildbucket for the build status
[14:53:41.490] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:41.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:72:0) after 1m0s
[14:54:41.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:72:0)
[14:54:41.521] Timer tick, asking Buildbucket for the build status
[14:54:41.685] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:41.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:73:0) after 1m0s
[14:55:41.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:73:0)
[14:55:41.750] Timer tick, asking Buildbucket for the build status
[14:55:41.883] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:41.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:74:0) after 1m0s
[14:56:41.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:74:0)
[14:56:41.918] Timer tick, asking Buildbucket for the build status
[14:56:42.273] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:42.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:75:0) after 1m0s
[14:57:42.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:75:0)
[14:57:42.260] Timer tick, asking Buildbucket for the build status
[14:57:42.426] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:42.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:76:0) after 1m0s
[14:58:42.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:76:0)
[14:58:42.527] Timer tick, asking Buildbucket for the build status
[14:58:42.833] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:42.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:77:0) after 1m0s
[14:59:42.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:77:0)
[14:59:42.981] Timer tick, asking Buildbucket for the build status
[14:59:43.057] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:43.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:78:0) after 1m0s
[15:00:43.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:78:0)
[15:00:43.199] Timer tick, asking Buildbucket for the build status
[15:00:43.522] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:43.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:79:0) after 1m0s
[15:01:43.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:79:0)
[15:01:43.608] Timer tick, asking Buildbucket for the build status
[15:01:43.752] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:43.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:80:0) after 1m0s
[15:02:43.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:80:0)
[15:02:43.810] Timer tick, asking Buildbucket for the build status
[15:02:44.209] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:44.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:81:0) after 1m0s
[15:03:44.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:81:0)
[15:03:44.277] Timer tick, asking Buildbucket for the build status
[15:03:44.392] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:44.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:82:0) after 1m0s
[15:04:44.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:82:0)
[15:04:44.499] Timer tick, asking Buildbucket for the build status
[15:04:44.565] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:44.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:83:0) after 1m0s
[15:05:44.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:83:0)
[15:05:44.552] Timer tick, asking Buildbucket for the build status
[15:05:44.762] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:44.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:84:0) after 1m0s
[15:06:44.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:84:0)
[15:06:44.765] Timer tick, asking Buildbucket for the build status
[15:06:44.883] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:44.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:85:0) after 1m0s
[15:07:44.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:85:0)
[15:07:44.877] Timer tick, asking Buildbucket for the build status
[15:07:45.109] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:45.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:86:0) after 1m0s
[15:08:45.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:86:0)
[15:08:45.157] Timer tick, asking Buildbucket for the build status
[15:08:45.262] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:45.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:87:0) after 1m0s
[15:09:45.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:87:0)
[15:09:45.324] Timer tick, asking Buildbucket for the build status
[15:09:45.410] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:45.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:88:0) after 1m0s
[15:10:45.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:88:0)
[15:10:45.524] Timer tick, asking Buildbucket for the build status
[15:10:45.645] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:45.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:89:0) after 1m0s
[15:11:45.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:89:0)
[15:11:45.708] Timer tick, asking Buildbucket for the build status
[15:11:46.128] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:46.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:90:0) after 1m0s
[15:12:46.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:90:0)
[15:12:46.211] Timer tick, asking Buildbucket for the build status
[15:12:46.285] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:46.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:91:0) after 1m0s
[15:13:46.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:91:0)
[15:13:46.374] Timer tick, asking Buildbucket for the build status
[15:13:46.687] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:46.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:92:0) after 1m0s
[15:14:46.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:92:0)
[15:14:46.846] Timer tick, asking Buildbucket for the build status
[15:14:46.969] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:46.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:93:0) after 1m0s
[15:15:47.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:93:0)
[15:15:47.092] Timer tick, asking Buildbucket for the build status
[15:15:47.263] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:47.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:94:0) after 1m0s
[15:16:47.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:94:0)
[15:16:47.438] Timer tick, asking Buildbucket for the build status
[15:16:47.541] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:47.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:95:0) after 1m0s
[15:17:47.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:95:0)
[15:17:47.647] Timer tick, asking Buildbucket for the build status
[15:17:47.862] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:47.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:96:0) after 1m0s
[15:18:47.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:96:0)
[15:18:47.899] Timer tick, asking Buildbucket for the build status
[15:18:48.062] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:48.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:97:0) after 1m0s
[15:19:48.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:97:0)
[15:19:48.114] Timer tick, asking Buildbucket for the build status
[15:19:48.196] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:48.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:98:0) after 1m0s
[15:20:48.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:98:0)
[15:20:48.255] Timer tick, asking Buildbucket for the build status
[15:20:48.415] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:48.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:99:0) after 1m0s
[15:21:48.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:99:0)
[15:21:48.466] Timer tick, asking Buildbucket for the build status
[15:21:48.540] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:48.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:100:0) after 1m0s
[15:22:48.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:100:0)
[15:22:48.649] Timer tick, asking Buildbucket for the build status
[15:22:48.761] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:48.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:101:0) after 1m0s
[15:23:48.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:101:0)
[15:23:48.855] Timer tick, asking Buildbucket for the build status
[15:23:49.067] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:49.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:102:0) after 1m0s
[15:24:49.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:102:0)
[15:24:49.155] Timer tick, asking Buildbucket for the build status
[15:24:49.282] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:49.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:103:0) after 1m0s
[15:25:49.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:103:0)
[15:25:49.330] Timer tick, asking Buildbucket for the build status
[15:25:49.507] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:49.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:104:0) after 1m0s
[15:26:49.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:104:0)
[15:26:49.519] Timer tick, asking Buildbucket for the build status
[15:26:49.598] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:49.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:105:0) after 1m0s
[15:27:49.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:105:0)
[15:27:49.745] Timer tick, asking Buildbucket for the build status
[15:27:50.183] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:50.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:106:0) after 1m0s
[15:28:50.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:106:0)
[15:28:50.320] Timer tick, asking Buildbucket for the build status
[15:28:50.393] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:50.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:107:0) after 1m0s
[15:29:50.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:107:0)
[15:29:50.423] Timer tick, asking Buildbucket for the build status
[15:29:50.561] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:50.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:108:0) after 1m0s
[15:30:50.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:108:0)
[15:30:50.602] Timer tick, asking Buildbucket for the build status
[15:30:50.724] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:50.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:109:0) after 1m0s
[15:31:50.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:109:0)
[15:31:50.754] Timer tick, asking Buildbucket for the build status
[15:31:50.830] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:50.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:110:0) after 1m0s
[15:32:50.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:110:0)
[15:32:50.861] Timer tick, asking Buildbucket for the build status
[15:32:51.134] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:51.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:111:0) after 1m0s
[15:33:51.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:111:0)
[15:33:51.176] Timer tick, asking Buildbucket for the build status
[15:33:51.300] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:51.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:112:0) after 1m0s
[15:34:51.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:112:0)
[15:34:51.331] Timer tick, asking Buildbucket for the build status
[15:34:51.554] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:51.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:113:0) after 1m0s
[15:35:51.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:113:0)
[15:35:51.590] Timer tick, asking Buildbucket for the build status
[15:35:51.670] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:51.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:114:0) after 1m0s
[15:36:51.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:114:0)
[15:36:51.689] Timer tick, asking Buildbucket for the build status
[15:36:51.850] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:51.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:115:0) after 1m0s
[15:37:51.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:115:0)
[15:37:51.876] Timer tick, asking Buildbucket for the build status
[15:37:51.939] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:51.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:116:0) after 1m0s
[15:38:51.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:116:0)
[15:38:51.992] Timer tick, asking Buildbucket for the build status
[15:38:52.473] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:52.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:117:0) after 1m0s
[15:39:52.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:117:0)
[15:39:52.588] Timer tick, asking Buildbucket for the build status
[15:39:52.710] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:52.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:118:0) after 1m0s
[15:40:52.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:118:0)
[15:40:52.754] Timer tick, asking Buildbucket for the build status
[15:40:52.894] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:52.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:119:0) after 1m0s
[15:41:52.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:119:0)
[15:41:52.871] Timer tick, asking Buildbucket for the build status
[15:41:52.940] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:52.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:120:0) after 1m0s
[15:42:52.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:120:0)
[15:42:52.973] Timer tick, asking Buildbucket for the build status
[15:42:53.481] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:53.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:121:0) after 1m0s
[15:43:53.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:121:0)
[15:43:53.549] Timer tick, asking Buildbucket for the build status
[15:43:53.624] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:53.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:122:0) after 1m0s
[15:44:53.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:122:0)
[15:44:53.741] Timer tick, asking Buildbucket for the build status
[15:44:53.856] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:53.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:123:0) after 1m0s
[15:45:53.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:123:0)
[15:45:53.887] Timer tick, asking Buildbucket for the build status
[15:45:54.011] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:54.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:124:0) after 1m0s
[15:46:54.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:124:0)
[15:46:54.032] Timer tick, asking Buildbucket for the build status
[15:46:54.152] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:54.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:125:0) after 1m0s
[15:47:54.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:125:0)
[15:47:54.190] Timer tick, asking Buildbucket for the build status
[15:47:54.331] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:54.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:126:0) after 1m0s
[15:48:54.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:126:0)
[15:48:54.441] Timer tick, asking Buildbucket for the build status
[15:48:54.571] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:54.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:127:0) after 1m0s
[15:49:54.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:127:0)
[15:49:54.624] Timer tick, asking Buildbucket for the build status
[15:49:54.716] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:54.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:128:0) after 1m0s
[15:50:54.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:128:0)
[15:50:54.835] Timer tick, asking Buildbucket for the build status
[15:50:55.036] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:55.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:129:0) after 1m0s
[15:51:55.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:129:0)
[15:51:55.052] Timer tick, asking Buildbucket for the build status
[15:51:55.148] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:55.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:130:0) after 1m0s
[15:52:55.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:130:0)
[15:52:55.184] Timer tick, asking Buildbucket for the build status
[15:52:55.264] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:55.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:131:0) after 1m0s
[15:53:55.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:131:0)
[15:53:55.370] Timer tick, asking Buildbucket for the build status
[15:53:55.596] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:55.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:132:0) after 1m0s
[15:54:55.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:132:0)
[15:54:55.646] Timer tick, asking Buildbucket for the build status
[15:54:55.725] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:55.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:133:0) after 1m0s
[15:55:55.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:133:0)
[15:55:55.748] Timer tick, asking Buildbucket for the build status
[15:55:55.839] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:55.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:134:0) after 1m0s
[15:56:55.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:134:0)
[15:56:55.946] Timer tick, asking Buildbucket for the build status
[15:56:56.289] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:56.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:135:0) after 1m0s
[15:57:56.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:135:0)
[15:57:56.336] Timer tick, asking Buildbucket for the build status
[15:57:56.582] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:56.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:136:0) after 1m0s
[15:58:56.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:136:0)
[15:58:56.698] Timer tick, asking Buildbucket for the build status
[15:58:56.881] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:56.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:137:0) after 1m0s
[15:59:57.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:137:0)
[15:59:57.002] Timer tick, asking Buildbucket for the build status
[15:59:57.170] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:57.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:138:0) after 1m0s
[16:00:57.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:138:0)
[16:00:57.347] Timer tick, asking Buildbucket for the build status
[16:00:57.449] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:57.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:139:0) after 1m0s
[16:01:57.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:139:0)
[16:01:57.488] Timer tick, asking Buildbucket for the build status
[16:01:59.326] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:59.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:140:0) after 1m0s
[16:02:59.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:140:0)
[16:02:59.404] Timer tick, asking Buildbucket for the build status
[16:02:59.513] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:59.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:141:0) after 1m0s
[16:03:59.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:141:0)
[16:03:59.561] Timer tick, asking Buildbucket for the build status
[16:03:59.888] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:59.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:142:0) after 1m0s
[16:04:59.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:142:0)
[16:04:59.979] Timer tick, asking Buildbucket for the build status
[16:05:00.112] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:00.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:143:0) after 1m0s
[16:06:00.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:143:0)
[16:06:00.265] Timer tick, asking Buildbucket for the build status
[16:06:00.366] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:00.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:144:0) after 1m0s
[16:07:00.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:144:0)
[16:07:00.800] Timer tick, asking Buildbucket for the build status
[16:07:00.875] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:00.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:145:0) after 1m0s
[16:08:00.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:145:0)
[16:08:00.907] Timer tick, asking Buildbucket for the build status
[16:08:01.190] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:01.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:146:0) after 1m0s
[16:09:01.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:146:0)
[16:09:01.287] Timer tick, asking Buildbucket for the build status
[16:09:01.387] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:01.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:147:0) after 1m0s
[16:10:01.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:147:0)
[16:10:01.705] Timer tick, asking Buildbucket for the build status
[16:10:01.943] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:01.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:148:0) after 1m0s
[16:11:02.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:148:0)
[16:11:02.004] Timer tick, asking Buildbucket for the build status
[16:11:02.196] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:02.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:149:0) after 1m0s
[16:12:02.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:149:0)
[16:12:02.286] Timer tick, asking Buildbucket for the build status
[16:12:02.583] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:02.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:150:0) after 1m0s
[16:13:02.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:150:0)
[16:13:02.665] Timer tick, asking Buildbucket for the build status
[16:13:02.766] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:02.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:151:0) after 1m0s
[16:14:02.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:151:0)
[16:14:02.799] Timer tick, asking Buildbucket for the build status
[16:14:02.977] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:02.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:152:0) after 1m0s
[16:15:03.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:152:0)
[16:15:03.066] Timer tick, asking Buildbucket for the build status
[16:15:03.202] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:03.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:153:0) after 1m0s
[16:16:03.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:153:0)
[16:16:03.239] Timer tick, asking Buildbucket for the build status
[16:16:03.534] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:03.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:154:0) after 1m0s
[16:17:03.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:154:0)
[16:17:03.611] Timer tick, asking Buildbucket for the build status
[16:17:03.898] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:03.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:155:0) after 1m0s
[16:18:03.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:155:0)
[16:18:03.988] Timer tick, asking Buildbucket for the build status
[16:18:04.728] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:04.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:156:0) after 1m0s
[16:19:04.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:156:0)
[16:19:04.803] Timer tick, asking Buildbucket for the build status
[16:19:04.891] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:04.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:157:0) after 1m0s
[16:20:04.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:157:0)
[16:20:04.969] Timer tick, asking Buildbucket for the build status
[16:20:05.188] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:05.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:158:0) after 1m0s
[16:21:05.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:158:0)
[16:21:05.238] Timer tick, asking Buildbucket for the build status
[16:21:08.562] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:08.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:159:0) after 1m0s
[16:22:08.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:159:0)
[16:22:08.684] Timer tick, asking Buildbucket for the build status
[16:22:08.843] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:08.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:160:0) after 1m0s
[16:23:08.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:160:0)
[16:23:08.962] Timer tick, asking Buildbucket for the build status
[16:23:09.120] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:09.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:161:0) after 1m0s
[16:24:09.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:161:0)
[16:24:09.161] Timer tick, asking Buildbucket for the build status
[16:24:09.663] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:09.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:162:0) after 1m0s
[16:25:09.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:162:0)
[16:25:09.737] Timer tick, asking Buildbucket for the build status
[16:25:09.820] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:09.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:163:0) after 1m0s
[16:26:09.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:163:0)
[16:26:09.915] Timer tick, asking Buildbucket for the build status
[16:26:10.378] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:10.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:164:0) after 1m0s
[16:27:10.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:164:0)
[16:27:10.468] Timer tick, asking Buildbucket for the build status
[16:27:10.746] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:10.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:165:0) after 1m0s
[16:28:10.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:165:0)
[16:28:10.782] Timer tick, asking Buildbucket for the build status
[16:28:11.310] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:11.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:166:0) after 1m0s
[16:29:11.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:166:0)
[16:29:11.376] Timer tick, asking Buildbucket for the build status
[16:29:11.536] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:11.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:167:0) after 1m0s
[16:30:11.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:167:0)
[16:30:11.623] Timer tick, asking Buildbucket for the build status
[16:30:11.709] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:11.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:168:0) after 1m0s
[16:31:11.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:168:0)
[16:31:11.755] Timer tick, asking Buildbucket for the build status
[16:31:13.157] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:13.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:169:0) after 1m0s
[16:32:13.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:169:0)
[16:32:13.261] Timer tick, asking Buildbucket for the build status
[16:32:13.512] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:13.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:170:0) after 1m0s
[16:33:13.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:170:0)
[16:33:13.566] Timer tick, asking Buildbucket for the build status
[16:33:13.919] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:13.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:171:0) after 1m0s
[16:34:13.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:171:0)
[16:34:13.962] Timer tick, asking Buildbucket for the build status
[16:34:14.133] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:14.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:172:0) after 1m0s
[16:35:14.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:172:0)
[16:35:14.251] Timer tick, asking Buildbucket for the build status
[16:35:14.416] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:14.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:173:0) after 1m0s
[16:36:14.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:173:0)
[16:36:14.501] Timer tick, asking Buildbucket for the build status
[16:36:14.590] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:14.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:174:0) after 1m0s
[16:37:14.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:174:0)
[16:37:14.760] Timer tick, asking Buildbucket for the build status
[16:37:15.026] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:15.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:175:0) after 1m0s
[16:38:15.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:175:0)
[16:38:15.121] Timer tick, asking Buildbucket for the build status
[16:38:15.461] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:15.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:176:0) after 1m0s
[16:39:15.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:176:0)
[16:39:15.490] Timer tick, asking Buildbucket for the build status
[16:39:15.672] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:15.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:177:0) after 1m0s
[16:40:15.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:177:0)
[16:40:15.816] Timer tick, asking Buildbucket for the build status
[16:40:15.910] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:15.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:178:0) after 1m0s
[16:41:15.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:178:0)
[16:41:15.906] Timer tick, asking Buildbucket for the build status
[16:41:16.120] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:16.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:179:0) after 1m0s
[16:42:16.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:179:0)
[16:42:16.228] Timer tick, asking Buildbucket for the build status
[16:42:16.320] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:16.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:180:0) after 1m0s
[16:43:16.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:180:0)
[16:43:16.368] Timer tick, asking Buildbucket for the build status
[16:43:16.675] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:16.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:181:0) after 1m0s
[16:44:16.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:181:0)
[16:44:16.753] Timer tick, asking Buildbucket for the build status
[16:44:16.897] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:16.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:182:0) after 1m0s
[16:45:16.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:182:0)
[16:45:16.996] Timer tick, asking Buildbucket for the build status
[16:45:17.398] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:17.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:183:0) after 1m0s
[16:46:17.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:183:0)
[16:46:17.440] Timer tick, asking Buildbucket for the build status
[16:46:17.505] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:17.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:184:0) after 1m0s
[16:47:17.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:184:0)
[16:47:17.538] Timer tick, asking Buildbucket for the build status
[16:47:17.731] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:17.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:185:0) after 1m0s
[16:48:17.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:185:0)
[16:48:17.863] Timer tick, asking Buildbucket for the build status
[16:48:17.944] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:17.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:186:0) after 1m0s
[16:49:18.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:186:0)
[16:49:18.059] Timer tick, asking Buildbucket for the build status
[16:49:18.387] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:18.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:187:0) after 1m0s
[16:50:18.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:187:0)
[16:50:18.485] Timer tick, asking Buildbucket for the build status
[16:50:18.756] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:18.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:188:0) after 1m0s
[16:51:18.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:188:0)
[16:51:18.820] Timer tick, asking Buildbucket for the build status
[16:51:18.935] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:18.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:189:0) after 1m0s
[16:52:19.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:189:0)
[16:52:19.055] Timer tick, asking Buildbucket for the build status
[16:52:19.344] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:19.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:190:0) after 1m0s
[16:53:19.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:190:0)
[16:53:19.378] Timer tick, asking Buildbucket for the build status
[16:53:19.496] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:19.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:191:0) after 1m0s
[16:54:19.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:191:0)
[16:54:19.530] Timer tick, asking Buildbucket for the build status
[16:54:19.628] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:19.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:192:0) after 1m0s
[16:55:19.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:192:0)
[16:55:19.729] Timer tick, asking Buildbucket for the build status
[16:55:19.969] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:19.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:193:0) after 1m0s
[16:56:20.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:193:0)
[16:56:20.152] Timer tick, asking Buildbucket for the build status
[16:56:20.351] Build 8874521860999596864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:20.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9039951923778315376:194:0) after 1m0s
[16:56:28.995] Received PubSub notification, asking Buildbucket for the build status
[16:56:29.396] Build 8874521860999596864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:56:29.396] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1595004987958607",
    "created_by": "project:infra",
    "created_ts": "1594993452986185",
    "id": "8874521860999596864",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4205.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"e57bd757-1faa-4e19-8150-0a62673dd14b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4205.3\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-jkly\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5409-9d77717\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1594993511162407",
    "status": "COMPLETED",
    "status_changed_ts": "1595004988505210",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9039951923778315376",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8874521860999596864/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_task_id:4d75d6318d9c5310",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1595004988528506",
    "url": "https://ci.chromium.org/b/8874521860999596864",
    "utcnow_ts": "1595004989361845"
  }
}
[16:56:29.396] Invocation finished in 3h12m18.442635199s with status SUCCEEDED