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

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

{
  "version": "85.0.4158.1"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[15:10:16.772] New invocation is queued and will start shortly
[15:10:16.772] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[15:10:17.957] Starting the invocation (attempt 1)
[15:10:17.996] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:10:17.997] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:10:17.997] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9044476355363037344",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4158.1\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"e6d06680-b069-4088-bdbd-6d233dd8a2b4\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4158.1\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9044476355363037344",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[15:10:18.646] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1590678618102378",
    "id": "8879046293303222464",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4158.1\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"e6d06680-b069-4088-bdbd-6d233dd8a2b4\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4158.1\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1590678618398500",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9044476355363037344",
      "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/8879046293303222464/+/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": "1590678618398632",
    "url": "https://ci.chromium.org/b/8879046293303222464",
    "utcnow_ts": "1590678618637424"
  }
}
[15:10:18.646] Task URL: https://ci.chromium.org/b/8879046293303222464
[15:10:18.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:2:0) after 1m0s
[15:11:18.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:2:0)
[15:11:18.778] Timer tick, asking Buildbucket for the build status
[15:11:18.933] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:11:18.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:3:0) after 1m0s
[15:12:19.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:3:0)
[15:12:19.029] Timer tick, asking Buildbucket for the build status
[15:12:19.155] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:12:19.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:4:0) after 1m0s
[15:13:19.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:4:0)
[15:13:19.251] Timer tick, asking Buildbucket for the build status
[15:13:19.565] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:13:19.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:5:0) after 1m0s
[15:14:19.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:5:0)
[15:14:19.580] Timer tick, asking Buildbucket for the build status
[15:14:20.003] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:14:20.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:6:0) after 1m0s
[15:15:20.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:6:0)
[15:15:20.032] Timer tick, asking Buildbucket for the build status
[15:15:20.204] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:15:20.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:7:0) after 1m0s
[15:16:20.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:7:0)
[15:16:20.358] Timer tick, asking Buildbucket for the build status
[15:16:20.534] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:16:20.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:8:0) after 1m0s
[15:17:20.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:8:0)
[15:17:20.666] Timer tick, asking Buildbucket for the build status
[15:17:20.844] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:17:20.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:9:0) after 1m0s
[15:18:20.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:9:0)
[15:18:20.900] Timer tick, asking Buildbucket for the build status
[15:18:21.026] Build 8879046293303222464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:18:21.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:10:0) after 1m0s
[15:18:44.927] Received PubSub notification, asking Buildbucket for the build status
[15:18:45.074] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:21.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:10:0)
[15:19:21.108] Timer tick, asking Buildbucket for the build status
[15:19:21.259] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:21.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:12:0) after 1m0s
[15:20:21.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:12:0)
[15:20:21.355] Timer tick, asking Buildbucket for the build status
[15:20:21.580] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:21.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:13:0) after 1m0s
[15:21:21.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:13:0)
[15:21:21.669] Timer tick, asking Buildbucket for the build status
[15:21:21.959] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:21.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:14:0) after 1m0s
[15:22:21.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:14:0)
[15:22:21.988] Timer tick, asking Buildbucket for the build status
[15:22:22.258] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:22.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:15:0) after 1m0s
[15:23:22.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:15:0)
[15:23:22.417] Timer tick, asking Buildbucket for the build status
[15:23:22.581] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:22.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:16:0) after 1m0s
[15:24:22.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:16:0)
[15:24:22.631] Timer tick, asking Buildbucket for the build status
[15:24:24.570] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:24.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:17:0) after 1m0s
[15:25:24.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:17:0)
[15:25:24.685] Timer tick, asking Buildbucket for the build status
[15:25:24.843] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:24.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:18:0) after 1m0s
[15:26:24.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:18:0)
[15:26:24.944] Timer tick, asking Buildbucket for the build status
[15:26:25.131] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:25.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:19:0) after 1m0s
[15:27:25.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:19:0)
[15:27:25.217] Timer tick, asking Buildbucket for the build status
[15:27:25.655] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:25.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:20:0) after 1m0s
[15:28:25.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:20:0)
[15:28:25.745] Timer tick, asking Buildbucket for the build status
[15:28:26.007] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:26.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:21:0) after 1m0s
[15:29:26.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:21:0)
[15:29:26.120] Timer tick, asking Buildbucket for the build status
[15:29:26.469] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:26.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:22:0) after 1m0s
[15:30:26.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:22:0)
[15:30:26.672] Timer tick, asking Buildbucket for the build status
[15:30:27.003] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:27.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:23:0) after 1m0s
[15:31:27.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:23:0)
[15:31:27.122] Timer tick, asking Buildbucket for the build status
[15:31:27.469] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:27.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:24:0) after 1m0s
[15:32:27.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:24:0)
[15:32:27.559] Timer tick, asking Buildbucket for the build status
[15:32:27.943] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:27.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:25:0) after 1m0s
[15:33:28.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:25:0)
[15:33:28.023] Timer tick, asking Buildbucket for the build status
[15:33:28.503] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:28.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:26:0) after 1m0s
[15:34:28.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:26:0)
[15:34:28.607] Timer tick, asking Buildbucket for the build status
[15:34:28.789] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:28.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:27:0) after 1m0s
[15:35:28.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:27:0)
[15:35:28.974] Timer tick, asking Buildbucket for the build status
[15:35:29.385] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:29.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:28:0) after 1m0s
[15:36:29.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:28:0)
[15:36:29.588] Timer tick, asking Buildbucket for the build status
[15:36:29.727] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:29.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:29:0) after 1m0s
[15:37:29.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:29:0)
[15:37:29.730] Timer tick, asking Buildbucket for the build status
[15:37:30.113] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:30.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:30:0) after 1m0s
[15:38:30.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:30:0)
[15:38:30.146] Timer tick, asking Buildbucket for the build status
[15:38:30.432] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:30.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:31:0) after 1m0s
[15:39:30.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:31:0)
[15:39:30.479] Timer tick, asking Buildbucket for the build status
[15:39:31.127] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:31.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:32:0) after 1m0s
[15:40:31.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:32:0)
[15:40:31.105] Timer tick, asking Buildbucket for the build status
[15:40:31.509] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:31.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:33:0) after 1m0s
[15:41:31.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:33:0)
[15:41:31.586] Timer tick, asking Buildbucket for the build status
[15:41:31.783] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:31.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:34:0) after 1m0s
[15:42:31.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:34:0)
[15:42:31.858] Timer tick, asking Buildbucket for the build status
[15:42:32.454] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:32.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:35:0) after 1m0s
[15:43:32.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:35:0)
[15:43:32.815] Timer tick, asking Buildbucket for the build status
[15:43:32.921] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:32.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:36:0) after 1m0s
[15:44:33.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:36:0)
[15:44:33.154] Timer tick, asking Buildbucket for the build status
[15:44:33.431] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:33.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:37:0) after 1m0s
[15:45:33.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:37:0)
[15:45:33.774] Timer tick, asking Buildbucket for the build status
[15:45:34.032] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:34.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:38:0) after 1m0s
[15:46:34.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:38:0)
[15:46:34.603] Timer tick, asking Buildbucket for the build status
[15:46:34.706] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:34.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:39:0) after 1m0s
[15:47:34.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:39:0)
[15:47:34.807] Timer tick, asking Buildbucket for the build status
[15:47:35.398] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:35.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:40:0) after 1m0s
[15:48:35.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:40:0)
[15:48:35.472] Timer tick, asking Buildbucket for the build status
[15:48:35.652] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:35.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:41:0) after 1m0s
[15:49:35.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:41:0)
[15:49:35.737] Timer tick, asking Buildbucket for the build status
[15:49:35.943] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:35.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:42:0) after 1m0s
[15:50:35.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:42:0)
[15:50:35.959] Timer tick, asking Buildbucket for the build status
[15:50:36.155] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:36.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:43:0) after 1m0s
[15:51:36.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:43:0)
[15:51:36.181] Timer tick, asking Buildbucket for the build status
[15:51:36.327] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:36.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:44:0) after 1m0s
[15:52:36.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:44:0)
[15:52:36.327] Timer tick, asking Buildbucket for the build status
[15:52:36.649] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:36.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:45:0) after 1m0s
[15:53:36.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:45:0)
[15:53:36.689] Timer tick, asking Buildbucket for the build status
[15:53:36.872] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:36.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:46:0) after 1m0s
[15:54:36.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:46:0)
[15:54:36.908] Timer tick, asking Buildbucket for the build status
[15:54:37.022] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:37.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:47:0) after 1m0s
[15:55:37.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:47:0)
[15:55:37.062] Timer tick, asking Buildbucket for the build status
[15:55:37.278] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:37.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:48:0) after 1m0s
[15:56:37.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:48:0)
[15:56:37.322] Timer tick, asking Buildbucket for the build status
[15:56:37.874] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:37.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:49:0) after 1m0s
[15:57:37.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:49:0)
[15:57:37.905] Timer tick, asking Buildbucket for the build status
[15:57:45.724] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:45.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:50:0) after 1m0s
[15:58:45.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:50:0)
[15:58:45.743] Timer tick, asking Buildbucket for the build status
[15:58:46.043] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:46.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:51:0) after 1m0s
[15:59:46.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:51:0)
[15:59:46.109] Timer tick, asking Buildbucket for the build status
[15:59:46.254] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:46.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:52:0) after 1m0s
[16:00:46.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:52:0)
[16:00:46.310] Timer tick, asking Buildbucket for the build status
[16:00:48.233] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:48.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:53:0) after 1m0s
[16:01:48.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:53:0)
[16:01:48.278] Timer tick, asking Buildbucket for the build status
[16:01:48.503] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:48.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:54:0) after 1m0s
[16:02:48.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:54:0)
[16:02:48.616] Timer tick, asking Buildbucket for the build status
[16:02:56.934] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:56.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:55:0) after 1m0s
[16:03:56.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:55:0)
[16:03:56.977] Timer tick, asking Buildbucket for the build status
[16:03:57.189] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:57.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:56:0) after 1m0s
[16:04:57.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:56:0)
[16:04:57.212] Timer tick, asking Buildbucket for the build status
[16:04:57.355] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:57.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:57:0) after 1m0s
[16:05:57.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:57:0)
[16:05:57.429] Timer tick, asking Buildbucket for the build status
[16:05:57.646] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:57.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:58:0) after 1m0s
[16:06:57.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:58:0)
[16:06:57.660] Timer tick, asking Buildbucket for the build status
[16:06:57.913] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:57.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:59:0) after 1m0s
[16:07:57.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:59:0)
[16:07:57.933] Timer tick, asking Buildbucket for the build status
[16:07:58.106] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:58.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:60:0) after 1m0s
[16:08:58.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:60:0)
[16:08:58.138] Timer tick, asking Buildbucket for the build status
[16:08:58.411] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:58.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:61:0) after 1m0s
[16:09:58.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:61:0)
[16:09:58.482] Timer tick, asking Buildbucket for the build status
[16:09:58.661] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:58.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:62:0) after 1m0s
[16:10:58.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:62:0)
[16:10:58.710] Timer tick, asking Buildbucket for the build status
[16:10:59.037] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:59.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:63:0) after 1m0s
[16:11:59.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:63:0)
[16:11:59.136] Timer tick, asking Buildbucket for the build status
[16:11:59.590] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:59.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:64:0) after 1m0s
[16:12:59.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:64:0)
[16:12:59.899] Timer tick, asking Buildbucket for the build status
[16:13:00.356] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:00.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:65:0) after 1m0s
[16:14:00.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:65:0)
[16:14:00.661] Timer tick, asking Buildbucket for the build status
[16:14:00.844] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:00.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:66:0) after 1m0s
[16:15:02.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:66:0)
[16:15:02.099] Timer tick, asking Buildbucket for the build status
[16:15:03.691] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:03.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:67:0) after 1m0s
[16:16:03.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:67:0)
[16:16:03.798] Timer tick, asking Buildbucket for the build status
[16:16:04.204] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:04.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:68:0) after 1m0s
[16:17:04.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:68:0)
[16:17:04.293] Timer tick, asking Buildbucket for the build status
[16:17:04.660] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:04.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:69:0) after 1m0s
[16:18:04.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:69:0)
[16:18:04.737] Timer tick, asking Buildbucket for the build status
[16:18:05.090] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:05.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:70:0) after 1m0s
[16:19:07.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:70:0)
[16:19:07.755] Timer tick, asking Buildbucket for the build status
[16:19:08.180] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:08.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:71:0) after 1m0s
[16:20:08.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:71:0)
[16:20:08.211] Timer tick, asking Buildbucket for the build status
[16:20:08.370] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:08.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:72:0) after 1m0s
[16:21:08.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:72:0)
[16:21:08.456] Timer tick, asking Buildbucket for the build status
[16:21:08.569] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:08.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:73:0) after 1m0s
[16:22:08.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:73:0)
[16:22:08.595] Timer tick, asking Buildbucket for the build status
[16:22:08.735] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:08.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:74:0) after 1m0s
[16:23:08.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:74:0)
[16:23:08.774] Timer tick, asking Buildbucket for the build status
[16:23:09.751] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:09.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:75:0) after 1m0s
[16:24:09.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:75:0)
[16:24:09.899] Timer tick, asking Buildbucket for the build status
[16:24:10.006] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:10.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:76:0) after 1m0s
[16:25:10.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:76:0)
[16:25:10.101] Timer tick, asking Buildbucket for the build status
[16:25:10.463] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:10.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:77:0) after 1m0s
[16:26:10.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:77:0)
[16:26:10.479] Timer tick, asking Buildbucket for the build status
[16:26:10.651] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:10.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:78:0) after 1m0s
[16:27:10.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:78:0)
[16:27:10.693] Timer tick, asking Buildbucket for the build status
[16:27:12.023] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:12.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:79:0) after 1m0s
[16:28:12.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:79:0)
[16:28:12.102] Timer tick, asking Buildbucket for the build status
[16:28:12.245] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:12.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:80:0) after 1m0s
[16:29:12.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:80:0)
[16:29:12.314] Timer tick, asking Buildbucket for the build status
[16:29:14.180] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:14.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:81:0) after 1m0s
[16:30:14.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:81:0)
[16:30:14.341] Timer tick, asking Buildbucket for the build status
[16:30:15.031] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:15.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:82:0) after 1m0s
[16:31:15.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:82:0)
[16:31:15.194] Timer tick, asking Buildbucket for the build status
[16:31:15.349] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:15.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:83:0) after 1m0s
[16:32:15.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:83:0)
[16:32:15.447] Timer tick, asking Buildbucket for the build status
[16:32:15.687] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:15.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:84:0) after 1m0s
[16:33:15.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:84:0)
[16:33:15.818] Timer tick, asking Buildbucket for the build status
[16:33:16.049] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:16.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:85:0) after 1m0s
[16:34:16.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:85:0)
[16:34:16.124] Timer tick, asking Buildbucket for the build status
[16:34:16.252] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:16.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:86:0) after 1m0s
[16:35:16.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:86:0)
[16:35:16.304] Timer tick, asking Buildbucket for the build status
[16:35:16.651] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:16.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:87:0) after 1m0s
[16:36:16.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:87:0)
[16:36:16.805] Timer tick, asking Buildbucket for the build status
[16:36:17.003] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:17.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:88:0) after 1m0s
[16:37:17.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:88:0)
[16:37:17.061] Timer tick, asking Buildbucket for the build status
[16:37:17.188] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:17.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:89:0) after 1m0s
[16:38:17.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:89:0)
[16:38:17.301] Timer tick, asking Buildbucket for the build status
[16:38:17.416] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:17.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:90:0) after 1m0s
[16:39:17.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:90:0)
[16:39:17.465] Timer tick, asking Buildbucket for the build status
[16:39:17.618] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:17.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:91:0) after 1m0s
[16:40:17.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:91:0)
[16:40:17.685] Timer tick, asking Buildbucket for the build status
[16:40:17.989] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:17.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:92:0) after 1m0s
[16:41:18.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:92:0)
[16:41:18.075] Timer tick, asking Buildbucket for the build status
[16:41:18.203] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:18.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:93:0) after 1m0s
[16:42:18.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:93:0)
[16:42:18.325] Timer tick, asking Buildbucket for the build status
[16:42:18.742] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:18.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:94:0) after 1m0s
[16:43:18.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:94:0)
[16:43:18.799] Timer tick, asking Buildbucket for the build status
[16:43:18.924] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:18.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:95:0) after 1m0s
[16:44:18.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:95:0)
[16:44:18.970] Timer tick, asking Buildbucket for the build status
[16:44:19.334] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:19.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:96:0) after 1m0s
[16:45:19.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:96:0)
[16:45:19.391] Timer tick, asking Buildbucket for the build status
[16:45:19.703] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:19.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:97:0) after 1m0s
[16:46:19.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:97:0)
[16:46:19.769] Timer tick, asking Buildbucket for the build status
[16:46:20.102] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:20.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:98:0) after 1m0s
[16:47:20.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:98:0)
[16:47:20.226] Timer tick, asking Buildbucket for the build status
[16:47:20.427] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:20.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:99:0) after 1m0s
[16:48:20.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:99:0)
[16:48:20.452] Timer tick, asking Buildbucket for the build status
[16:48:20.614] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:20.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:100:0) after 1m0s
[16:49:20.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:100:0)
[16:49:20.635] Timer tick, asking Buildbucket for the build status
[16:49:21.246] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:21.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:101:0) after 1m0s
[16:50:21.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:101:0)
[16:50:21.878] Timer tick, asking Buildbucket for the build status
[16:50:22.074] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:22.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:102:0) after 1m0s
[16:51:22.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:102:0)
[16:51:22.081] Timer tick, asking Buildbucket for the build status
[16:51:22.288] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:22.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:103:0) after 1m0s
[16:52:22.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:103:0)
[16:52:22.416] Timer tick, asking Buildbucket for the build status
[16:52:22.539] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:22.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:104:0) after 1m0s
[16:53:22.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:104:0)
[16:53:22.615] Timer tick, asking Buildbucket for the build status
[16:53:22.765] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:22.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:105:0) after 1m0s
[16:54:22.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:105:0)
[16:54:22.829] Timer tick, asking Buildbucket for the build status
[16:54:22.959] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:22.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:106:0) after 1m0s
[16:55:23.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:106:0)
[16:55:23.039] Timer tick, asking Buildbucket for the build status
[16:55:23.507] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:23.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:107:0) after 1m0s
[16:56:23.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:107:0)
[16:56:23.534] Timer tick, asking Buildbucket for the build status
[16:56:23.700] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:23.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:108:0) after 1m0s
[16:57:23.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:108:0)
[16:57:23.782] Timer tick, asking Buildbucket for the build status
[16:57:23.968] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:23.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:109:0) after 1m0s
[16:58:23.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:109:0)
[16:58:23.992] Timer tick, asking Buildbucket for the build status
[16:58:24.156] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:24.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:110:0) after 1m0s
[16:59:24.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:110:0)
[16:59:24.217] Timer tick, asking Buildbucket for the build status
[16:59:24.971] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:24.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:111:0) after 1m0s
[17:00:24.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:111:0)
[17:00:24.996] Timer tick, asking Buildbucket for the build status
[17:00:25.268] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:25.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:112:0) after 1m0s
[17:01:25.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:112:0)
[17:01:25.281] Timer tick, asking Buildbucket for the build status
[17:01:25.585] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:25.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:113:0) after 1m0s
[17:02:25.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:113:0)
[17:02:25.589] Timer tick, asking Buildbucket for the build status
[17:02:25.886] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:25.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:114:0) after 1m0s
[17:03:25.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:114:0)
[17:03:25.963] Timer tick, asking Buildbucket for the build status
[17:03:26.105] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:26.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:115:0) after 1m0s
[17:04:26.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:115:0)
[17:04:26.189] Timer tick, asking Buildbucket for the build status
[17:04:26.426] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:26.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:116:0) after 1m0s
[17:05:26.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:116:0)
[17:05:26.561] Timer tick, asking Buildbucket for the build status
[17:05:27.120] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:27.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:117:0) after 1m0s
[17:06:27.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:117:0)
[17:06:27.233] Timer tick, asking Buildbucket for the build status
[17:06:29.556] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:29.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:118:0) after 1m0s
[17:07:29.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:118:0)
[17:07:29.556] Timer tick, asking Buildbucket for the build status
[17:07:30.113] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:30.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:119:0) after 1m0s
[17:08:30.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:119:0)
[17:08:30.185] Timer tick, asking Buildbucket for the build status
[17:08:30.314] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:30.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:120:0) after 1m0s
[17:09:30.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:120:0)
[17:09:30.342] Timer tick, asking Buildbucket for the build status
[17:09:30.653] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:30.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:121:0) after 1m0s
[17:10:30.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:121:0)
[17:10:30.740] Timer tick, asking Buildbucket for the build status
[17:10:30.960] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:30.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:122:0) after 1m0s
[17:11:30.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:122:0)
[17:11:30.979] Timer tick, asking Buildbucket for the build status
[17:11:31.117] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:31.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:123:0) after 1m0s
[17:12:31.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:123:0)
[17:12:31.109] Timer tick, asking Buildbucket for the build status
[17:12:31.440] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:31.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:124:0) after 1m0s
[17:13:31.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:124:0)
[17:13:31.530] Timer tick, asking Buildbucket for the build status
[17:13:31.704] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:31.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:125:0) after 1m0s
[17:14:31.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:125:0)
[17:14:31.754] Timer tick, asking Buildbucket for the build status
[17:14:32.002] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:32.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:126:0) after 1m0s
[17:15:32.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:126:0)
[17:15:32.025] Timer tick, asking Buildbucket for the build status
[17:15:32.132] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:32.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:127:0) after 1m0s
[17:16:32.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:127:0)
[17:16:32.119] Timer tick, asking Buildbucket for the build status
[17:16:33.067] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:33.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:128:0) after 1m0s
[17:17:33.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:128:0)
[17:17:33.128] Timer tick, asking Buildbucket for the build status
[17:17:33.519] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:33.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:129:0) after 1m0s
[17:18:33.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:129:0)
[17:18:33.547] Timer tick, asking Buildbucket for the build status
[17:18:33.698] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:33.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:130:0) after 1m0s
[17:19:33.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:130:0)
[17:19:33.731] Timer tick, asking Buildbucket for the build status
[17:19:34.334] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:34.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:131:0) after 1m0s
[17:20:34.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:131:0)
[17:20:34.384] Timer tick, asking Buildbucket for the build status
[17:20:34.550] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:34.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:132:0) after 1m0s
[17:21:34.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:132:0)
[17:21:34.628] Timer tick, asking Buildbucket for the build status
[17:21:34.781] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:34.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:133:0) after 1m0s
[17:22:34.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:133:0)
[17:22:34.884] Timer tick, asking Buildbucket for the build status
[17:22:35.331] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:35.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:134:0) after 1m0s
[17:23:35.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:134:0)
[17:23:35.373] Timer tick, asking Buildbucket for the build status
[17:23:35.943] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:35.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:135:0) after 1m0s
[17:24:35.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:135:0)
[17:24:35.985] Timer tick, asking Buildbucket for the build status
[17:24:36.248] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:36.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:136:0) after 1m0s
[17:25:36.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:136:0)
[17:25:36.248] Timer tick, asking Buildbucket for the build status
[17:25:36.594] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:36.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:137:0) after 1m0s
[17:26:36.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:137:0)
[17:26:36.652] Timer tick, asking Buildbucket for the build status
[17:26:36.993] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:36.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:138:0) after 1m0s
[17:27:37.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:138:0)
[17:27:37.021] Timer tick, asking Buildbucket for the build status
[17:27:37.239] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:37.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:139:0) after 1m0s
[17:28:37.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:139:0)
[17:28:37.271] Timer tick, asking Buildbucket for the build status
[17:28:37.372] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:37.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:140:0) after 1m0s
[17:29:37.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:140:0)
[17:29:37.421] Timer tick, asking Buildbucket for the build status
[17:29:37.529] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:37.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:141:0) after 1m0s
[17:30:37.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:141:0)
[17:30:37.709] Timer tick, asking Buildbucket for the build status
[17:30:37.967] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:37.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:142:0) after 1m0s
[17:31:38.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:142:0)
[17:31:38.113] Timer tick, asking Buildbucket for the build status
[17:31:38.224] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:38.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:143:0) after 1m0s
[17:32:38.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:143:0)
[17:32:38.263] Timer tick, asking Buildbucket for the build status
[17:32:38.382] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:38.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:144:0) after 1m0s
[17:33:38.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:144:0)
[17:33:38.513] Timer tick, asking Buildbucket for the build status
[17:33:38.930] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:38.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:145:0) after 1m0s
[17:34:39.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:145:0)
[17:34:39.009] Timer tick, asking Buildbucket for the build status
[17:34:39.470] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:39.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:146:0) after 1m0s
[17:35:39.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:146:0)
[17:35:39.572] Timer tick, asking Buildbucket for the build status
[17:35:39.695] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:39.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:147:0) after 1m0s
[17:36:39.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:147:0)
[17:36:39.780] Timer tick, asking Buildbucket for the build status
[17:36:40.223] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:40.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:148:0) after 1m0s
[17:37:40.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:148:0)
[17:37:40.289] Timer tick, asking Buildbucket for the build status
[17:37:40.403] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:40.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:149:0) after 1m0s
[17:38:40.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:149:0)
[17:38:40.494] Timer tick, asking Buildbucket for the build status
[17:38:40.625] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:40.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:150:0) after 1m0s
[17:39:40.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:150:0)
[17:39:40.678] Timer tick, asking Buildbucket for the build status
[17:39:40.823] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:40.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:151:0) after 1m0s
[17:40:40.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:151:0)
[17:40:40.971] Timer tick, asking Buildbucket for the build status
[17:40:41.364] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:41.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:152:0) after 1m0s
[17:41:41.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:152:0)
[17:41:41.564] Timer tick, asking Buildbucket for the build status
[17:41:41.685] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:41.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:153:0) after 1m0s
[17:42:41.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:153:0)
[17:42:41.739] Timer tick, asking Buildbucket for the build status
[17:42:41.888] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:41.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:154:0) after 1m0s
[17:43:41.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:154:0)
[17:43:41.916] Timer tick, asking Buildbucket for the build status
[17:43:42.386] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:42.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:155:0) after 1m0s
[17:44:42.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:155:0)
[17:44:42.488] Timer tick, asking Buildbucket for the build status
[17:44:43.567] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:43.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:156:0) after 1m0s
[17:45:43.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:156:0)
[17:45:43.701] Timer tick, asking Buildbucket for the build status
[17:45:44.132] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:44.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:157:0) after 1m0s
[17:46:44.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:157:0)
[17:46:44.188] Timer tick, asking Buildbucket for the build status
[17:46:44.404] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:44.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:158:0) after 1m0s
[17:47:44.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:158:0)
[17:47:44.431] Timer tick, asking Buildbucket for the build status
[17:47:45.451] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:45.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:159:0) after 1m0s
[17:48:45.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:159:0)
[17:48:45.474] Timer tick, asking Buildbucket for the build status
[17:48:45.786] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:45.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:160:0) after 1m0s
[17:49:45.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:160:0)
[17:49:45.836] Timer tick, asking Buildbucket for the build status
[17:49:45.938] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:45.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:161:0) after 1m0s
[17:50:46.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:161:0)
[17:50:46.011] Timer tick, asking Buildbucket for the build status
[17:50:46.197] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:46.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:162:0) after 1m0s
[17:51:46.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:162:0)
[17:51:46.285] Timer tick, asking Buildbucket for the build status
[17:51:46.420] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:46.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:163:0) after 1m0s
[17:52:46.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:163:0)
[17:52:46.451] Timer tick, asking Buildbucket for the build status
[17:52:46.613] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:46.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:164:0) after 1m0s
[17:53:46.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:164:0)
[17:53:46.650] Timer tick, asking Buildbucket for the build status
[17:53:46.833] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:46.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:165:0) after 1m0s
[17:54:46.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:165:0)
[17:54:46.918] Timer tick, asking Buildbucket for the build status
[17:54:47.038] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:47.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:166:0) after 1m0s
[17:55:47.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:166:0)
[17:55:47.316] Timer tick, asking Buildbucket for the build status
[17:55:47.698] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:47.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:167:0) after 1m0s
[17:56:47.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:167:0)
[17:56:47.823] Timer tick, asking Buildbucket for the build status
[17:56:47.967] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:47.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:168:0) after 1m0s
[17:57:48.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:168:0)
[17:57:48.127] Timer tick, asking Buildbucket for the build status
[17:57:48.623] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:48.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:169:0) after 1m0s
[17:58:48.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:169:0)
[17:58:48.837] Timer tick, asking Buildbucket for the build status
[17:58:49.344] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:49.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:170:0) after 1m0s
[17:59:49.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:170:0)
[17:59:49.409] Timer tick, asking Buildbucket for the build status
[17:59:49.588] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:49.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:171:0) after 1m0s
[18:00:49.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:171:0)
[18:00:49.593] Timer tick, asking Buildbucket for the build status
[18:00:49.779] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:49.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:172:0) after 1m0s
[18:01:49.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:172:0)
[18:01:49.855] Timer tick, asking Buildbucket for the build status
[18:01:51.462] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:51.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:173:0) after 1m0s
[18:02:51.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:173:0)
[18:02:51.473] Timer tick, asking Buildbucket for the build status
[18:02:51.709] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:51.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:174:0) after 1m0s
[18:03:51.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:174:0)
[18:03:51.763] Timer tick, asking Buildbucket for the build status
[18:03:51.951] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:51.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:175:0) after 1m0s
[18:04:52.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:175:0)
[18:04:52.037] Timer tick, asking Buildbucket for the build status
[18:04:52.958] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:52.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:176:0) after 1m0s
[18:05:52.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:176:0)
[18:05:52.994] Timer tick, asking Buildbucket for the build status
[18:05:53.434] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:53.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:177:0) after 1m0s
[18:06:53.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:177:0)
[18:06:53.455] Timer tick, asking Buildbucket for the build status
[18:06:53.721] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:53.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:178:0) after 1m0s
[18:07:53.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:178:0)
[18:07:53.806] Timer tick, asking Buildbucket for the build status
[18:07:54.108] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:54.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:179:0) after 1m0s
[18:08:54.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:179:0)
[18:08:54.097] Timer tick, asking Buildbucket for the build status
[18:08:54.386] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:54.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:180:0) after 1m0s
[18:09:54.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:180:0)
[18:09:54.373] Timer tick, asking Buildbucket for the build status
[18:09:54.605] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:54.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:181:0) after 1m0s
[18:10:54.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:181:0)
[18:10:54.651] Timer tick, asking Buildbucket for the build status
[18:10:55.075] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:55.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:182:0) after 1m0s
[18:11:55.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:182:0)
[18:11:55.148] Timer tick, asking Buildbucket for the build status
[18:11:55.388] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:55.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:183:0) after 1m0s
[18:12:55.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:183:0)
[18:12:55.416] Timer tick, asking Buildbucket for the build status
[18:12:55.658] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:55.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:184:0) after 1m0s
[18:13:55.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:184:0)
[18:13:55.687] Timer tick, asking Buildbucket for the build status
[18:13:55.821] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:55.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:185:0) after 1m0s
[18:14:55.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:185:0)
[18:14:55.899] Timer tick, asking Buildbucket for the build status
[18:14:56.911] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:56.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:186:0) after 1m0s
[18:15:56.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:186:0)
[18:15:56.954] Timer tick, asking Buildbucket for the build status
[18:15:57.365] Build 8879046293303222464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:57.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9044476355363037344:187:0) after 1m0s
[18:16:42.057] Received PubSub notification, asking Buildbucket for the build status
[18:16:43.757] Build 8879046293303222464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:16:43.757] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1590689799469129",
    "created_by": "project:infra",
    "created_ts": "1590678618102378",
    "id": "8879046293303222464",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4158.1\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"e6d06680-b069-4088-bdbd-6d233dd8a2b4\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4158.1\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"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-3h44\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5142-e16b32c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1590679123384167",
    "status": "COMPLETED",
    "status_changed_ts": "1590689800698058",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9044476355363037344",
      "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/8879046293303222464/+/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:4c74a70aefe39910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1590689801003155",
    "url": "https://ci.chromium.org/b/8879046293303222464",
    "utcnow_ts": "1590689803732575"
  }
}
[18:16:43.757] Invocation finished in 3h6m27.003562494s with status SUCCEEDED