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.4217.2"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[05:37:02.560] New invocation is queued and will start shortly
[05:37:02.560] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[05:37:04.140] Starting the invocation (attempt 1)
[05:37:04.194] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:37:04.194] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:37:04.194] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9038714214465636592",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4217.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"d2b332c4-cee1-4cc7-954f-b836d89cea3d\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4217.2\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9038714214465636592",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[05:37:04.973] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1596173824354028",
    "id": "8873284151912123696",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4217.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d2b332c4-cee1-4cc7-954f-b836d89cea3d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4217.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1596173824780448",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9038714214465636592",
      "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/8873284151912123696/+/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": "1596173824780605",
    "url": "https://ci.chromium.org/b/8873284151912123696",
    "utcnow_ts": "1596173824958188"
  }
}
[05:37:04.973] Task URL: https://ci.chromium.org/b/8873284151912123696
[05:37:04.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:2:0) after 1m0s
[05:37:42.228] Received PubSub notification, asking Buildbucket for the build status
[05:37:42.327] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:05.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:2:0)
[05:38:05.129] Timer tick, asking Buildbucket for the build status
[05:38:05.311] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:05.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:4:0) after 1m0s
[05:39:05.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:4:0)
[05:39:05.416] Timer tick, asking Buildbucket for the build status
[05:39:05.556] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:05.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:5:0) after 1m0s
[05:40:05.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:5:0)
[05:40:05.696] Timer tick, asking Buildbucket for the build status
[05:40:05.979] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:05.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:6:0) after 1m0s
[05:41:06.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:6:0)
[05:41:06.114] Timer tick, asking Buildbucket for the build status
[05:41:06.227] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:06.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:7:0) after 1m0s
[05:42:06.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:7:0)
[05:42:06.239] Timer tick, asking Buildbucket for the build status
[05:42:06.356] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:06.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:8:0) after 1m0s
[05:43:06.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:8:0)
[05:43:06.526] Timer tick, asking Buildbucket for the build status
[05:43:06.726] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:06.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:9:0) after 1m0s
[05:44:06.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:9:0)
[05:44:06.937] Timer tick, asking Buildbucket for the build status
[05:44:07.027] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:07.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:10:0) after 1m0s
[05:45:07.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:10:0)
[05:45:07.115] Timer tick, asking Buildbucket for the build status
[05:45:07.500] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:07.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:11:0) after 1m0s
[05:46:07.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:11:0)
[05:46:07.615] Timer tick, asking Buildbucket for the build status
[05:46:07.996] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:07.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:12:0) after 1m0s
[05:47:08.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:12:0)
[05:47:08.041] Timer tick, asking Buildbucket for the build status
[05:47:08.434] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:08.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:13:0) after 1m0s
[05:48:08.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:13:0)
[05:48:08.703] Timer tick, asking Buildbucket for the build status
[05:48:08.806] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:08.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:14:0) after 1m0s
[05:49:08.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:14:0)
[05:49:08.874] Timer tick, asking Buildbucket for the build status
[05:49:09.036] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:09.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:15:0) after 1m0s
[05:50:09.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:15:0)
[05:50:09.194] Timer tick, asking Buildbucket for the build status
[05:50:09.582] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:09.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:16:0) after 1m0s
[05:51:09.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:16:0)
[05:51:09.673] Timer tick, asking Buildbucket for the build status
[05:51:09.988] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:09.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:17:0) after 1m0s
[05:52:10.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:17:0)
[05:52:10.012] Timer tick, asking Buildbucket for the build status
[05:52:10.895] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:10.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:18:0) after 1m0s
[05:53:10.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:18:0)
[05:53:10.987] Timer tick, asking Buildbucket for the build status
[05:53:11.062] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:11.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:19:0) after 1m0s
[05:54:11.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:19:0)
[05:54:11.215] Timer tick, asking Buildbucket for the build status
[05:54:11.552] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:11.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:20:0) after 1m0s
[05:55:11.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:20:0)
[05:55:11.626] Timer tick, asking Buildbucket for the build status
[05:55:11.697] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:11.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:21:0) after 1m0s
[05:56:11.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:21:0)
[05:56:11.773] Timer tick, asking Buildbucket for the build status
[05:56:11.891] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:11.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:22:0) after 1m0s
[05:57:11.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:22:0)
[05:57:11.976] Timer tick, asking Buildbucket for the build status
[05:57:12.168] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:12.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:23:0) after 1m0s
[05:58:12.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:23:0)
[05:58:12.283] Timer tick, asking Buildbucket for the build status
[05:58:12.417] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:12.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:24:0) after 1m0s
[05:59:12.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:24:0)
[05:59:12.442] Timer tick, asking Buildbucket for the build status
[05:59:13.083] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:13.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:25:0) after 1m0s
[06:00:13.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:25:0)
[06:00:13.211] Timer tick, asking Buildbucket for the build status
[06:00:13.432] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:13.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:26:0) after 1m0s
[06:01:13.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:26:0)
[06:01:13.489] Timer tick, asking Buildbucket for the build status
[06:01:13.584] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:13.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:27:0) after 1m0s
[06:02:13.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:27:0)
[06:02:13.682] Timer tick, asking Buildbucket for the build status
[06:02:13.820] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:13.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:28:0) after 1m0s
[06:03:13.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:28:0)
[06:03:13.951] Timer tick, asking Buildbucket for the build status
[06:03:14.073] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:14.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:29:0) after 1m0s
[06:04:14.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:29:0)
[06:04:14.108] Timer tick, asking Buildbucket for the build status
[06:04:14.201] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:14.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:30:0) after 1m0s
[06:05:14.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:30:0)
[06:05:14.393] Timer tick, asking Buildbucket for the build status
[06:05:14.554] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:14.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:31:0) after 1m0s
[06:06:14.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:31:0)
[06:06:14.891] Timer tick, asking Buildbucket for the build status
[06:06:14.998] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:14.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:32:0) after 1m0s
[06:07:15.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:32:0)
[06:07:15.057] Timer tick, asking Buildbucket for the build status
[06:07:15.601] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:15.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:33:0) after 1m0s
[06:08:15.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:33:0)
[06:08:15.690] Timer tick, asking Buildbucket for the build status
[06:08:15.816] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:15.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:34:0) after 1m0s
[06:09:15.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:34:0)
[06:09:15.814] Timer tick, asking Buildbucket for the build status
[06:09:15.920] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:15.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:35:0) after 1m0s
[06:10:15.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:35:0)
[06:10:15.978] Timer tick, asking Buildbucket for the build status
[06:10:16.065] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:16.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:36:0) after 1m0s
[06:11:16.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:36:0)
[06:11:16.097] Timer tick, asking Buildbucket for the build status
[06:11:16.246] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:16.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:37:0) after 1m0s
[06:12:16.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:37:0)
[06:12:16.327] Timer tick, asking Buildbucket for the build status
[06:12:16.472] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:16.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:38:0) after 1m0s
[06:13:16.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:38:0)
[06:13:16.547] Timer tick, asking Buildbucket for the build status
[06:13:16.631] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:16.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:39:0) after 1m0s
[06:14:16.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:39:0)
[06:14:16.722] Timer tick, asking Buildbucket for the build status
[06:14:16.803] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:16.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:40:0) after 1m0s
[06:15:16.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:40:0)
[06:15:16.925] Timer tick, asking Buildbucket for the build status
[06:15:17.135] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:17.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:41:0) after 1m0s
[06:16:17.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:41:0)
[06:16:17.244] Timer tick, asking Buildbucket for the build status
[06:16:17.328] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:17.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:42:0) after 1m0s
[06:17:17.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:42:0)
[06:17:17.316] Timer tick, asking Buildbucket for the build status
[06:17:17.438] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:17.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:43:0) after 1m0s
[06:18:17.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:43:0)
[06:18:17.470] Timer tick, asking Buildbucket for the build status
[06:18:17.539] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:17.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:44:0) after 1m0s
[06:19:17.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:44:0)
[06:19:17.512] Timer tick, asking Buildbucket for the build status
[06:19:17.621] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:17.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:45:0) after 1m0s
[06:20:17.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:45:0)
[06:20:17.685] Timer tick, asking Buildbucket for the build status
[06:20:17.907] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:17.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:46:0) after 1m0s
[06:21:18.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:46:0)
[06:21:18.008] Timer tick, asking Buildbucket for the build status
[06:21:18.088] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:18.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:47:0) after 1m0s
[06:22:18.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:47:0)
[06:22:18.109] Timer tick, asking Buildbucket for the build status
[06:22:18.249] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:18.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:48:0) after 1m0s
[06:23:18.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:48:0)
[06:23:18.304] Timer tick, asking Buildbucket for the build status
[06:23:18.663] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:18.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:49:0) after 1m0s
[06:24:18.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:49:0)
[06:24:18.741] Timer tick, asking Buildbucket for the build status
[06:24:18.845] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:18.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:50:0) after 1m0s
[06:25:18.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:50:0)
[06:25:18.870] Timer tick, asking Buildbucket for the build status
[06:25:18.952] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:18.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:51:0) after 1m0s
[06:26:19.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:51:0)
[06:26:19.011] Timer tick, asking Buildbucket for the build status
[06:26:19.202] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:19.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:52:0) after 1m0s
[06:27:19.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:52:0)
[06:27:19.243] Timer tick, asking Buildbucket for the build status
[06:27:19.491] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:19.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:53:0) after 1m0s
[06:28:19.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:53:0)
[06:28:19.695] Timer tick, asking Buildbucket for the build status
[06:28:19.851] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:19.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:54:0) after 1m0s
[06:29:19.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:54:0)
[06:29:19.999] Timer tick, asking Buildbucket for the build status
[06:29:20.161] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:20.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:55:0) after 1m0s
[06:30:20.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:55:0)
[06:30:20.150] Timer tick, asking Buildbucket for the build status
[06:30:20.233] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:20.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:56:0) after 1m0s
[06:31:20.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:56:0)
[06:31:20.293] Timer tick, asking Buildbucket for the build status
[06:31:20.375] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:20.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:57:0) after 1m0s
[06:32:20.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:57:0)
[06:32:20.594] Timer tick, asking Buildbucket for the build status
[06:32:20.931] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:20.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:58:0) after 1m0s
[06:33:21.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:58:0)
[06:33:21.044] Timer tick, asking Buildbucket for the build status
[06:33:21.118] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:21.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:59:0) after 1m0s
[06:34:21.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:59:0)
[06:34:21.231] Timer tick, asking Buildbucket for the build status
[06:34:21.331] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:21.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:60:0) after 1m0s
[06:35:21.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:60:0)
[06:35:21.513] Timer tick, asking Buildbucket for the build status
[06:35:21.599] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:21.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:61:0) after 1m0s
[06:36:21.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:61:0)
[06:36:21.668] Timer tick, asking Buildbucket for the build status
[06:36:21.739] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:21.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:62:0) after 1m0s
[06:37:21.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:62:0)
[06:37:21.831] Timer tick, asking Buildbucket for the build status
[06:37:22.048] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:22.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:63:0) after 1m0s
[06:38:22.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:63:0)
[06:38:22.107] Timer tick, asking Buildbucket for the build status
[06:38:22.198] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:22.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:64:0) after 1m0s
[06:39:22.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:64:0)
[06:39:22.366] Timer tick, asking Buildbucket for the build status
[06:39:22.647] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:22.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:65:0) after 1m0s
[06:40:22.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:65:0)
[06:40:22.754] Timer tick, asking Buildbucket for the build status
[06:40:22.842] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:22.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:66:0) after 1m0s
[06:41:22.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:66:0)
[06:41:22.909] Timer tick, asking Buildbucket for the build status
[06:41:22.992] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:22.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:67:0) after 1m0s
[06:42:23.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:67:0)
[06:42:23.244] Timer tick, asking Buildbucket for the build status
[06:42:23.542] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:23.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:68:0) after 1m0s
[06:43:23.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:68:0)
[06:43:23.618] Timer tick, asking Buildbucket for the build status
[06:43:24.686] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:24.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:69:0) after 1m0s
[06:44:24.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:69:0)
[06:44:24.871] Timer tick, asking Buildbucket for the build status
[06:44:25.055] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:25.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:70:0) after 1m0s
[06:45:25.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:70:0)
[06:45:25.139] Timer tick, asking Buildbucket for the build status
[06:45:25.214] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:25.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:71:0) after 1m0s
[06:46:25.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:71:0)
[06:46:25.331] Timer tick, asking Buildbucket for the build status
[06:46:25.425] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:25.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:72:0) after 1m0s
[06:47:25.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:72:0)
[06:47:25.644] Timer tick, asking Buildbucket for the build status
[06:47:25.793] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:25.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:73:0) after 1m0s
[06:48:25.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:73:0)
[06:48:25.876] Timer tick, asking Buildbucket for the build status
[06:48:26.143] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:26.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:74:0) after 1m0s
[06:49:26.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:74:0)
[06:49:26.246] Timer tick, asking Buildbucket for the build status
[06:49:26.328] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:26.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:75:0) after 1m0s
[06:50:26.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:75:0)
[06:50:26.490] Timer tick, asking Buildbucket for the build status
[06:50:26.561] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:26.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:76:0) after 1m0s
[06:51:26.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:76:0)
[06:51:26.667] Timer tick, asking Buildbucket for the build status
[06:51:26.742] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:26.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:77:0) after 1m0s
[06:52:26.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:77:0)
[06:52:26.910] Timer tick, asking Buildbucket for the build status
[06:52:27.066] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:27.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:78:0) after 1m0s
[06:53:27.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:78:0)
[06:53:27.168] Timer tick, asking Buildbucket for the build status
[06:53:27.252] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:27.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:79:0) after 1m0s
[06:54:27.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:79:0)
[06:54:27.359] Timer tick, asking Buildbucket for the build status
[06:54:28.084] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:28.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:80:0) after 1m0s
[06:55:28.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:80:0)
[06:55:28.112] Timer tick, asking Buildbucket for the build status
[06:55:28.208] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:28.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:81:0) after 1m0s
[06:56:28.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:81:0)
[06:56:28.259] Timer tick, asking Buildbucket for the build status
[06:56:28.358] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:28.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:82:0) after 1m0s
[06:57:28.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:82:0)
[06:57:28.461] Timer tick, asking Buildbucket for the build status
[06:57:28.679] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:28.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:83:0) after 1m0s
[06:58:28.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:83:0)
[06:58:28.761] Timer tick, asking Buildbucket for the build status
[06:58:28.993] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:28.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:84:0) after 1m0s
[06:59:29.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:84:0)
[06:59:29.046] Timer tick, asking Buildbucket for the build status
[06:59:29.131] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:29.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:85:0) after 1m0s
[07:00:29.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:85:0)
[07:00:29.391] Timer tick, asking Buildbucket for the build status
[07:00:29.550] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:29.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:86:0) after 1m0s
[07:01:29.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:86:0)
[07:01:29.617] Timer tick, asking Buildbucket for the build status
[07:01:29.699] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:29.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:87:0) after 1m0s
[07:02:29.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:87:0)
[07:02:29.965] Timer tick, asking Buildbucket for the build status
[07:02:30.107] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:30.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:88:0) after 1m0s
[07:03:30.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:88:0)
[07:03:30.174] Timer tick, asking Buildbucket for the build status
[07:03:30.513] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:30.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:89:0) after 1m0s
[07:04:30.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:89:0)
[07:04:30.547] Timer tick, asking Buildbucket for the build status
[07:04:30.756] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:30.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:90:0) after 1m0s
[07:05:30.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:90:0)
[07:05:30.910] Timer tick, asking Buildbucket for the build status
[07:05:31.233] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:31.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:91:0) after 1m0s
[07:06:31.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:91:0)
[07:06:31.406] Timer tick, asking Buildbucket for the build status
[07:06:31.500] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:31.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:92:0) after 1m0s
[07:07:31.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:92:0)
[07:07:31.513] Timer tick, asking Buildbucket for the build status
[07:07:31.781] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:31.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:93:0) after 1m0s
[07:08:31.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:93:0)
[07:08:31.830] Timer tick, asking Buildbucket for the build status
[07:08:32.133] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:32.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:94:0) after 1m0s
[07:09:32.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:94:0)
[07:09:32.171] Timer tick, asking Buildbucket for the build status
[07:09:32.243] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:32.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:95:0) after 1m0s
[07:10:32.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:95:0)
[07:10:32.391] Timer tick, asking Buildbucket for the build status
[07:10:32.480] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:32.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:96:0) after 1m0s
[07:11:32.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:96:0)
[07:11:32.557] Timer tick, asking Buildbucket for the build status
[07:11:32.636] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:32.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:97:0) after 1m0s
[07:12:32.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:97:0)
[07:12:32.697] Timer tick, asking Buildbucket for the build status
[07:12:32.821] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:32.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:98:0) after 1m0s
[07:13:32.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:98:0)
[07:13:32.915] Timer tick, asking Buildbucket for the build status
[07:13:33.030] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:33.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:99:0) after 1m0s
[07:14:33.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:99:0)
[07:14:33.114] Timer tick, asking Buildbucket for the build status
[07:14:33.220] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:33.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:100:0) after 1m0s
[07:15:33.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:100:0)
[07:15:33.352] Timer tick, asking Buildbucket for the build status
[07:15:33.532] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:33.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:101:0) after 1m0s
[07:16:33.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:101:0)
[07:16:33.603] Timer tick, asking Buildbucket for the build status
[07:16:33.696] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:33.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:102:0) after 1m0s
[07:17:33.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:102:0)
[07:17:33.719] Timer tick, asking Buildbucket for the build status
[07:17:33.940] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:33.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:103:0) after 1m0s
[07:18:34.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:103:0)
[07:18:34.016] Timer tick, asking Buildbucket for the build status
[07:18:34.326] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:34.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:104:0) after 1m0s
[07:19:34.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:104:0)
[07:19:34.356] Timer tick, asking Buildbucket for the build status
[07:19:35.746] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:35.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:105:0) after 1m0s
[07:20:35.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:105:0)
[07:20:35.781] Timer tick, asking Buildbucket for the build status
[07:20:35.867] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:35.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:106:0) after 1m0s
[07:21:35.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:106:0)
[07:21:35.874] Timer tick, asking Buildbucket for the build status
[07:21:36.151] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:36.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:107:0) after 1m0s
[07:22:36.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:107:0)
[07:22:36.214] Timer tick, asking Buildbucket for the build status
[07:22:36.431] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:36.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:108:0) after 1m0s
[07:23:36.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:108:0)
[07:23:36.468] Timer tick, asking Buildbucket for the build status
[07:23:36.569] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:36.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:109:0) after 1m0s
[07:24:36.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:109:0)
[07:24:36.648] Timer tick, asking Buildbucket for the build status
[07:24:36.828] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:36.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:110:0) after 1m0s
[07:25:36.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:110:0)
[07:25:36.912] Timer tick, asking Buildbucket for the build status
[07:25:37.036] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:37.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:111:0) after 1m0s
[07:26:37.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:111:0)
[07:26:37.112] Timer tick, asking Buildbucket for the build status
[07:26:37.370] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:37.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:112:0) after 1m0s
[07:27:37.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:112:0)
[07:27:37.407] Timer tick, asking Buildbucket for the build status
[07:27:37.544] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:37.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:113:0) after 1m0s
[07:28:37.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:113:0)
[07:28:37.568] Timer tick, asking Buildbucket for the build status
[07:28:37.640] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:37.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:114:0) after 1m0s
[07:29:37.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:114:0)
[07:29:37.665] Timer tick, asking Buildbucket for the build status
[07:29:37.729] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:37.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:115:0) after 1m0s
[07:30:37.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:115:0)
[07:30:37.884] Timer tick, asking Buildbucket for the build status
[07:30:37.952] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:37.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:116:0) after 1m0s
[07:31:38.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:116:0)
[07:31:38.002] Timer tick, asking Buildbucket for the build status
[07:31:38.087] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:38.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:117:0) after 1m0s
[07:32:38.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:117:0)
[07:32:38.219] Timer tick, asking Buildbucket for the build status
[07:32:38.346] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:38.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:118:0) after 1m0s
[07:33:38.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:118:0)
[07:33:38.376] Timer tick, asking Buildbucket for the build status
[07:33:38.664] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:38.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:119:0) after 1m0s
[07:34:38.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:119:0)
[07:34:38.696] Timer tick, asking Buildbucket for the build status
[07:34:38.791] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:38.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:120:0) after 1m0s
[07:35:38.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:120:0)
[07:35:38.823] Timer tick, asking Buildbucket for the build status
[07:35:38.907] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:38.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:121:0) after 1m0s
[07:36:38.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:121:0)
[07:36:38.925] Timer tick, asking Buildbucket for the build status
[07:36:38.990] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:38.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:122:0) after 1m0s
[07:37:39.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:122:0)
[07:37:39.111] Timer tick, asking Buildbucket for the build status
[07:37:39.402] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:39.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:123:0) after 1m0s
[07:38:39.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:123:0)
[07:38:39.507] Timer tick, asking Buildbucket for the build status
[07:38:39.713] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:39.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:124:0) after 1m0s
[07:39:39.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:124:0)
[07:39:39.797] Timer tick, asking Buildbucket for the build status
[07:39:40.482] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:40.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:125:0) after 1m0s
[07:40:40.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:125:0)
[07:40:40.728] Timer tick, asking Buildbucket for the build status
[07:40:40.808] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:40.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:126:0) after 1m0s
[07:41:40.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:126:0)
[07:41:40.919] Timer tick, asking Buildbucket for the build status
[07:41:41.003] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:41.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:127:0) after 1m0s
[07:42:41.253] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:127:0)
[07:42:41.253] Timer tick, asking Buildbucket for the build status
[07:42:41.374] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:41.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:128:0) after 1m0s
[07:43:41.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:128:0)
[07:43:41.451] Timer tick, asking Buildbucket for the build status
[07:43:41.518] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:41.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:129:0) after 1m0s
[07:44:41.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:129:0)
[07:44:41.592] Timer tick, asking Buildbucket for the build status
[07:44:41.657] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:41.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:130:0) after 1m0s
[07:45:41.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:130:0)
[07:45:41.706] Timer tick, asking Buildbucket for the build status
[07:45:42.060] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:42.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:131:0) after 1m0s
[07:46:42.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:131:0)
[07:46:42.243] Timer tick, asking Buildbucket for the build status
[07:46:42.315] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:42.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:132:0) after 1m0s
[07:47:42.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:132:0)
[07:47:42.542] Timer tick, asking Buildbucket for the build status
[07:47:42.677] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:42.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:133:0) after 1m0s
[07:48:42.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:133:0)
[07:48:42.811] Timer tick, asking Buildbucket for the build status
[07:48:42.977] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:42.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:134:0) after 1m0s
[07:49:43.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:134:0)
[07:49:43.189] Timer tick, asking Buildbucket for the build status
[07:49:43.277] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:43.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:135:0) after 1m0s
[07:50:43.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:135:0)
[07:50:43.389] Timer tick, asking Buildbucket for the build status
[07:50:43.488] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:43.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:136:0) after 1m0s
[07:51:43.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:136:0)
[07:51:43.641] Timer tick, asking Buildbucket for the build status
[07:51:43.712] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:43.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:137:0) after 1m0s
[07:52:43.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:137:0)
[07:52:43.734] Timer tick, asking Buildbucket for the build status
[07:52:43.846] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:43.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:138:0) after 1m0s
[07:53:44.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:138:0)
[07:53:44.378] Timer tick, asking Buildbucket for the build status
[07:53:44.450] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:44.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:139:0) after 1m0s
[07:54:44.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:139:0)
[07:54:44.694] Timer tick, asking Buildbucket for the build status
[07:54:44.906] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:44.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:140:0) after 1m0s
[07:55:45.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:140:0)
[07:55:45.650] Timer tick, asking Buildbucket for the build status
[07:55:45.732] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:45.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:141:0) after 1m0s
[07:56:45.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:141:0)
[07:56:45.738] Timer tick, asking Buildbucket for the build status
[07:56:45.818] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:45.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:142:0) after 1m0s
[07:57:45.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:142:0)
[07:57:45.897] Timer tick, asking Buildbucket for the build status
[07:57:46.051] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:46.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:143:0) after 1m0s
[07:58:46.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:143:0)
[07:58:46.107] Timer tick, asking Buildbucket for the build status
[07:58:46.169] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:46.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:144:0) after 1m0s
[07:59:46.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:144:0)
[07:59:46.272] Timer tick, asking Buildbucket for the build status
[07:59:46.371] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:46.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:145:0) after 1m0s
[08:00:46.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:145:0)
[08:00:46.507] Timer tick, asking Buildbucket for the build status
[08:00:46.594] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:46.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:146:0) after 1m0s
[08:01:46.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:146:0)
[08:01:46.619] Timer tick, asking Buildbucket for the build status
[08:01:46.877] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:46.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:147:0) after 1m0s
[08:02:46.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:147:0)
[08:02:46.971] Timer tick, asking Buildbucket for the build status
[08:02:47.955] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:47.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:148:0) after 1m0s
[08:03:48.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:148:0)
[08:03:48.011] Timer tick, asking Buildbucket for the build status
[08:03:48.142] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:48.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:149:0) after 1m0s
[08:04:48.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:149:0)
[08:04:48.287] Timer tick, asking Buildbucket for the build status
[08:04:48.362] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:48.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:150:0) after 1m0s
[08:05:48.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:150:0)
[08:05:48.383] Timer tick, asking Buildbucket for the build status
[08:05:48.513] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:48.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:151:0) after 1m0s
[08:06:48.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:151:0)
[08:06:48.555] Timer tick, asking Buildbucket for the build status
[08:06:48.618] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:48.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:152:0) after 1m0s
[08:07:48.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:152:0)
[08:07:48.661] Timer tick, asking Buildbucket for the build status
[08:07:48.875] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:48.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:153:0) after 1m0s
[08:08:49.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:153:0)
[08:08:49.301] Timer tick, asking Buildbucket for the build status
[08:08:49.498] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:49.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:154:0) after 1m0s
[08:09:49.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:154:0)
[08:09:49.702] Timer tick, asking Buildbucket for the build status
[08:09:52.425] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:52.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:155:0) after 1m0s
[08:10:52.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:155:0)
[08:10:52.474] Timer tick, asking Buildbucket for the build status
[08:10:52.540] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:52.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:156:0) after 1m0s
[08:11:52.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:156:0)
[08:11:52.687] Timer tick, asking Buildbucket for the build status
[08:11:52.752] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:52.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:157:0) after 1m0s
[08:12:52.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:157:0)
[08:12:52.939] Timer tick, asking Buildbucket for the build status
[08:12:53.148] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:53.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:158:0) after 1m0s
[08:13:53.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:158:0)
[08:13:53.276] Timer tick, asking Buildbucket for the build status
[08:13:53.452] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:53.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:159:0) after 1m0s
[08:14:53.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:159:0)
[08:14:53.623] Timer tick, asking Buildbucket for the build status
[08:14:53.795] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:53.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:160:0) after 1m0s
[08:15:53.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:160:0)
[08:15:53.859] Timer tick, asking Buildbucket for the build status
[08:15:53.953] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:53.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:161:0) after 1m0s
[08:16:54.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:161:0)
[08:16:54.522] Timer tick, asking Buildbucket for the build status
[08:16:54.637] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:54.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:162:0) after 1m0s
[08:17:54.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:162:0)
[08:17:54.696] Timer tick, asking Buildbucket for the build status
[08:17:54.868] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:54.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:163:0) after 1m0s
[08:18:54.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:163:0)
[08:18:54.928] Timer tick, asking Buildbucket for the build status
[08:18:55.102] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:55.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:164:0) after 1m0s
[08:19:55.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:164:0)
[08:19:55.203] Timer tick, asking Buildbucket for the build status
[08:19:55.283] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:55.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:165:0) after 1m0s
[08:20:55.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:165:0)
[08:20:55.327] Timer tick, asking Buildbucket for the build status
[08:20:55.407] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:55.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:166:0) after 1m0s
[08:21:55.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:166:0)
[08:21:55.481] Timer tick, asking Buildbucket for the build status
[08:21:55.593] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:55.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:167:0) after 1m0s
[08:22:55.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:167:0)
[08:22:55.607] Timer tick, asking Buildbucket for the build status
[08:22:55.721] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:55.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:168:0) after 1m0s
[08:23:55.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:168:0)
[08:23:55.772] Timer tick, asking Buildbucket for the build status
[08:23:55.986] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:55.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:169:0) after 1m0s
[08:24:56.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:169:0)
[08:24:56.031] Timer tick, asking Buildbucket for the build status
[08:24:56.126] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:56.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:170:0) after 1m0s
[08:25:56.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:170:0)
[08:25:56.179] Timer tick, asking Buildbucket for the build status
[08:25:56.271] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:56.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:171:0) after 1m0s
[08:26:56.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:171:0)
[08:26:56.294] Timer tick, asking Buildbucket for the build status
[08:26:56.471] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:56.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:172:0) after 1m0s
[08:27:56.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:172:0)
[08:27:56.596] Timer tick, asking Buildbucket for the build status
[08:27:56.797] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:56.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:173:0) after 1m0s
[08:28:57.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:173:0)
[08:28:57.188] Timer tick, asking Buildbucket for the build status
[08:28:57.413] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:57.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:174:0) after 1m0s
[08:29:57.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:174:0)
[08:29:57.836] Timer tick, asking Buildbucket for the build status
[08:29:58.009] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:58.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:175:0) after 1m0s
[08:30:58.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:175:0)
[08:30:58.175] Timer tick, asking Buildbucket for the build status
[08:30:58.674] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:58.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:176:0) after 1m0s
[08:31:59.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:176:0)
[08:31:59.006] Timer tick, asking Buildbucket for the build status
[08:31:59.208] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:59.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:177:0) after 1m0s
[08:32:59.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:177:0)
[08:32:59.279] Timer tick, asking Buildbucket for the build status
[08:32:59.663] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:59.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:178:0) after 1m0s
[08:33:59.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:178:0)
[08:33:59.720] Timer tick, asking Buildbucket for the build status
[08:33:59.943] Build 8873284151912123696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:59.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038714214465636592:179:0) after 1m0s
[08:34:01.087] Received PubSub notification, asking Buildbucket for the build status
[08:34:01.363] Build 8873284151912123696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:34:01.363] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1596184439326761",
    "created_by": "project:infra",
    "created_ts": "1596173824354028",
    "id": "8873284151912123696",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4217.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d2b332c4-cee1-4cc7-954f-b836d89cea3d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4217.2\"}}",
    "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-2sqx\"], \"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\": [\"5436-adf7f8a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1596173860820516",
    "status": "COMPLETED",
    "status_changed_ts": "1596184439902200",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9038714214465636592",
      "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/8873284151912123696/+/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:4dbc313d3586c210",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1596184439967943",
    "url": "https://ci.chromium.org/b/8873284151912123696",
    "utcnow_ts": "1596184441352329"
  }
}
[08:34:01.363] Invocation finished in 2h56m58.827132754s with status SUCCEEDED