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

• parent_buildername:publish_tarball
• user_agent:recipe

[11:44:56.434] New invocation is queued and will start shortly
[11:44:56.434] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:44:57.604] Starting the invocation (attempt 1)
[11:44:57.640] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:44:57.640] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:44:57.640] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9042133753046703616",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4179.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"83992248-5364-4e94-8183-b23a957af0f4\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4179.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:9042133753046703616",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[11:44:58.129] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1592912697732359",
    "id": "8876703691020915424",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4179.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"83992248-5364-4e94-8183-b23a957af0f4\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4179.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1592912698003460",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042133753046703616",
      "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/8876703691020915424/+/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": "1592912698003582",
    "url": "https://ci.chromium.org/b/8876703691020915424",
    "utcnow_ts": "1592912698122352"
  }
}
[11:44:58.129] Task URL: https://ci.chromium.org/b/8876703691020915424
[11:44:58.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:2:0) after 1m0s
[11:45:58.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:2:0)
[11:45:58.183] Timer tick, asking Buildbucket for the build status
[11:45:58.300] Build 8876703691020915424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:45:58.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:3:0) after 1m0s
[11:46:58.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:3:0)
[11:46:58.362] Timer tick, asking Buildbucket for the build status
[11:46:58.448] Build 8876703691020915424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:46:58.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:4:0) after 1m0s
[11:47:58.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:4:0)
[11:47:58.537] Timer tick, asking Buildbucket for the build status
[11:47:58.704] Build 8876703691020915424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:47:58.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:5:0) after 1m0s
[11:48:58.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:5:0)
[11:48:58.746] Timer tick, asking Buildbucket for the build status
[11:48:59.414] Build 8876703691020915424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:48:59.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:6:0) after 1m0s
[11:49:07.443] Received PubSub notification, asking Buildbucket for the build status
[11:49:07.660] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:59.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:6:0)
[11:49:59.738] Timer tick, asking Buildbucket for the build status
[11:49:59.954] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:59.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:8:0) after 1m0s
[11:51:00.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:8:0)
[11:51:00.221] Timer tick, asking Buildbucket for the build status
[11:51:00.914] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:00.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:9:0) after 1m0s
[11:52:01.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:9:0)
[11:52:01.316] Timer tick, asking Buildbucket for the build status
[11:52:01.600] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:01.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:10:0) after 1m0s
[11:53:02.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:10:0)
[11:53:02.105] Timer tick, asking Buildbucket for the build status
[11:53:02.312] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:02.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:11:0) after 1m0s
[11:54:02.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:11:0)
[11:54:02.552] Timer tick, asking Buildbucket for the build status
[11:54:05.826] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:05.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:12:0) after 1m0s
[11:55:05.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:12:0)
[11:55:05.840] Timer tick, asking Buildbucket for the build status
[11:55:06.240] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:06.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:13:0) after 1m0s
[11:56:06.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:13:0)
[11:56:06.316] Timer tick, asking Buildbucket for the build status
[11:56:06.724] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:06.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:14:0) after 1m0s
[11:57:06.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:14:0)
[11:57:06.785] Timer tick, asking Buildbucket for the build status
[11:57:06.932] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:06.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:15:0) after 1m0s
[11:58:06.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:15:0)
[11:58:06.990] Timer tick, asking Buildbucket for the build status
[11:58:07.160] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:07.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:16:0) after 1m0s
[11:59:07.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:16:0)
[11:59:07.242] Timer tick, asking Buildbucket for the build status
[11:59:07.413] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:07.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:17:0) after 1m0s
[12:00:07.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:17:0)
[12:00:07.593] Timer tick, asking Buildbucket for the build status
[12:00:07.854] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:07.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:18:0) after 1m0s
[12:01:08.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:18:0)
[12:01:08.366] Timer tick, asking Buildbucket for the build status
[12:01:08.821] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:08.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:19:0) after 1m0s
[12:02:09.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:19:0)
[12:02:09.005] Timer tick, asking Buildbucket for the build status
[12:02:09.171] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:09.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:20:0) after 1m0s
[12:03:09.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:20:0)
[12:03:09.343] Timer tick, asking Buildbucket for the build status
[12:03:09.519] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:09.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:21:0) after 1m0s
[12:04:09.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:21:0)
[12:04:09.640] Timer tick, asking Buildbucket for the build status
[12:04:09.791] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:09.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:22:0) after 1m0s
[12:05:09.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:22:0)
[12:05:09.851] Timer tick, asking Buildbucket for the build status
[12:05:11.195] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:11.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:23:0) after 1m0s
[12:06:11.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:23:0)
[12:06:11.258] Timer tick, asking Buildbucket for the build status
[12:06:11.614] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:11.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:24:0) after 1m0s
[12:07:11.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:24:0)
[12:07:11.678] Timer tick, asking Buildbucket for the build status
[12:07:11.875] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:11.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:25:0) after 1m0s
[12:08:11.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:25:0)
[12:08:11.956] Timer tick, asking Buildbucket for the build status
[12:08:12.111] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:12.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:26:0) after 1m0s
[12:09:12.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:26:0)
[12:09:12.213] Timer tick, asking Buildbucket for the build status
[12:09:12.350] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:12.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:27:0) after 1m0s
[12:10:12.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:27:0)
[12:10:12.439] Timer tick, asking Buildbucket for the build status
[12:10:12.678] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:12.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:28:0) after 1m0s
[12:11:12.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:28:0)
[12:11:12.694] Timer tick, asking Buildbucket for the build status
[12:11:12.838] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:12.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:29:0) after 1m0s
[12:12:12.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:29:0)
[12:12:12.864] Timer tick, asking Buildbucket for the build status
[12:12:13.016] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:13.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:30:0) after 1m0s
[12:13:13.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:30:0)
[12:13:13.067] Timer tick, asking Buildbucket for the build status
[12:13:13.153] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:13.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:31:0) after 1m0s
[12:14:13.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:31:0)
[12:14:13.240] Timer tick, asking Buildbucket for the build status
[12:14:13.788] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:13.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:32:0) after 1m0s
[12:15:13.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:32:0)
[12:15:13.820] Timer tick, asking Buildbucket for the build status
[12:15:13.931] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:13.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:33:0) after 1m0s
[12:16:13.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:33:0)
[12:16:13.962] Timer tick, asking Buildbucket for the build status
[12:16:14.154] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:14.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:34:0) after 1m0s
[12:17:14.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:34:0)
[12:17:14.199] Timer tick, asking Buildbucket for the build status
[12:17:15.138] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:15.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:35:0) after 1m0s
[12:18:15.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:35:0)
[12:18:15.223] Timer tick, asking Buildbucket for the build status
[12:18:15.452] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:15.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:36:0) after 1m0s
[12:19:15.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:36:0)
[12:19:15.582] Timer tick, asking Buildbucket for the build status
[12:19:15.831] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:15.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:37:0) after 1m0s
[12:20:15.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:37:0)
[12:20:15.897] Timer tick, asking Buildbucket for the build status
[12:20:16.189] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:16.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:38:0) after 1m0s
[12:21:16.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:38:0)
[12:21:16.261] Timer tick, asking Buildbucket for the build status
[12:21:16.367] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:16.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:39:0) after 1m0s
[12:22:16.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:39:0)
[12:22:16.389] Timer tick, asking Buildbucket for the build status
[12:22:16.566] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:16.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:40:0) after 1m0s
[12:23:16.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:40:0)
[12:23:16.594] Timer tick, asking Buildbucket for the build status
[12:23:16.768] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:16.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:41:0) after 1m0s
[12:24:16.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:41:0)
[12:24:16.805] Timer tick, asking Buildbucket for the build status
[12:24:16.925] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:16.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:42:0) after 1m0s
[12:25:16.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:42:0)
[12:25:16.977] Timer tick, asking Buildbucket for the build status
[12:25:17.217] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:17.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:43:0) after 1m0s
[12:26:17.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:43:0)
[12:26:17.243] Timer tick, asking Buildbucket for the build status
[12:26:17.361] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:17.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:44:0) after 1m0s
[12:27:17.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:44:0)
[12:27:17.386] Timer tick, asking Buildbucket for the build status
[12:27:17.480] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:17.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:45:0) after 1m0s
[12:28:17.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:45:0)
[12:28:17.496] Timer tick, asking Buildbucket for the build status
[12:28:17.579] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:17.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:46:0) after 1m0s
[12:29:17.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:46:0)
[12:29:17.634] Timer tick, asking Buildbucket for the build status
[12:29:19.497] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:19.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:47:0) after 1m0s
[12:30:19.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:47:0)
[12:30:19.568] Timer tick, asking Buildbucket for the build status
[12:30:19.797] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:19.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:48:0) after 1m0s
[12:31:19.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:48:0)
[12:31:19.852] Timer tick, asking Buildbucket for the build status
[12:31:20.857] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:20.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:49:0) after 1m0s
[12:32:20.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:49:0)
[12:32:20.957] Timer tick, asking Buildbucket for the build status
[12:32:21.055] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:21.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:50:0) after 1m0s
[12:33:21.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:50:0)
[12:33:21.171] Timer tick, asking Buildbucket for the build status
[12:33:23.358] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:23.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:51:0) after 1m0s
[12:34:23.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:51:0)
[12:34:23.473] Timer tick, asking Buildbucket for the build status
[12:34:23.569] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:23.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:52:0) after 1m0s
[12:35:23.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:52:0)
[12:35:23.703] Timer tick, asking Buildbucket for the build status
[12:35:24.182] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:24.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:53:0) after 1m0s
[12:36:24.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:53:0)
[12:36:24.216] Timer tick, asking Buildbucket for the build status
[12:36:24.310] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:24.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:54:0) after 1m0s
[12:37:24.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:54:0)
[12:37:24.366] Timer tick, asking Buildbucket for the build status
[12:37:24.505] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:24.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:55:0) after 1m0s
[12:38:24.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:55:0)
[12:38:24.520] Timer tick, asking Buildbucket for the build status
[12:38:24.755] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:24.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:56:0) after 1m0s
[12:39:24.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:56:0)
[12:39:24.847] Timer tick, asking Buildbucket for the build status
[12:39:25.259] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:25.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:57:0) after 1m0s
[12:40:25.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:57:0)
[12:40:25.321] Timer tick, asking Buildbucket for the build status
[12:40:25.419] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:25.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:58:0) after 1m0s
[12:41:25.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:58:0)
[12:41:25.436] Timer tick, asking Buildbucket for the build status
[12:41:25.512] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:25.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:59:0) after 1m0s
[12:42:25.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:59:0)
[12:42:25.556] Timer tick, asking Buildbucket for the build status
[12:42:25.648] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:25.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:60:0) after 1m0s
[12:43:25.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:60:0)
[12:43:25.674] Timer tick, asking Buildbucket for the build status
[12:43:26.150] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:26.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:61:0) after 1m0s
[12:44:26.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:61:0)
[12:44:26.297] Timer tick, asking Buildbucket for the build status
[12:44:26.553] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:26.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:62:0) after 1m0s
[12:45:26.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:62:0)
[12:45:26.593] Timer tick, asking Buildbucket for the build status
[12:45:26.665] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:26.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:63:0) after 1m0s
[12:46:26.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:63:0)
[12:46:26.675] Timer tick, asking Buildbucket for the build status
[12:46:26.757] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:26.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:64:0) after 1m0s
[12:47:26.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:64:0)
[12:47:26.810] Timer tick, asking Buildbucket for the build status
[12:47:27.038] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:27.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:65:0) after 1m0s
[12:48:27.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:65:0)
[12:48:27.087] Timer tick, asking Buildbucket for the build status
[12:48:27.184] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:27.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:66:0) after 1m0s
[12:49:27.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:66:0)
[12:49:27.283] Timer tick, asking Buildbucket for the build status
[12:49:27.367] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:27.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:67:0) after 1m0s
[12:50:27.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:67:0)
[12:50:27.405] Timer tick, asking Buildbucket for the build status
[12:50:27.492] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:27.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:68:0) after 1m0s
[12:51:27.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:68:0)
[12:51:27.608] Timer tick, asking Buildbucket for the build status
[12:51:27.780] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:27.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:69:0) after 1m0s
[12:52:27.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:69:0)
[12:52:27.834] Timer tick, asking Buildbucket for the build status
[12:52:27.951] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:27.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:70:0) after 1m0s
[12:53:28.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:70:0)
[12:53:28.033] Timer tick, asking Buildbucket for the build status
[12:53:28.159] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:28.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:71:0) after 1m0s
[12:54:28.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:71:0)
[12:54:28.282] Timer tick, asking Buildbucket for the build status
[12:54:28.553] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:28.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:72:0) after 1m0s
[12:55:28.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:72:0)
[12:55:28.651] Timer tick, asking Buildbucket for the build status
[12:55:28.711] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:28.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:73:0) after 1m0s
[12:56:28.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:73:0)
[12:56:28.856] Timer tick, asking Buildbucket for the build status
[12:56:29.362] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:29.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:74:0) after 1m0s
[12:57:29.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:74:0)
[12:57:29.423] Timer tick, asking Buildbucket for the build status
[12:57:29.552] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:29.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:75:0) after 1m0s
[12:58:29.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:75:0)
[12:58:29.697] Timer tick, asking Buildbucket for the build status
[12:58:33.700] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:33.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:76:0) after 1m0s
[12:59:33.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:76:0)
[12:59:33.705] Timer tick, asking Buildbucket for the build status
[12:59:33.990] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:33.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:77:0) after 1m0s
[13:00:34.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:77:0)
[13:00:34.100] Timer tick, asking Buildbucket for the build status
[13:00:34.779] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:34.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:78:0) after 1m0s
[13:01:34.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:78:0)
[13:01:34.837] Timer tick, asking Buildbucket for the build status
[13:01:35.093] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:35.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:79:0) after 1m0s
[13:02:35.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:79:0)
[13:02:35.138] Timer tick, asking Buildbucket for the build status
[13:02:35.340] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:35.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:80:0) after 1m0s
[13:03:35.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:80:0)
[13:03:35.375] Timer tick, asking Buildbucket for the build status
[13:03:35.555] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:35.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:81:0) after 1m0s
[13:04:35.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:81:0)
[13:04:35.638] Timer tick, asking Buildbucket for the build status
[13:04:36.377] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:36.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:82:0) after 1m0s
[13:05:36.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:82:0)
[13:05:36.407] Timer tick, asking Buildbucket for the build status
[13:05:36.694] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:36.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:83:0) after 1m0s
[13:06:36.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:83:0)
[13:06:36.753] Timer tick, asking Buildbucket for the build status
[13:06:36.906] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:36.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:84:0) after 1m0s
[13:07:36.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:84:0)
[13:07:36.943] Timer tick, asking Buildbucket for the build status
[13:07:37.446] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:37.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:85:0) after 1m0s
[13:08:37.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:85:0)
[13:08:37.475] Timer tick, asking Buildbucket for the build status
[13:08:38.781] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:38.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:86:0) after 1m0s
[13:09:39.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:86:0)
[13:09:39.246] Timer tick, asking Buildbucket for the build status
[13:09:39.321] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:39.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:87:0) after 1m0s
[13:10:39.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:87:0)
[13:10:39.526] Timer tick, asking Buildbucket for the build status
[13:10:39.741] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:39.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:88:0) after 1m0s
[13:11:39.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:88:0)
[13:11:39.897] Timer tick, asking Buildbucket for the build status
[13:11:40.364] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:40.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:89:0) after 1m0s
[13:12:40.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:89:0)
[13:12:40.383] Timer tick, asking Buildbucket for the build status
[13:12:40.478] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:40.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:90:0) after 1m0s
[13:13:40.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:90:0)
[13:13:40.467] Timer tick, asking Buildbucket for the build status
[13:13:41.012] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:41.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:91:0) after 1m0s
[13:14:41.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:91:0)
[13:14:41.044] Timer tick, asking Buildbucket for the build status
[13:14:41.266] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:41.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:92:0) after 1m0s
[13:15:41.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:92:0)
[13:15:41.350] Timer tick, asking Buildbucket for the build status
[13:15:41.577] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:41.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:93:0) after 1m0s
[13:16:41.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:93:0)
[13:16:41.638] Timer tick, asking Buildbucket for the build status
[13:16:41.850] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:41.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:94:0) after 1m0s
[13:17:41.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:94:0)
[13:17:41.969] Timer tick, asking Buildbucket for the build status
[13:17:42.041] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:42.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:95:0) after 1m0s
[13:18:42.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:95:0)
[13:18:42.188] Timer tick, asking Buildbucket for the build status
[13:18:46.972] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:46.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:96:0) after 1m0s
[13:19:47.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:96:0)
[13:19:47.024] Timer tick, asking Buildbucket for the build status
[13:19:47.185] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:47.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:97:0) after 1m0s
[13:20:47.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:97:0)
[13:20:47.267] Timer tick, asking Buildbucket for the build status
[13:20:47.342] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:47.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:98:0) after 1m0s
[13:21:47.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:98:0)
[13:21:47.405] Timer tick, asking Buildbucket for the build status
[13:21:47.536] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:47.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:99:0) after 1m0s
[13:22:47.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:99:0)
[13:22:47.568] Timer tick, asking Buildbucket for the build status
[13:22:47.743] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:47.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:100:0) after 1m0s
[13:23:47.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:100:0)
[13:23:47.810] Timer tick, asking Buildbucket for the build status
[13:23:48.667] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:48.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:101:0) after 1m0s
[13:24:48.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:101:0)
[13:24:48.760] Timer tick, asking Buildbucket for the build status
[13:24:48.859] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:48.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:102:0) after 1m0s
[13:25:48.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:102:0)
[13:25:48.876] Timer tick, asking Buildbucket for the build status
[13:25:48.950] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:48.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:103:0) after 1m0s
[13:26:48.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:103:0)
[13:26:48.969] Timer tick, asking Buildbucket for the build status
[13:26:49.058] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:49.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:104:0) after 1m0s
[13:27:49.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:104:0)
[13:27:49.083] Timer tick, asking Buildbucket for the build status
[13:27:49.203] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:49.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:105:0) after 1m0s
[13:28:49.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:105:0)
[13:28:49.257] Timer tick, asking Buildbucket for the build status
[13:28:52.748] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:52.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:106:0) after 1m0s
[13:29:52.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:106:0)
[13:29:52.786] Timer tick, asking Buildbucket for the build status
[13:29:52.873] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:52.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:107:0) after 1m0s
[13:30:52.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:107:0)
[13:30:52.910] Timer tick, asking Buildbucket for the build status
[13:30:52.978] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:52.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:108:0) after 1m0s
[13:31:53.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:108:0)
[13:31:53.002] Timer tick, asking Buildbucket for the build status
[13:31:53.169] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:53.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:109:0) after 1m0s
[13:32:53.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:109:0)
[13:32:53.270] Timer tick, asking Buildbucket for the build status
[13:32:53.362] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:53.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:110:0) after 1m0s
[13:33:53.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:110:0)
[13:33:53.387] Timer tick, asking Buildbucket for the build status
[13:33:53.657] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:53.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:111:0) after 1m0s
[13:34:53.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:111:0)
[13:34:53.639] Timer tick, asking Buildbucket for the build status
[13:34:53.703] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:53.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:112:0) after 1m0s
[13:35:53.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:112:0)
[13:35:53.745] Timer tick, asking Buildbucket for the build status
[13:35:53.868] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:53.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:113:0) after 1m0s
[13:36:53.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:113:0)
[13:36:53.892] Timer tick, asking Buildbucket for the build status
[13:36:53.999] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:53.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:114:0) after 1m0s
[13:37:53.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:114:0)
[13:37:53.996] Timer tick, asking Buildbucket for the build status
[13:37:54.094] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:54.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:115:0) after 1m0s
[13:38:54.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:115:0)
[13:38:54.129] Timer tick, asking Buildbucket for the build status
[13:38:57.846] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:57.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:116:0) after 1m0s
[13:39:57.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:116:0)
[13:39:57.895] Timer tick, asking Buildbucket for the build status
[13:39:58.937] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:58.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:117:0) after 1m0s
[13:40:58.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:117:0)
[13:40:58.942] Timer tick, asking Buildbucket for the build status
[13:40:59.073] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:59.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:118:0) after 1m0s
[13:41:59.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:118:0)
[13:41:59.074] Timer tick, asking Buildbucket for the build status
[13:41:59.321] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:59.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:119:0) after 1m0s
[13:42:59.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:119:0)
[13:42:59.347] Timer tick, asking Buildbucket for the build status
[13:42:59.547] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:59.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:120:0) after 1m0s
[13:43:59.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:120:0)
[13:43:59.652] Timer tick, asking Buildbucket for the build status
[13:44:01.451] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:01.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:121:0) after 1m0s
[13:45:02.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:121:0)
[13:45:02.314] Timer tick, asking Buildbucket for the build status
[13:45:02.546] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:02.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:122:0) after 1m0s
[13:46:02.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:122:0)
[13:46:02.734] Timer tick, asking Buildbucket for the build status
[13:46:02.982] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:02.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:123:0) after 1m0s
[13:47:03.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:123:0)
[13:47:03.074] Timer tick, asking Buildbucket for the build status
[13:47:03.970] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:03.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:124:0) after 1m0s
[13:48:04.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:124:0)
[13:48:04.119] Timer tick, asking Buildbucket for the build status
[13:48:04.246] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:04.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:125:0) after 1m0s
[13:49:04.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:125:0)
[13:49:04.305] Timer tick, asking Buildbucket for the build status
[13:49:04.402] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:04.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:126:0) after 1m0s
[13:50:04.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:126:0)
[13:50:04.521] Timer tick, asking Buildbucket for the build status
[13:50:04.593] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:04.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:127:0) after 1m0s
[13:51:04.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:127:0)
[13:51:04.650] Timer tick, asking Buildbucket for the build status
[13:51:04.809] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:04.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:128:0) after 1m0s
[13:52:04.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:128:0)
[13:52:04.880] Timer tick, asking Buildbucket for the build status
[13:52:04.940] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:04.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:129:0) after 1m0s
[13:53:05.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:129:0)
[13:53:05.116] Timer tick, asking Buildbucket for the build status
[13:53:06.151] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:06.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:130:0) after 1m0s
[13:54:06.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:130:0)
[13:54:06.234] Timer tick, asking Buildbucket for the build status
[13:54:06.575] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:06.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:131:0) after 1m0s
[13:55:06.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:131:0)
[13:55:06.654] Timer tick, asking Buildbucket for the build status
[13:55:06.828] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:06.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:132:0) after 1m0s
[13:56:06.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:132:0)
[13:56:06.885] Timer tick, asking Buildbucket for the build status
[13:56:06.962] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:06.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:133:0) after 1m0s
[13:57:07.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:133:0)
[13:57:07.043] Timer tick, asking Buildbucket for the build status
[13:57:07.165] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:07.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:134:0) after 1m0s
[13:58:07.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:134:0)
[13:58:07.231] Timer tick, asking Buildbucket for the build status
[13:58:07.891] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:07.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:135:0) after 1m0s
[13:59:07.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:135:0)
[13:59:07.923] Timer tick, asking Buildbucket for the build status
[13:59:08.056] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:08.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:136:0) after 1m0s
[14:00:08.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:136:0)
[14:00:08.190] Timer tick, asking Buildbucket for the build status
[14:00:08.343] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:08.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:137:0) after 1m0s
[14:01:08.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:137:0)
[14:01:08.510] Timer tick, asking Buildbucket for the build status
[14:01:10.283] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:10.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:138:0) after 1m0s
[14:02:10.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:138:0)
[14:02:10.382] Timer tick, asking Buildbucket for the build status
[14:02:11.077] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:11.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:139:0) after 1m0s
[14:03:11.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:139:0)
[14:03:11.140] Timer tick, asking Buildbucket for the build status
[14:03:11.225] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:11.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:140:0) after 1m0s
[14:04:11.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:140:0)
[14:04:11.312] Timer tick, asking Buildbucket for the build status
[14:04:11.515] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:11.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:141:0) after 1m0s
[14:05:11.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:141:0)
[14:05:11.609] Timer tick, asking Buildbucket for the build status
[14:05:11.991] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:11.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:142:0) after 1m0s
[14:06:12.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:142:0)
[14:06:12.088] Timer tick, asking Buildbucket for the build status
[14:06:12.326] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:12.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:143:0) after 1m0s
[14:07:12.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:143:0)
[14:07:12.454] Timer tick, asking Buildbucket for the build status
[14:07:12.602] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:12.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:144:0) after 1m0s
[14:08:12.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:144:0)
[14:08:12.665] Timer tick, asking Buildbucket for the build status
[14:08:12.785] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:12.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:145:0) after 1m0s
[14:09:12.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:145:0)
[14:09:12.965] Timer tick, asking Buildbucket for the build status
[14:09:13.462] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:13.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:146:0) after 1m0s
[14:10:13.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:146:0)
[14:10:13.667] Timer tick, asking Buildbucket for the build status
[14:10:13.824] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:13.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:147:0) after 1m0s
[14:11:13.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:147:0)
[14:11:13.994] Timer tick, asking Buildbucket for the build status
[14:11:14.566] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:14.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:148:0) after 1m0s
[14:12:14.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:148:0)
[14:12:14.738] Timer tick, asking Buildbucket for the build status
[14:12:14.972] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:14.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:149:0) after 1m0s
[14:13:15.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:149:0)
[14:13:15.099] Timer tick, asking Buildbucket for the build status
[14:13:15.199] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:15.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:150:0) after 1m0s
[14:14:15.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:150:0)
[14:14:15.330] Timer tick, asking Buildbucket for the build status
[14:14:15.791] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:15.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:151:0) after 1m0s
[14:15:15.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:151:0)
[14:15:15.969] Timer tick, asking Buildbucket for the build status
[14:15:16.189] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:16.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:152:0) after 1m0s
[14:16:16.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:152:0)
[14:16:16.381] Timer tick, asking Buildbucket for the build status
[14:16:16.864] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:16.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:153:0) after 1m0s
[14:17:16.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:153:0)
[14:17:16.968] Timer tick, asking Buildbucket for the build status
[14:17:17.064] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:17.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:154:0) after 1m0s
[14:18:17.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:154:0)
[14:18:17.094] Timer tick, asking Buildbucket for the build status
[14:18:17.237] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:17.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:155:0) after 1m0s
[14:19:17.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:155:0)
[14:19:17.344] Timer tick, asking Buildbucket for the build status
[14:19:17.499] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:17.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:156:0) after 1m0s
[14:20:17.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:156:0)
[14:20:17.602] Timer tick, asking Buildbucket for the build status
[14:20:17.709] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:17.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:157:0) after 1m0s
[14:21:17.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:157:0)
[14:21:17.840] Timer tick, asking Buildbucket for the build status
[14:21:17.992] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:17.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:158:0) after 1m0s
[14:22:18.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:158:0)
[14:22:18.026] Timer tick, asking Buildbucket for the build status
[14:22:18.433] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:18.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:159:0) after 1m0s
[14:23:18.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:159:0)
[14:23:18.469] Timer tick, asking Buildbucket for the build status
[14:23:18.782] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:18.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:160:0) after 1m0s
[14:24:18.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:160:0)
[14:24:18.780] Timer tick, asking Buildbucket for the build status
[14:24:18.910] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:18.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:161:0) after 1m0s
[14:25:19.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:161:0)
[14:25:19.016] Timer tick, asking Buildbucket for the build status
[14:25:19.153] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:19.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:162:0) after 1m0s
[14:26:19.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:162:0)
[14:26:19.302] Timer tick, asking Buildbucket for the build status
[14:26:19.469] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:19.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:163:0) after 1m0s
[14:27:19.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:163:0)
[14:27:19.561] Timer tick, asking Buildbucket for the build status
[14:27:19.992] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:19.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:164:0) after 1m0s
[14:28:20.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:164:0)
[14:28:20.042] Timer tick, asking Buildbucket for the build status
[14:28:20.169] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:20.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:165:0) after 1m0s
[14:29:20.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:165:0)
[14:29:20.243] Timer tick, asking Buildbucket for the build status
[14:29:20.602] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:20.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:166:0) after 1m0s
[14:30:20.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:166:0)
[14:30:20.658] Timer tick, asking Buildbucket for the build status
[14:30:20.789] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:20.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:167:0) after 1m0s
[14:31:20.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:167:0)
[14:31:20.873] Timer tick, asking Buildbucket for the build status
[14:31:21.228] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:21.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:168:0) after 1m0s
[14:32:21.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:168:0)
[14:32:21.231] Timer tick, asking Buildbucket for the build status
[14:32:21.537] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:21.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:169:0) after 1m0s
[14:33:21.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:169:0)
[14:33:21.607] Timer tick, asking Buildbucket for the build status
[14:33:21.775] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:21.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:170:0) after 1m0s
[14:34:21.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:170:0)
[14:34:21.877] Timer tick, asking Buildbucket for the build status
[14:34:21.949] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:21.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:171:0) after 1m0s
[14:35:22.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:171:0)
[14:35:22.192] Timer tick, asking Buildbucket for the build status
[14:35:24.739] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:24.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:172:0) after 1m0s
[14:36:24.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:172:0)
[14:36:24.777] Timer tick, asking Buildbucket for the build status
[14:36:24.905] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:24.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:173:0) after 1m0s
[14:37:24.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:173:0)
[14:37:24.970] Timer tick, asking Buildbucket for the build status
[14:37:26.328] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:26.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:174:0) after 1m0s
[14:38:26.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:174:0)
[14:38:26.462] Timer tick, asking Buildbucket for the build status
[14:38:26.817] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:26.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:175:0) after 1m0s
[14:39:26.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:175:0)
[14:39:26.888] Timer tick, asking Buildbucket for the build status
[14:39:27.026] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:27.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:176:0) after 1m0s
[14:40:27.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:176:0)
[14:40:27.113] Timer tick, asking Buildbucket for the build status
[14:40:27.374] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:27.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:177:0) after 1m0s
[14:41:27.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:177:0)
[14:41:27.487] Timer tick, asking Buildbucket for the build status
[14:41:27.659] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:27.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:178:0) after 1m0s
[14:42:27.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:178:0)
[14:42:27.742] Timer tick, asking Buildbucket for the build status
[14:42:27.828] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:27.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:179:0) after 1m0s
[14:43:27.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:179:0)
[14:43:27.880] Timer tick, asking Buildbucket for the build status
[14:43:28.237] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:28.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:180:0) after 1m0s
[14:44:28.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:180:0)
[14:44:28.337] Timer tick, asking Buildbucket for the build status
[14:44:28.664] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:28.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:181:0) after 1m0s
[14:45:28.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:181:0)
[14:45:28.730] Timer tick, asking Buildbucket for the build status
[14:45:28.931] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:28.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:182:0) after 1m0s
[14:46:29.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:182:0)
[14:46:29.107] Timer tick, asking Buildbucket for the build status
[14:46:29.195] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:29.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:183:0) after 1m0s
[14:47:29.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:183:0)
[14:47:29.298] Timer tick, asking Buildbucket for the build status
[14:47:29.730] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:29.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:184:0) after 1m0s
[14:48:29.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:184:0)
[14:48:29.818] Timer tick, asking Buildbucket for the build status
[14:48:35.875] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:35.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:185:0) after 1m0s
[14:49:35.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:185:0)
[14:49:35.964] Timer tick, asking Buildbucket for the build status
[14:49:36.054] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:36.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:186:0) after 1m0s
[14:50:36.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:186:0)
[14:50:36.114] Timer tick, asking Buildbucket for the build status
[14:50:36.200] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:36.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:187:0) after 1m0s
[14:51:36.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:187:0)
[14:51:36.251] Timer tick, asking Buildbucket for the build status
[14:51:36.360] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:36.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:188:0) after 1m0s
[14:52:36.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:188:0)
[14:52:36.432] Timer tick, asking Buildbucket for the build status
[14:52:36.850] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:36.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:189:0) after 1m0s
[14:53:36.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:189:0)
[14:53:36.906] Timer tick, asking Buildbucket for the build status
[14:53:37.363] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:37.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:190:0) after 1m0s
[14:54:37.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:190:0)
[14:54:37.537] Timer tick, asking Buildbucket for the build status
[14:54:37.758] Build 8876703691020915424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:37.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042133753046703616:191:0) after 1m0s
[14:55:27.058] Received PubSub notification, asking Buildbucket for the build status
[14:55:27.136] Build 8876703691020915424: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:55:27.136] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1592924125662774",
    "created_by": "project:infra",
    "created_ts": "1592912697732359",
    "id": "8876703691020915424",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4179.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"83992248-5364-4e94-8183-b23a957af0f4\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4179.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-6vz3\"], \"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.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1592912945977610",
    "status": "COMPLETED",
    "status_changed_ts": "1592924126492421",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042133753046703616",
      "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/8876703691020915424/+/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:4cf9d35f50e5cf10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1592924126559866",
    "url": "https://ci.chromium.org/b/8876703691020915424",
    "utcnow_ts": "1592924127126588"
  }
}
[14:55:27.136] Invocation finished in 3h10m30.719315979s with status SUCCEEDED