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

• parent_buildername:publish_tarball
• user_agent:recipe

[07:10:04.561] New invocation is queued and will start shortly
[07:10:04.561] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[07:10:06.373] Starting the invocation (attempt 1)
[07:10:06.420] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:10:06.420] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:10:06.420] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9037440003773528880",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4230.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"2b9fd075-2597-4e2b-a4fa-321b7a176a77\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4230.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:9037440003773528880",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[07:10:07.051] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:infra",
    "created_ts": "1597389006559529",
    "id": "8872009941017079696",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4230.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"2b9fd075-2597-4e2b-a4fa-321b7a176a77\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4230.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1597389006816739",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9037440003773528880",
      "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/8872009941017079696/+/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": "1597389006816872",
    "url": "https://ci.chromium.org/b/8872009941017079696",
    "utcnow_ts": "1597389007021233"
  }
}
[07:10:07.051] Task URL: https://ci.chromium.org/b/8872009941017079696
[07:10:07.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:2:0) after 1m0s
[07:11:05.917] Received PubSub notification, asking Buildbucket for the build status
[07:11:06.049] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:07.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:2:0)
[07:11:07.119] Timer tick, asking Buildbucket for the build status
[07:11:07.383] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:07.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:4:0) after 1m0s
[07:12:07.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:4:0)
[07:12:07.503] Timer tick, asking Buildbucket for the build status
[07:12:07.701] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:07.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:5:0) after 1m0s
[07:13:07.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:5:0)
[07:13:07.729] Timer tick, asking Buildbucket for the build status
[07:13:07.901] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:07.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:6:0) after 1m0s
[07:14:07.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:6:0)
[07:14:07.914] Timer tick, asking Buildbucket for the build status
[07:14:08.034] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:08.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:7:0) after 1m0s
[07:15:08.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:7:0)
[07:15:08.088] Timer tick, asking Buildbucket for the build status
[07:15:08.867] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:08.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:8:0) after 1m0s
[07:16:08.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:8:0)
[07:16:08.888] Timer tick, asking Buildbucket for the build status
[07:16:09.027] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:09.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:9:0) after 1m0s
[07:17:09.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:9:0)
[07:17:09.020] Timer tick, asking Buildbucket for the build status
[07:17:09.142] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:09.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:10:0) after 1m0s
[07:18:09.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:10:0)
[07:18:09.230] Timer tick, asking Buildbucket for the build status
[07:18:09.352] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:09.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:11:0) after 1m0s
[07:19:09.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:11:0)
[07:19:09.435] Timer tick, asking Buildbucket for the build status
[07:19:09.515] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:09.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:12:0) after 1m0s
[07:20:09.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:12:0)
[07:20:09.540] Timer tick, asking Buildbucket for the build status
[07:20:09.732] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:09.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:13:0) after 1m0s
[07:21:09.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:13:0)
[07:21:09.746] Timer tick, asking Buildbucket for the build status
[07:21:09.857] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:09.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:14:0) after 1m0s
[07:22:09.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:14:0)
[07:22:09.907] Timer tick, asking Buildbucket for the build status
[07:22:10.053] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:10.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:15:0) after 1m0s
[07:23:10.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:15:0)
[07:23:10.121] Timer tick, asking Buildbucket for the build status
[07:23:10.208] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:10.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:16:0) after 1m0s
[07:24:10.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:16:0)
[07:24:10.266] Timer tick, asking Buildbucket for the build status
[07:24:10.556] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:10.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:17:0) after 1m0s
[07:25:10.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:17:0)
[07:25:10.590] Timer tick, asking Buildbucket for the build status
[07:25:10.840] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:10.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:18:0) after 1m0s
[07:26:10.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:18:0)
[07:26:10.862] Timer tick, asking Buildbucket for the build status
[07:26:10.970] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:10.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:19:0) after 1m0s
[07:27:11.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:19:0)
[07:27:11.028] Timer tick, asking Buildbucket for the build status
[07:27:11.133] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:11.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:20:0) after 1m0s
[07:28:11.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:20:0)
[07:28:11.170] Timer tick, asking Buildbucket for the build status
[07:28:11.268] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:11.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:21:0) after 1m0s
[07:29:11.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:21:0)
[07:29:11.323] Timer tick, asking Buildbucket for the build status
[07:29:12.283] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:12.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:22:0) after 1m0s
[07:30:12.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:22:0)
[07:30:12.314] Timer tick, asking Buildbucket for the build status
[07:30:12.534] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:12.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:23:0) after 1m0s
[07:31:12.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:23:0)
[07:31:12.559] Timer tick, asking Buildbucket for the build status
[07:31:12.823] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:12.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:24:0) after 1m0s
[07:32:12.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:24:0)
[07:32:12.845] Timer tick, asking Buildbucket for the build status
[07:32:13.886] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:13.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:25:0) after 1m0s
[07:33:13.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:25:0)
[07:33:13.974] Timer tick, asking Buildbucket for the build status
[07:33:14.058] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:14.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:26:0) after 1m0s
[07:34:14.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:26:0)
[07:34:14.039] Timer tick, asking Buildbucket for the build status
[07:34:14.265] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:14.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:27:0) after 1m0s
[07:35:14.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:27:0)
[07:35:14.319] Timer tick, asking Buildbucket for the build status
[07:35:14.480] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:14.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:28:0) after 1m0s
[07:36:14.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:28:0)
[07:36:14.503] Timer tick, asking Buildbucket for the build status
[07:36:14.651] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:14.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:29:0) after 1m0s
[07:37:14.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:29:0)
[07:37:14.688] Timer tick, asking Buildbucket for the build status
[07:37:14.897] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:14.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:30:0) after 1m0s
[07:38:14.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:30:0)
[07:38:14.936] Timer tick, asking Buildbucket for the build status
[07:38:15.081] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:15.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:31:0) after 1m0s
[07:39:15.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:31:0)
[07:39:15.133] Timer tick, asking Buildbucket for the build status
[07:39:15.246] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:15.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:32:0) after 1m0s
[07:40:15.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:32:0)
[07:40:15.301] Timer tick, asking Buildbucket for the build status
[07:40:15.687] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:15.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:33:0) after 1m0s
[07:41:15.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:33:0)
[07:41:15.741] Timer tick, asking Buildbucket for the build status
[07:41:15.828] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:15.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:34:0) after 1m0s
[07:42:15.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:34:0)
[07:42:15.851] Timer tick, asking Buildbucket for the build status
[07:42:15.954] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:15.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:35:0) after 1m0s
[07:43:15.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:35:0)
[07:43:15.980] Timer tick, asking Buildbucket for the build status
[07:43:16.038] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:16.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:36:0) after 1m0s
[07:44:16.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:36:0)
[07:44:16.192] Timer tick, asking Buildbucket for the build status
[07:44:16.495] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:16.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:37:0) after 1m0s
[07:45:16.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:37:0)
[07:45:16.817] Timer tick, asking Buildbucket for the build status
[07:45:17.001] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:17.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:38:0) after 1m0s
[07:46:17.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:38:0)
[07:46:17.234] Timer tick, asking Buildbucket for the build status
[07:46:17.314] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:17.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:39:0) after 1m0s
[07:47:17.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:39:0)
[07:47:17.513] Timer tick, asking Buildbucket for the build status
[07:47:17.757] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:17.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:40:0) after 1m0s
[07:48:18.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:40:0)
[07:48:18.329] Timer tick, asking Buildbucket for the build status
[07:48:18.607] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:18.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:41:0) after 1m0s
[07:49:18.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:41:0)
[07:49:18.676] Timer tick, asking Buildbucket for the build status
[07:49:18.802] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:18.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:42:0) after 1m0s
[07:50:18.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:42:0)
[07:50:18.876] Timer tick, asking Buildbucket for the build status
[07:50:19.056] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:19.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:43:0) after 1m0s
[07:51:19.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:43:0)
[07:51:19.109] Timer tick, asking Buildbucket for the build status
[07:51:19.200] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:19.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:44:0) after 1m0s
[07:52:19.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:44:0)
[07:52:19.225] Timer tick, asking Buildbucket for the build status
[07:52:19.351] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:19.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:45:0) after 1m0s
[07:53:19.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:45:0)
[07:53:19.415] Timer tick, asking Buildbucket for the build status
[07:53:19.630] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:19.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:46:0) after 1m0s
[07:54:19.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:46:0)
[07:54:19.707] Timer tick, asking Buildbucket for the build status
[07:54:19.767] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:19.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:47:0) after 1m0s
[07:55:19.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:47:0)
[07:55:19.834] Timer tick, asking Buildbucket for the build status
[07:55:19.960] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:19.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:48:0) after 1m0s
[07:56:20.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:48:0)
[07:56:20.020] Timer tick, asking Buildbucket for the build status
[07:56:20.198] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:20.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:49:0) after 1m0s
[07:57:20.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:49:0)
[07:57:20.324] Timer tick, asking Buildbucket for the build status
[07:57:20.391] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:20.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:50:0) after 1m0s
[07:58:20.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:50:0)
[07:58:20.506] Timer tick, asking Buildbucket for the build status
[07:58:20.589] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:20.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:51:0) after 1m0s
[07:59:20.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:51:0)
[07:59:20.615] Timer tick, asking Buildbucket for the build status
[07:59:20.949] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:52:0) after 1m0s
[08:00:21.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:52:0)
[08:00:21.043] Timer tick, asking Buildbucket for the build status
[08:00:21.186] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:21.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:53:0) after 1m0s
[08:01:21.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:53:0)
[08:01:21.288] Timer tick, asking Buildbucket for the build status
[08:01:21.626] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:21.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:54:0) after 1m0s
[08:02:21.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:54:0)
[08:02:21.888] Timer tick, asking Buildbucket for the build status
[08:02:22.631] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:22.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:55:0) after 1m0s
[08:03:22.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:55:0)
[08:03:22.759] Timer tick, asking Buildbucket for the build status
[08:03:22.871] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:22.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:56:0) after 1m0s
[08:04:23.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:56:0)
[08:04:23.020] Timer tick, asking Buildbucket for the build status
[08:04:23.125] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:23.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:57:0) after 1m0s
[08:05:23.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:57:0)
[08:05:23.250] Timer tick, asking Buildbucket for the build status
[08:05:23.692] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:23.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:58:0) after 1m0s
[08:06:23.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:58:0)
[08:06:23.853] Timer tick, asking Buildbucket for the build status
[08:06:24.024] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:24.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:59:0) after 1m0s
[08:07:24.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:59:0)
[08:07:24.168] Timer tick, asking Buildbucket for the build status
[08:07:24.287] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:24.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:60:0) after 1m0s
[08:08:24.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:60:0)
[08:08:24.369] Timer tick, asking Buildbucket for the build status
[08:08:24.435] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:24.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:61:0) after 1m0s
[08:09:24.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:61:0)
[08:09:24.482] Timer tick, asking Buildbucket for the build status
[08:09:24.614] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:24.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:62:0) after 1m0s
[08:10:24.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:62:0)
[08:10:24.657] Timer tick, asking Buildbucket for the build status
[08:10:25.134] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:25.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:63:0) after 1m0s
[08:11:25.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:63:0)
[08:11:25.192] Timer tick, asking Buildbucket for the build status
[08:11:25.760] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:25.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:64:0) after 1m0s
[08:12:25.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:64:0)
[08:12:25.894] Timer tick, asking Buildbucket for the build status
[08:12:26.024] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:26.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:65:0) after 1m0s
[08:13:26.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:65:0)
[08:13:26.014] Timer tick, asking Buildbucket for the build status
[08:13:26.159] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:26.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:66:0) after 1m0s
[08:14:26.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:66:0)
[08:14:26.466] Timer tick, asking Buildbucket for the build status
[08:14:26.614] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:26.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:67:0) after 1m0s
[08:15:26.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:67:0)
[08:15:26.772] Timer tick, asking Buildbucket for the build status
[08:15:26.895] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:26.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:68:0) after 1m0s
[08:16:27.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:68:0)
[08:16:27.030] Timer tick, asking Buildbucket for the build status
[08:16:27.113] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:27.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:69:0) after 1m0s
[08:17:27.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:69:0)
[08:17:27.191] Timer tick, asking Buildbucket for the build status
[08:17:27.251] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:27.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:70:0) after 1m0s
[08:18:27.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:70:0)
[08:18:27.343] Timer tick, asking Buildbucket for the build status
[08:18:27.436] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:27.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:71:0) after 1m0s
[08:19:27.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:71:0)
[08:19:27.490] Timer tick, asking Buildbucket for the build status
[08:19:28.535] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:28.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:72:0) after 1m0s
[08:20:28.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:72:0)
[08:20:28.563] Timer tick, asking Buildbucket for the build status
[08:20:28.965] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:28.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:73:0) after 1m0s
[08:21:29.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:73:0)
[08:21:29.181] Timer tick, asking Buildbucket for the build status
[08:21:29.283] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:29.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:74:0) after 1m0s
[08:22:29.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:74:0)
[08:22:29.412] Timer tick, asking Buildbucket for the build status
[08:22:29.516] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:29.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:75:0) after 1m0s
[08:23:30.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:75:0)
[08:23:30.044] Timer tick, asking Buildbucket for the build status
[08:23:30.156] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:30.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:76:0) after 1m0s
[08:24:30.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:76:0)
[08:24:30.237] Timer tick, asking Buildbucket for the build status
[08:24:30.348] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:30.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:77:0) after 1m0s
[08:25:30.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:77:0)
[08:25:30.424] Timer tick, asking Buildbucket for the build status
[08:25:30.621] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:30.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:78:0) after 1m0s
[08:26:30.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:78:0)
[08:26:30.726] Timer tick, asking Buildbucket for the build status
[08:26:30.811] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:30.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:79:0) after 1m0s
[08:27:30.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:79:0)
[08:27:30.886] Timer tick, asking Buildbucket for the build status
[08:27:30.940] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:30.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:80:0) after 1m0s
[08:28:31.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:80:0)
[08:28:31.017] Timer tick, asking Buildbucket for the build status
[08:28:31.404] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:31.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:81:0) after 1m0s
[08:29:31.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:81:0)
[08:29:31.446] Timer tick, asking Buildbucket for the build status
[08:29:31.867] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:31.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:82:0) after 1m0s
[08:30:31.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:82:0)
[08:30:31.930] Timer tick, asking Buildbucket for the build status
[08:30:32.124] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:32.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:83:0) after 1m0s
[08:31:32.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:83:0)
[08:31:32.173] Timer tick, asking Buildbucket for the build status
[08:31:32.315] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:32.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:84:0) after 1m0s
[08:32:32.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:84:0)
[08:32:32.430] Timer tick, asking Buildbucket for the build status
[08:32:32.642] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:32.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:85:0) after 1m0s
[08:33:32.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:85:0)
[08:33:32.724] Timer tick, asking Buildbucket for the build status
[08:33:32.825] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:32.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:86:0) after 1m0s
[08:34:32.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:86:0)
[08:34:32.926] Timer tick, asking Buildbucket for the build status
[08:34:33.249] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:33.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:87:0) after 1m0s
[08:35:33.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:87:0)
[08:35:33.348] Timer tick, asking Buildbucket for the build status
[08:35:33.707] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:33.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:88:0) after 1m0s
[08:36:33.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:88:0)
[08:36:33.729] Timer tick, asking Buildbucket for the build status
[08:36:33.890] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:33.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:89:0) after 1m0s
[08:37:34.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:89:0)
[08:37:34.116] Timer tick, asking Buildbucket for the build status
[08:37:35.165] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:35.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:90:0) after 1m0s
[08:38:35.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:90:0)
[08:38:35.277] Timer tick, asking Buildbucket for the build status
[08:38:35.691] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:35.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:91:0) after 1m0s
[08:39:35.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:91:0)
[08:39:35.711] Timer tick, asking Buildbucket for the build status
[08:39:35.787] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:35.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:92:0) after 1m0s
[08:40:35.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:92:0)
[08:40:35.800] Timer tick, asking Buildbucket for the build status
[08:40:36.233] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:36.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:93:0) after 1m0s
[08:41:36.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:93:0)
[08:41:36.258] Timer tick, asking Buildbucket for the build status
[08:41:36.860] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:36.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:94:0) after 1m0s
[08:42:36.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:94:0)
[08:42:36.887] Timer tick, asking Buildbucket for the build status
[08:42:37.040] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:37.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:95:0) after 1m0s
[08:43:37.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:95:0)
[08:43:37.073] Timer tick, asking Buildbucket for the build status
[08:43:37.253] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:37.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:96:0) after 1m0s
[08:44:37.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:96:0)
[08:44:37.373] Timer tick, asking Buildbucket for the build status
[08:44:40.599] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:40.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:97:0) after 1m0s
[08:45:40.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:97:0)
[08:45:40.637] Timer tick, asking Buildbucket for the build status
[08:45:40.903] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:40.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:98:0) after 1m0s
[08:46:40.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:98:0)
[08:46:40.962] Timer tick, asking Buildbucket for the build status
[08:46:41.059] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:41.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:99:0) after 1m0s
[08:47:41.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:99:0)
[08:47:41.163] Timer tick, asking Buildbucket for the build status
[08:47:41.236] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:41.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:100:0) after 1m0s
[08:48:41.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:100:0)
[08:48:41.278] Timer tick, asking Buildbucket for the build status
[08:48:41.344] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:41.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:101:0) after 1m0s
[08:49:41.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:101:0)
[08:49:41.433] Timer tick, asking Buildbucket for the build status
[08:49:41.749] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:41.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:102:0) after 1m0s
[08:50:41.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:102:0)
[08:50:41.774] Timer tick, asking Buildbucket for the build status
[08:50:41.949] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:41.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:103:0) after 1m0s
[08:51:41.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:103:0)
[08:51:41.967] Timer tick, asking Buildbucket for the build status
[08:51:42.232] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:42.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:104:0) after 1m0s
[08:52:42.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:104:0)
[08:52:42.304] Timer tick, asking Buildbucket for the build status
[08:52:42.440] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:42.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:105:0) after 1m0s
[08:53:42.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:105:0)
[08:53:42.538] Timer tick, asking Buildbucket for the build status
[08:53:42.614] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:42.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:106:0) after 1m0s
[08:54:42.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:106:0)
[08:54:42.669] Timer tick, asking Buildbucket for the build status
[08:54:42.765] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:42.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:107:0) after 1m0s
[08:55:42.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:107:0)
[08:55:42.835] Timer tick, asking Buildbucket for the build status
[08:55:43.098] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:43.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:108:0) after 1m0s
[08:56:43.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:108:0)
[08:56:43.124] Timer tick, asking Buildbucket for the build status
[08:56:43.260] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:43.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:109:0) after 1m0s
[08:57:43.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:109:0)
[08:57:43.278] Timer tick, asking Buildbucket for the build status
[08:57:43.641] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:43.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:110:0) after 1m0s
[08:58:43.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:110:0)
[08:58:43.787] Timer tick, asking Buildbucket for the build status
[08:58:44.234] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:44.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:111:0) after 1m0s
[08:59:44.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:111:0)
[08:59:44.378] Timer tick, asking Buildbucket for the build status
[08:59:44.837] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:44.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:112:0) after 1m0s
[09:00:45.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:112:0)
[09:00:45.069] Timer tick, asking Buildbucket for the build status
[09:00:45.232] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:45.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:113:0) after 1m0s
[09:01:45.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:113:0)
[09:01:45.259] Timer tick, asking Buildbucket for the build status
[09:01:45.331] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:45.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:114:0) after 1m0s
[09:02:45.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:114:0)
[09:02:45.610] Timer tick, asking Buildbucket for the build status
[09:02:45.860] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:45.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:115:0) after 1m0s
[09:03:45.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:115:0)
[09:03:45.880] Timer tick, asking Buildbucket for the build status
[09:03:46.162] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:46.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:116:0) after 1m0s
[09:04:46.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:116:0)
[09:04:46.546] Timer tick, asking Buildbucket for the build status
[09:04:46.657] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:46.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:117:0) after 1m0s
[09:05:46.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:117:0)
[09:05:46.804] Timer tick, asking Buildbucket for the build status
[09:05:49.210] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:49.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:118:0) after 1m0s
[09:06:49.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:118:0)
[09:06:49.355] Timer tick, asking Buildbucket for the build status
[09:06:49.472] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:49.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:119:0) after 1m0s
[09:07:49.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:119:0)
[09:07:49.796] Timer tick, asking Buildbucket for the build status
[09:07:49.966] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:49.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:120:0) after 1m0s
[09:08:50.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:120:0)
[09:08:50.344] Timer tick, asking Buildbucket for the build status
[09:08:50.673] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:50.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:121:0) after 1m0s
[09:09:50.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:121:0)
[09:09:50.687] Timer tick, asking Buildbucket for the build status
[09:09:52.794] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:52.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:122:0) after 1m0s
[09:10:52.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:122:0)
[09:10:52.886] Timer tick, asking Buildbucket for the build status
[09:10:53.246] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:53.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:123:0) after 1m0s
[09:11:53.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:123:0)
[09:11:53.342] Timer tick, asking Buildbucket for the build status
[09:11:53.635] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:53.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:124:0) after 1m0s
[09:12:53.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:124:0)
[09:12:53.698] Timer tick, asking Buildbucket for the build status
[09:12:53.860] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:53.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:125:0) after 1m0s
[09:13:54.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:125:0)
[09:13:54.007] Timer tick, asking Buildbucket for the build status
[09:13:54.128] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:54.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:126:0) after 1m0s
[09:14:54.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:126:0)
[09:14:54.232] Timer tick, asking Buildbucket for the build status
[09:14:54.703] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:54.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:127:0) after 1m0s
[09:15:54.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:127:0)
[09:15:54.831] Timer tick, asking Buildbucket for the build status
[09:15:55.040] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:55.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:128:0) after 1m0s
[09:16:55.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:128:0)
[09:16:55.086] Timer tick, asking Buildbucket for the build status
[09:16:55.473] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:55.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:129:0) after 1m0s
[09:17:55.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:129:0)
[09:17:55.512] Timer tick, asking Buildbucket for the build status
[09:17:56.098] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:56.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:130:0) after 1m0s
[09:18:56.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:130:0)
[09:18:56.163] Timer tick, asking Buildbucket for the build status
[09:18:56.251] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:56.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:131:0) after 1m0s
[09:19:56.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:131:0)
[09:19:56.265] Timer tick, asking Buildbucket for the build status
[09:19:58.032] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:58.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:132:0) after 1m0s
[09:20:58.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:132:0)
[09:20:58.123] Timer tick, asking Buildbucket for the build status
[09:20:59.736] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:59.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:133:0) after 1m0s
[09:21:59.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:133:0)
[09:21:59.891] Timer tick, asking Buildbucket for the build status
[09:22:00.123] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:00.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:134:0) after 1m0s
[09:23:00.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:134:0)
[09:23:00.281] Timer tick, asking Buildbucket for the build status
[09:23:00.544] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:00.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:135:0) after 1m0s
[09:24:00.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:135:0)
[09:24:00.891] Timer tick, asking Buildbucket for the build status
[09:24:00.990] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:00.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:136:0) after 1m0s
[09:25:01.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:136:0)
[09:25:01.168] Timer tick, asking Buildbucket for the build status
[09:25:01.462] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:01.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:137:0) after 1m0s
[09:26:01.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:137:0)
[09:26:01.492] Timer tick, asking Buildbucket for the build status
[09:26:01.978] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:01.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:138:0) after 1m0s
[09:27:02.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:138:0)
[09:27:02.414] Timer tick, asking Buildbucket for the build status
[09:27:02.521] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:02.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:139:0) after 1m0s
[09:28:02.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:139:0)
[09:28:02.592] Timer tick, asking Buildbucket for the build status
[09:28:02.709] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:02.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:140:0) after 1m0s
[09:29:02.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:140:0)
[09:29:02.734] Timer tick, asking Buildbucket for the build status
[09:29:02.794] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:02.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:141:0) after 1m0s
[09:30:02.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:141:0)
[09:30:02.845] Timer tick, asking Buildbucket for the build status
[09:30:02.912] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:02.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:142:0) after 1m0s
[09:31:02.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:142:0)
[09:31:02.962] Timer tick, asking Buildbucket for the build status
[09:31:03.229] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:03.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:143:0) after 1m0s
[09:32:03.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:143:0)
[09:32:03.330] Timer tick, asking Buildbucket for the build status
[09:32:03.418] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:03.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:144:0) after 1m0s
[09:33:03.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:144:0)
[09:33:03.509] Timer tick, asking Buildbucket for the build status
[09:33:03.650] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:03.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:145:0) after 1m0s
[09:34:03.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:145:0)
[09:34:03.698] Timer tick, asking Buildbucket for the build status
[09:34:04.346] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:04.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:146:0) after 1m0s
[09:35:04.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:146:0)
[09:35:04.361] Timer tick, asking Buildbucket for the build status
[09:35:04.447] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:04.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:147:0) after 1m0s
[09:36:04.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:147:0)
[09:36:04.573] Timer tick, asking Buildbucket for the build status
[09:36:04.972] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:04.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:148:0) after 1m0s
[09:37:05.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:148:0)
[09:37:05.018] Timer tick, asking Buildbucket for the build status
[09:37:05.146] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:05.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:149:0) after 1m0s
[09:38:05.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:149:0)
[09:38:05.232] Timer tick, asking Buildbucket for the build status
[09:38:05.550] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:05.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:150:0) after 1m0s
[09:39:05.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:150:0)
[09:39:05.591] Timer tick, asking Buildbucket for the build status
[09:39:05.705] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:05.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:151:0) after 1m0s
[09:40:05.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:151:0)
[09:40:05.737] Timer tick, asking Buildbucket for the build status
[09:40:05.827] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:05.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:152:0) after 1m0s
[09:41:05.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:152:0)
[09:41:05.908] Timer tick, asking Buildbucket for the build status
[09:41:06.038] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:06.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:153:0) after 1m0s
[09:42:06.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:153:0)
[09:42:06.173] Timer tick, asking Buildbucket for the build status
[09:42:06.451] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:06.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:154:0) after 1m0s
[09:43:06.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:154:0)
[09:43:06.557] Timer tick, asking Buildbucket for the build status
[09:43:06.647] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:06.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:155:0) after 1m0s
[09:44:07.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:155:0)
[09:44:07.164] Timer tick, asking Buildbucket for the build status
[09:44:07.394] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:07.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:156:0) after 1m0s
[09:45:07.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:156:0)
[09:45:07.410] Timer tick, asking Buildbucket for the build status
[09:45:07.510] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:07.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:157:0) after 1m0s
[09:46:07.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:157:0)
[09:46:07.660] Timer tick, asking Buildbucket for the build status
[09:46:07.889] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:07.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:158:0) after 1m0s
[09:47:07.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:158:0)
[09:47:07.942] Timer tick, asking Buildbucket for the build status
[09:47:08.033] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:08.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:159:0) after 1m0s
[09:48:08.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:159:0)
[09:48:08.118] Timer tick, asking Buildbucket for the build status
[09:48:08.250] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:08.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:160:0) after 1m0s
[09:49:08.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:160:0)
[09:49:08.422] Timer tick, asking Buildbucket for the build status
[09:49:08.527] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:08.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:161:0) after 1m0s
[09:50:08.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:161:0)
[09:50:08.622] Timer tick, asking Buildbucket for the build status
[09:50:08.779] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:08.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:162:0) after 1m0s
[09:51:09.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:162:0)
[09:51:09.016] Timer tick, asking Buildbucket for the build status
[09:51:09.191] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:09.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:163:0) after 1m0s
[09:52:09.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:163:0)
[09:52:09.280] Timer tick, asking Buildbucket for the build status
[09:52:09.413] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:09.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:164:0) after 1m0s
[09:53:09.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:164:0)
[09:53:09.476] Timer tick, asking Buildbucket for the build status
[09:53:09.699] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:09.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:165:0) after 1m0s
[09:54:09.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:165:0)
[09:54:09.784] Timer tick, asking Buildbucket for the build status
[09:54:10.117] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:10.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:166:0) after 1m0s
[09:55:10.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:166:0)
[09:55:10.286] Timer tick, asking Buildbucket for the build status
[09:55:11.173] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:11.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:167:0) after 1m0s
[09:56:11.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:167:0)
[09:56:11.295] Timer tick, asking Buildbucket for the build status
[09:56:11.462] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:11.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:168:0) after 1m0s
[09:57:11.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:168:0)
[09:57:11.639] Timer tick, asking Buildbucket for the build status
[09:57:11.698] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:11.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:169:0) after 1m0s
[09:58:11.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:169:0)
[09:58:11.712] Timer tick, asking Buildbucket for the build status
[09:58:11.789] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:11.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:170:0) after 1m0s
[09:59:11.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:170:0)
[09:59:11.840] Timer tick, asking Buildbucket for the build status
[09:59:12.080] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:12.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:171:0) after 1m0s
[10:00:12.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:171:0)
[10:00:12.135] Timer tick, asking Buildbucket for the build status
[10:00:12.214] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:12.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:172:0) after 1m0s
[10:01:13.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:172:0)
[10:01:13.143] Timer tick, asking Buildbucket for the build status
[10:01:15.294] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:15.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:173:0) after 1m0s
[10:02:15.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:173:0)
[10:02:15.502] Timer tick, asking Buildbucket for the build status
[10:02:15.596] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:15.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:174:0) after 1m0s
[10:03:15.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:174:0)
[10:03:15.747] Timer tick, asking Buildbucket for the build status
[10:03:15.868] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:15.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:175:0) after 1m0s
[10:04:16.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:175:0)
[10:04:16.431] Timer tick, asking Buildbucket for the build status
[10:04:16.493] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:16.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:176:0) after 1m0s
[10:05:16.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:176:0)
[10:05:16.800] Timer tick, asking Buildbucket for the build status
[10:05:17.132] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:17.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:177:0) after 1m0s
[10:06:17.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:177:0)
[10:06:17.305] Timer tick, asking Buildbucket for the build status
[10:06:17.463] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:17.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:178:0) after 1m0s
[10:07:17.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:178:0)
[10:07:17.719] Timer tick, asking Buildbucket for the build status
[10:07:17.806] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:17.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:179:0) after 1m0s
[10:08:17.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:179:0)
[10:08:17.916] Timer tick, asking Buildbucket for the build status
[10:08:18.368] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:18.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:180:0) after 1m0s
[10:09:18.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:180:0)
[10:09:18.457] Timer tick, asking Buildbucket for the build status
[10:09:18.553] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:18.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:181:0) after 1m0s
[10:10:18.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:181:0)
[10:10:18.638] Timer tick, asking Buildbucket for the build status
[10:10:18.737] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:18.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:182:0) after 1m0s
[10:11:18.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:182:0)
[10:11:18.794] Timer tick, asking Buildbucket for the build status
[10:11:18.989] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:18.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:183:0) after 1m0s
[10:12:19.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:183:0)
[10:12:19.055] Timer tick, asking Buildbucket for the build status
[10:12:19.282] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:19.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:184:0) after 1m0s
[10:13:19.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:184:0)
[10:13:19.466] Timer tick, asking Buildbucket for the build status
[10:13:19.579] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:19.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:185:0) after 1m0s
[10:14:20.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:185:0)
[10:14:20.088] Timer tick, asking Buildbucket for the build status
[10:14:20.288] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:20.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:186:0) after 1m0s
[10:15:20.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:186:0)
[10:15:20.623] Timer tick, asking Buildbucket for the build status
[10:15:20.746] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:20.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:187:0) after 1m0s
[10:16:20.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:187:0)
[10:16:20.901] Timer tick, asking Buildbucket for the build status
[10:16:21.039] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:21.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:188:0) after 1m0s
[10:17:22.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:188:0)
[10:17:22.583] Timer tick, asking Buildbucket for the build status
[10:17:22.693] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:22.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:189:0) after 1m0s
[10:18:22.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:189:0)
[10:18:22.930] Timer tick, asking Buildbucket for the build status
[10:18:23.247] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:23.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:190:0) after 1m0s
[10:19:23.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:190:0)
[10:19:23.343] Timer tick, asking Buildbucket for the build status
[10:19:24.216] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:24.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:191:0) after 1m0s
[10:20:24.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:191:0)
[10:20:24.373] Timer tick, asking Buildbucket for the build status
[10:20:24.786] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:24.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:192:0) after 1m0s
[10:21:25.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:192:0)
[10:21:25.197] Timer tick, asking Buildbucket for the build status
[10:21:25.836] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:25.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:193:0) after 1m0s
[10:22:26.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:193:0)
[10:22:26.171] Timer tick, asking Buildbucket for the build status
[10:22:26.250] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:26.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:194:0) after 1m0s
[10:23:26.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:194:0)
[10:23:26.418] Timer tick, asking Buildbucket for the build status
[10:23:26.493] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:26.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:195:0) after 1m0s
[10:24:26.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:195:0)
[10:24:26.545] Timer tick, asking Buildbucket for the build status
[10:24:26.652] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:26.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:196:0) after 1m0s
[10:25:26.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:196:0)
[10:25:26.734] Timer tick, asking Buildbucket for the build status
[10:25:32.216] Build 8872009941017079696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:32.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037440003773528880:197:0) after 1m0s
[10:25:39.649] Received PubSub notification, asking Buildbucket for the build status
[10:25:39.734] Build 8872009941017079696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:25:39.734] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1597400738752276",
    "created_by": "project:infra",
    "created_ts": "1597389006559529",
    "id": "8872009941017079696",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4230.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"2b9fd075-2597-4e2b-a4fa-321b7a176a77\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4230.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-iejw\"], \"image\": [\"chrome-xenial-20081200-b8de48ff3cf\"], \"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\": [\"5488-fc78d20\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1597389064753820",
    "status": "COMPLETED",
    "status_changed_ts": "1597400739203887",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9037440003773528880",
      "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/8872009941017079696/+/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:4e049f71bb46da10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1597400739227495",
    "url": "https://ci.chromium.org/b/8872009941017079696",
    "utcnow_ts": "1597400739722984"
  }
}
[10:25:39.734] Invocation finished in 3h15m35.187649805s with status SUCCEEDED