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

• parent_buildername:publish_tarball
• user_agent:recipe

[11:05:13.471] New invocation is queued and will start shortly
[11:05:13.471] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:05:15.250] Starting the invocation (attempt 1)
[11:05:15.294] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:05:15.294] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:05:15.294] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9029362079501360320",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4321.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"dfb5c600-2035-4f1d-8ced-7c33f20403bb\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4321.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:9029362079501360320",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[11:05:21.550] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1605092715637677",
    "id": "8863932016566338672",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4321.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"dfb5c600-2035-4f1d-8ced-7c33f20403bb\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4321.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1605092720302169",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029362079501360320",
      "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/8863932016566338672/+/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": "1605092720302424",
    "url": "https://ci.chromium.org/b/8863932016566338672",
    "utcnow_ts": "1605092721475541"
  }
}
[11:05:21.550] Task URL: https://ci.chromium.org/b/8863932016566338672
[11:05:21.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:2:0) after 1m0s
[11:05:24.717] Received PubSub notification, asking Buildbucket for the build status
[11:05:25.073] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:21.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:2:0)
[11:06:21.640] Timer tick, asking Buildbucket for the build status
[11:06:21.751] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:21.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:4:0) after 1m0s
[11:07:21.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:4:0)
[11:07:21.814] Timer tick, asking Buildbucket for the build status
[11:07:21.890] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:21.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:5:0) after 1m0s
[11:08:21.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:5:0)
[11:08:21.971] Timer tick, asking Buildbucket for the build status
[11:08:22.048] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:22.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:6:0) after 1m0s
[11:09:22.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:6:0)
[11:09:22.059] Timer tick, asking Buildbucket for the build status
[11:09:22.159] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:22.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:7:0) after 1m0s
[11:10:22.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:7:0)
[11:10:22.193] Timer tick, asking Buildbucket for the build status
[11:10:22.309] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:22.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:8:0) after 1m0s
[11:11:22.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:8:0)
[11:11:22.333] Timer tick, asking Buildbucket for the build status
[11:11:22.415] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:22.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:9:0) after 1m0s
[11:12:22.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:9:0)
[11:12:22.415] Timer tick, asking Buildbucket for the build status
[11:12:22.710] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:22.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:10:0) after 1m0s
[11:13:22.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:10:0)
[11:13:22.738] Timer tick, asking Buildbucket for the build status
[11:13:22.952] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:22.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:11:0) after 1m0s
[11:14:23.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:11:0)
[11:14:23.047] Timer tick, asking Buildbucket for the build status
[11:14:23.129] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:23.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:12:0) after 1m0s
[11:15:23.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:12:0)
[11:15:23.144] Timer tick, asking Buildbucket for the build status
[11:15:23.343] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:23.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:13:0) after 1m0s
[11:16:23.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:13:0)
[11:16:23.372] Timer tick, asking Buildbucket for the build status
[11:16:23.526] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:23.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:14:0) after 1m0s
[11:17:23.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:14:0)
[11:17:23.991] Timer tick, asking Buildbucket for the build status
[11:17:24.269] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:24.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:15:0) after 1m0s
[11:18:24.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:15:0)
[11:18:24.394] Timer tick, asking Buildbucket for the build status
[11:18:24.492] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:24.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:16:0) after 1m0s
[11:19:24.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:16:0)
[11:19:24.640] Timer tick, asking Buildbucket for the build status
[11:19:24.745] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:24.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:17:0) after 1m0s
[11:20:25.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:17:0)
[11:20:25.265] Timer tick, asking Buildbucket for the build status
[11:20:25.507] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:25.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:18:0) after 1m0s
[11:21:25.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:18:0)
[11:21:25.564] Timer tick, asking Buildbucket for the build status
[11:21:25.809] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:25.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:19:0) after 1m0s
[11:22:25.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:19:0)
[11:22:25.936] Timer tick, asking Buildbucket for the build status
[11:22:26.008] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:26.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:20:0) after 1m0s
[11:23:26.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:20:0)
[11:23:26.087] Timer tick, asking Buildbucket for the build status
[11:23:26.179] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:26.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:21:0) after 1m0s
[11:24:26.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:21:0)
[11:24:26.305] Timer tick, asking Buildbucket for the build status
[11:24:26.432] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:26.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:22:0) after 1m0s
[11:25:26.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:22:0)
[11:25:26.478] Timer tick, asking Buildbucket for the build status
[11:25:26.612] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:26.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:23:0) after 1m0s
[11:26:26.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:23:0)
[11:26:26.664] Timer tick, asking Buildbucket for the build status
[11:26:27.311] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:27.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:24:0) after 1m0s
[11:27:27.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:24:0)
[11:27:27.372] Timer tick, asking Buildbucket for the build status
[11:27:27.459] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:27.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:25:0) after 1m0s
[11:28:27.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:25:0)
[11:28:27.644] Timer tick, asking Buildbucket for the build status
[11:28:27.729] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:27.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:26:0) after 1m0s
[11:29:27.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:26:0)
[11:29:27.831] Timer tick, asking Buildbucket for the build status
[11:29:27.969] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:27.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:27:0) after 1m0s
[11:30:28.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:27:0)
[11:30:28.072] Timer tick, asking Buildbucket for the build status
[11:30:29.875] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:29.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:28:0) after 1m0s
[11:31:29.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:28:0)
[11:31:29.990] Timer tick, asking Buildbucket for the build status
[11:31:30.113] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:30.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:29:0) after 1m0s
[11:32:30.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:29:0)
[11:32:30.287] Timer tick, asking Buildbucket for the build status
[11:32:30.613] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:30.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:30:0) after 1m0s
[11:33:30.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:30:0)
[11:33:30.635] Timer tick, asking Buildbucket for the build status
[11:33:30.822] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:30.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:31:0) after 1m0s
[11:34:30.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:31:0)
[11:34:30.912] Timer tick, asking Buildbucket for the build status
[11:34:31.003] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:31.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:32:0) after 1m0s
[11:35:31.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:32:0)
[11:35:31.022] Timer tick, asking Buildbucket for the build status
[11:35:31.640] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:31.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:33:0) after 1m0s
[11:36:31.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:33:0)
[11:36:31.749] Timer tick, asking Buildbucket for the build status
[11:36:31.858] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:31.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:34:0) after 1m0s
[11:37:31.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:34:0)
[11:37:31.859] Timer tick, asking Buildbucket for the build status
[11:37:31.949] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:31.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:35:0) after 1m0s
[11:38:32.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:35:0)
[11:38:32.048] Timer tick, asking Buildbucket for the build status
[11:38:32.146] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:32.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:36:0) after 1m0s
[11:39:32.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:36:0)
[11:39:32.209] Timer tick, asking Buildbucket for the build status
[11:39:32.475] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:32.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:37:0) after 1m0s
[11:40:32.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:37:0)
[11:40:32.602] Timer tick, asking Buildbucket for the build status
[11:40:32.695] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:32.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:38:0) after 1m0s
[11:41:32.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:38:0)
[11:41:32.831] Timer tick, asking Buildbucket for the build status
[11:41:33.118] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:33.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:39:0) after 1m0s
[11:42:33.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:39:0)
[11:42:33.171] Timer tick, asking Buildbucket for the build status
[11:42:33.493] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:33.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:40:0) after 1m0s
[11:43:33.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:40:0)
[11:43:33.517] Timer tick, asking Buildbucket for the build status
[11:43:33.658] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:33.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:41:0) after 1m0s
[11:44:33.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:41:0)
[11:44:33.669] Timer tick, asking Buildbucket for the build status
[11:44:33.771] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:33.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:42:0) after 1m0s
[11:45:33.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:42:0)
[11:45:33.870] Timer tick, asking Buildbucket for the build status
[11:45:34.025] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:34.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:43:0) after 1m0s
[11:46:34.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:43:0)
[11:46:34.077] Timer tick, asking Buildbucket for the build status
[11:46:34.192] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:34.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:44:0) after 1m0s
[11:47:34.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:44:0)
[11:47:34.305] Timer tick, asking Buildbucket for the build status
[11:47:34.387] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:34.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:45:0) after 1m0s
[11:48:34.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:45:0)
[11:48:34.489] Timer tick, asking Buildbucket for the build status
[11:48:34.569] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:34.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:46:0) after 1m0s
[11:49:34.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:46:0)
[11:49:34.609] Timer tick, asking Buildbucket for the build status
[11:49:34.725] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:34.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:47:0) after 1m0s
[11:50:34.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:47:0)
[11:50:34.799] Timer tick, asking Buildbucket for the build status
[11:50:35.376] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:35.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:48:0) after 1m0s
[11:51:35.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:48:0)
[11:51:35.549] Timer tick, asking Buildbucket for the build status
[11:51:35.632] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:35.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:49:0) after 1m0s
[11:52:35.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:49:0)
[11:52:35.622] Timer tick, asking Buildbucket for the build status
[11:52:35.845] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:35.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:50:0) after 1m0s
[11:53:35.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:50:0)
[11:53:35.917] Timer tick, asking Buildbucket for the build status
[11:53:36.003] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:36.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:51:0) after 1m0s
[11:54:36.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:51:0)
[11:54:36.200] Timer tick, asking Buildbucket for the build status
[11:54:36.290] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:36.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:52:0) after 1m0s
[11:55:36.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:52:0)
[11:55:36.400] Timer tick, asking Buildbucket for the build status
[11:55:36.548] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:36.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:53:0) after 1m0s
[11:56:36.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:53:0)
[11:56:36.859] Timer tick, asking Buildbucket for the build status
[11:56:36.939] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:36.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:54:0) after 1m0s
[11:57:36.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:54:0)
[11:57:36.963] Timer tick, asking Buildbucket for the build status
[11:57:37.166] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:37.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:55:0) after 1m0s
[11:58:37.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:55:0)
[11:58:37.236] Timer tick, asking Buildbucket for the build status
[11:58:37.359] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:37.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:56:0) after 1m0s
[11:59:37.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:56:0)
[11:59:37.390] Timer tick, asking Buildbucket for the build status
[11:59:37.537] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:37.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:57:0) after 1m0s
[12:00:37.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:57:0)
[12:00:37.582] Timer tick, asking Buildbucket for the build status
[12:00:37.778] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:37.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:58:0) after 1m0s
[12:01:37.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:58:0)
[12:01:37.806] Timer tick, asking Buildbucket for the build status
[12:01:37.962] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:37.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:59:0) after 1m0s
[12:02:37.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:59:0)
[12:02:37.945] Timer tick, asking Buildbucket for the build status
[12:02:38.348] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:38.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:60:0) after 1m0s
[12:03:38.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:60:0)
[12:03:38.510] Timer tick, asking Buildbucket for the build status
[12:03:38.681] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:38.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:61:0) after 1m0s
[12:04:38.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:61:0)
[12:04:38.743] Timer tick, asking Buildbucket for the build status
[12:04:39.086] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:39.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:62:0) after 1m0s
[12:05:39.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:62:0)
[12:05:39.260] Timer tick, asking Buildbucket for the build status
[12:05:39.591] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:39.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:63:0) after 1m0s
[12:06:39.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:63:0)
[12:06:39.637] Timer tick, asking Buildbucket for the build status
[12:06:39.955] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:39.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:64:0) after 1m0s
[12:07:40.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:64:0)
[12:07:40.036] Timer tick, asking Buildbucket for the build status
[12:07:47.745] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:47.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:65:0) after 1m0s
[12:08:47.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:65:0)
[12:08:47.788] Timer tick, asking Buildbucket for the build status
[12:08:47.898] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:47.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:66:0) after 1m0s
[12:09:48.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:66:0)
[12:09:48.166] Timer tick, asking Buildbucket for the build status
[12:09:48.234] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:48.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:67:0) after 1m0s
[12:10:48.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:67:0)
[12:10:48.397] Timer tick, asking Buildbucket for the build status
[12:10:48.500] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:48.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:68:0) after 1m0s
[12:11:48.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:68:0)
[12:11:48.659] Timer tick, asking Buildbucket for the build status
[12:11:48.744] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:48.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:69:0) after 1m0s
[12:12:48.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:69:0)
[12:12:48.879] Timer tick, asking Buildbucket for the build status
[12:12:49.024] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:49.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:70:0) after 1m0s
[12:13:49.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:70:0)
[12:13:49.080] Timer tick, asking Buildbucket for the build status
[12:13:49.180] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:49.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:71:0) after 1m0s
[12:14:49.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:71:0)
[12:14:49.212] Timer tick, asking Buildbucket for the build status
[12:14:49.321] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:49.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:72:0) after 1m0s
[12:15:49.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:72:0)
[12:15:49.403] Timer tick, asking Buildbucket for the build status
[12:15:49.529] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:49.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:73:0) after 1m0s
[12:16:49.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:73:0)
[12:16:49.595] Timer tick, asking Buildbucket for the build status
[12:16:49.803] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:49.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:74:0) after 1m0s
[12:17:49.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:74:0)
[12:17:49.914] Timer tick, asking Buildbucket for the build status
[12:17:50.012] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:50.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:75:0) after 1m0s
[12:18:50.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:75:0)
[12:18:50.001] Timer tick, asking Buildbucket for the build status
[12:18:50.093] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:50.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:76:0) after 1m0s
[12:19:50.132] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:76:0)
[12:19:50.132] Timer tick, asking Buildbucket for the build status
[12:19:50.228] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:50.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:77:0) after 1m0s
[12:20:50.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:77:0)
[12:20:50.256] Timer tick, asking Buildbucket for the build status
[12:20:50.474] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:50.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:78:0) after 1m0s
[12:21:50.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:78:0)
[12:21:50.511] Timer tick, asking Buildbucket for the build status
[12:21:50.581] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:50.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:79:0) after 1m0s
[12:22:50.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:79:0)
[12:22:50.610] Timer tick, asking Buildbucket for the build status
[12:22:50.694] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:50.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:80:0) after 1m0s
[12:23:50.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:80:0)
[12:23:50.717] Timer tick, asking Buildbucket for the build status
[12:23:52.631] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:52.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:81:0) after 1m0s
[12:24:52.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:81:0)
[12:24:52.664] Timer tick, asking Buildbucket for the build status
[12:24:52.821] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:52.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:82:0) after 1m0s
[12:25:52.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:82:0)
[12:25:52.825] Timer tick, asking Buildbucket for the build status
[12:25:52.932] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:52.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:83:0) after 1m0s
[12:26:53.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:83:0)
[12:26:53.028] Timer tick, asking Buildbucket for the build status
[12:26:53.136] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:53.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:84:0) after 1m0s
[12:27:53.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:84:0)
[12:27:53.229] Timer tick, asking Buildbucket for the build status
[12:27:53.305] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:53.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:85:0) after 1m0s
[12:28:53.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:85:0)
[12:28:53.354] Timer tick, asking Buildbucket for the build status
[12:28:53.443] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:53.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:86:0) after 1m0s
[12:29:53.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:86:0)
[12:29:53.471] Timer tick, asking Buildbucket for the build status
[12:29:53.577] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:53.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:87:0) after 1m0s
[12:30:53.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:87:0)
[12:30:53.658] Timer tick, asking Buildbucket for the build status
[12:30:53.902] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:53.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:88:0) after 1m0s
[12:31:53.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:88:0)
[12:31:53.916] Timer tick, asking Buildbucket for the build status
[12:31:54.007] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:54.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:89:0) after 1m0s
[12:32:54.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:89:0)
[12:32:54.071] Timer tick, asking Buildbucket for the build status
[12:32:54.178] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:54.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:90:0) after 1m0s
[12:33:54.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:90:0)
[12:33:54.213] Timer tick, asking Buildbucket for the build status
[12:33:54.314] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:54.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:91:0) after 1m0s
[12:34:54.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:91:0)
[12:34:54.362] Timer tick, asking Buildbucket for the build status
[12:34:54.616] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:54.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:92:0) after 1m0s
[12:35:54.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:92:0)
[12:35:54.687] Timer tick, asking Buildbucket for the build status
[12:35:54.821] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:54.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:93:0) after 1m0s
[12:36:54.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:93:0)
[12:36:54.888] Timer tick, asking Buildbucket for the build status
[12:36:54.979] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:54.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:94:0) after 1m0s
[12:37:55.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:94:0)
[12:37:55.104] Timer tick, asking Buildbucket for the build status
[12:37:55.179] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:55.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:95:0) after 1m0s
[12:38:55.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:95:0)
[12:38:55.268] Timer tick, asking Buildbucket for the build status
[12:38:55.357] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:55.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:96:0) after 1m0s
[12:39:55.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:96:0)
[12:39:55.415] Timer tick, asking Buildbucket for the build status
[12:39:55.703] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:55.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:97:0) after 1m0s
[12:40:55.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:97:0)
[12:40:55.730] Timer tick, asking Buildbucket for the build status
[12:40:55.861] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:55.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:98:0) after 1m0s
[12:41:55.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:98:0)
[12:41:55.918] Timer tick, asking Buildbucket for the build status
[12:41:56.045] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:56.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:99:0) after 1m0s
[12:42:56.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:99:0)
[12:42:56.035] Timer tick, asking Buildbucket for the build status
[12:42:56.107] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:56.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:100:0) after 1m0s
[12:43:56.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:100:0)
[12:43:56.171] Timer tick, asking Buildbucket for the build status
[12:43:56.327] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:56.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:101:0) after 1m0s
[12:44:56.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:101:0)
[12:44:56.350] Timer tick, asking Buildbucket for the build status
[12:44:56.490] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:56.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:102:0) after 1m0s
[12:45:56.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:102:0)
[12:45:56.541] Timer tick, asking Buildbucket for the build status
[12:45:56.891] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:56.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:103:0) after 1m0s
[12:46:56.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:103:0)
[12:46:56.917] Timer tick, asking Buildbucket for the build status
[12:46:57.043] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:57.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:104:0) after 1m0s
[12:47:57.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:104:0)
[12:47:57.134] Timer tick, asking Buildbucket for the build status
[12:47:57.242] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:57.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:105:0) after 1m0s
[12:48:57.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:105:0)
[12:48:57.272] Timer tick, asking Buildbucket for the build status
[12:48:57.499] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:57.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:106:0) after 1m0s
[12:49:57.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:106:0)
[12:49:57.560] Timer tick, asking Buildbucket for the build status
[12:49:57.643] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:57.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:107:0) after 1m0s
[12:50:57.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:107:0)
[12:50:57.674] Timer tick, asking Buildbucket for the build status
[12:50:57.765] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:57.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:108:0) after 1m0s
[12:51:57.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:108:0)
[12:51:57.811] Timer tick, asking Buildbucket for the build status
[12:51:59.825] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:59.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:109:0) after 1m0s
[12:52:59.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:109:0)
[12:52:59.852] Timer tick, asking Buildbucket for the build status
[12:52:59.959] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:59.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:110:0) after 1m0s
[12:53:59.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:110:0)
[12:53:59.984] Timer tick, asking Buildbucket for the build status
[12:54:00.184] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:00.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:111:0) after 1m0s
[12:55:00.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:111:0)
[12:55:00.210] Timer tick, asking Buildbucket for the build status
[12:55:00.301] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:00.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:112:0) after 1m0s
[12:56:00.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:112:0)
[12:56:00.367] Timer tick, asking Buildbucket for the build status
[12:56:00.482] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:00.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:113:0) after 1m0s
[12:57:00.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:113:0)
[12:57:00.505] Timer tick, asking Buildbucket for the build status
[12:57:00.617] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:00.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:114:0) after 1m0s
[12:58:03.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:114:0)
[12:58:03.339] Timer tick, asking Buildbucket for the build status
[12:58:03.807] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:03.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:115:0) after 1m0s
[12:59:04.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:115:0)
[12:59:04.336] Timer tick, asking Buildbucket for the build status
[12:59:04.801] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:04.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:116:0) after 1m0s
[13:00:05.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:116:0)
[13:00:05.045] Timer tick, asking Buildbucket for the build status
[13:00:05.345] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:05.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:117:0) after 1m0s
[13:01:05.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:117:0)
[13:01:05.363] Timer tick, asking Buildbucket for the build status
[13:01:05.503] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:05.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:118:0) after 1m0s
[13:02:05.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:118:0)
[13:02:05.581] Timer tick, asking Buildbucket for the build status
[13:02:05.712] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:05.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:119:0) after 1m0s
[13:03:06.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:119:0)
[13:03:06.235] Timer tick, asking Buildbucket for the build status
[13:03:06.451] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:06.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:120:0) after 1m0s
[13:04:06.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:120:0)
[13:04:06.452] Timer tick, asking Buildbucket for the build status
[13:04:06.574] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:06.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:121:0) after 1m0s
[13:05:06.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:121:0)
[13:05:06.570] Timer tick, asking Buildbucket for the build status
[13:05:06.651] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:06.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:122:0) after 1m0s
[13:06:06.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:122:0)
[13:06:06.749] Timer tick, asking Buildbucket for the build status
[13:06:06.901] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:06.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:123:0) after 1m0s
[13:07:06.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:123:0)
[13:07:06.972] Timer tick, asking Buildbucket for the build status
[13:07:07.187] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:07.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:124:0) after 1m0s
[13:08:07.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:124:0)
[13:08:07.323] Timer tick, asking Buildbucket for the build status
[13:08:07.751] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:07.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:125:0) after 1m0s
[13:09:08.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:125:0)
[13:09:08.251] Timer tick, asking Buildbucket for the build status
[13:09:08.420] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:08.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:126:0) after 1m0s
[13:10:08.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:126:0)
[13:10:08.535] Timer tick, asking Buildbucket for the build status
[13:10:09.412] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:09.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:127:0) after 1m0s
[13:11:09.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:127:0)
[13:11:09.654] Timer tick, asking Buildbucket for the build status
[13:11:09.852] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:09.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:128:0) after 1m0s
[13:12:09.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:128:0)
[13:12:09.914] Timer tick, asking Buildbucket for the build status
[13:12:09.986] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:09.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:129:0) after 1m0s
[13:13:10.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:129:0)
[13:13:10.392] Timer tick, asking Buildbucket for the build status
[13:13:10.723] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:10.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:130:0) after 1m0s
[13:14:10.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:130:0)
[13:14:10.840] Timer tick, asking Buildbucket for the build status
[13:14:11.374] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:11.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:131:0) after 1m0s
[13:15:11.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:131:0)
[13:15:11.635] Timer tick, asking Buildbucket for the build status
[13:15:11.731] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:11.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:132:0) after 1m0s
[13:16:11.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:132:0)
[13:16:11.925] Timer tick, asking Buildbucket for the build status
[13:16:12.357] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:12.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:133:0) after 1m0s
[13:17:12.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:133:0)
[13:17:12.478] Timer tick, asking Buildbucket for the build status
[13:17:12.573] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:12.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:134:0) after 1m0s
[13:18:12.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:134:0)
[13:18:12.662] Timer tick, asking Buildbucket for the build status
[13:18:12.891] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:12.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:135:0) after 1m0s
[13:19:13.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:135:0)
[13:19:13.003] Timer tick, asking Buildbucket for the build status
[13:19:13.086] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:13.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:136:0) after 1m0s
[13:20:13.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:136:0)
[13:20:13.214] Timer tick, asking Buildbucket for the build status
[13:20:13.345] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:13.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:137:0) after 1m0s
[13:21:13.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:137:0)
[13:21:13.987] Timer tick, asking Buildbucket for the build status
[13:21:14.171] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:14.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:138:0) after 1m0s
[13:22:14.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:138:0)
[13:22:14.280] Timer tick, asking Buildbucket for the build status
[13:22:15.896] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:15.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:139:0) after 1m0s
[13:23:16.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:139:0)
[13:23:16.317] Timer tick, asking Buildbucket for the build status
[13:23:16.536] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:16.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:140:0) after 1m0s
[13:24:16.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:140:0)
[13:24:16.888] Timer tick, asking Buildbucket for the build status
[13:24:17.244] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:17.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:141:0) after 1m0s
[13:25:17.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:141:0)
[13:25:17.640] Timer tick, asking Buildbucket for the build status
[13:25:17.808] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:17.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:142:0) after 1m0s
[13:26:17.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:142:0)
[13:26:17.867] Timer tick, asking Buildbucket for the build status
[13:26:17.991] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:17.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:143:0) after 1m0s
[13:27:17.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:143:0)
[13:27:17.967] Timer tick, asking Buildbucket for the build status
[13:27:18.047] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:18.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:144:0) after 1m0s
[13:28:18.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:144:0)
[13:28:18.193] Timer tick, asking Buildbucket for the build status
[13:28:18.280] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:18.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:145:0) after 1m0s
[13:29:18.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:145:0)
[13:29:18.338] Timer tick, asking Buildbucket for the build status
[13:29:18.422] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:18.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:146:0) after 1m0s
[13:30:19.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:146:0)
[13:30:19.588] Timer tick, asking Buildbucket for the build status
[13:30:19.669] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:19.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:147:0) after 1m0s
[13:31:19.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:147:0)
[13:31:19.777] Timer tick, asking Buildbucket for the build status
[13:31:20.056] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:20.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:148:0) after 1m0s
[13:32:20.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:148:0)
[13:32:20.177] Timer tick, asking Buildbucket for the build status
[13:32:20.265] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:20.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:149:0) after 1m0s
[13:33:20.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:149:0)
[13:33:20.315] Timer tick, asking Buildbucket for the build status
[13:33:20.406] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:20.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:150:0) after 1m0s
[13:34:20.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:150:0)
[13:34:20.405] Timer tick, asking Buildbucket for the build status
[13:34:20.787] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:20.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:151:0) after 1m0s
[13:35:20.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:151:0)
[13:35:20.863] Timer tick, asking Buildbucket for the build status
[13:35:21.013] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:21.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:152:0) after 1m0s
[13:36:21.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:152:0)
[13:36:21.055] Timer tick, asking Buildbucket for the build status
[13:36:21.170] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:21.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:153:0) after 1m0s
[13:37:21.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:153:0)
[13:37:21.281] Timer tick, asking Buildbucket for the build status
[13:37:21.475] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:21.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:154:0) after 1m0s
[13:38:21.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:154:0)
[13:38:21.534] Timer tick, asking Buildbucket for the build status
[13:38:21.619] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:21.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:155:0) after 1m0s
[13:39:21.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:155:0)
[13:39:21.632] Timer tick, asking Buildbucket for the build status
[13:39:21.730] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:21.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:156:0) after 1m0s
[13:40:21.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:156:0)
[13:40:21.818] Timer tick, asking Buildbucket for the build status
[13:40:21.944] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:21.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:157:0) after 1m0s
[13:41:22.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:157:0)
[13:41:22.003] Timer tick, asking Buildbucket for the build status
[13:41:22.123] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:22.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:158:0) after 1m0s
[13:42:22.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:158:0)
[13:42:22.173] Timer tick, asking Buildbucket for the build status
[13:42:22.313] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:22.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:159:0) after 1m0s
[13:43:22.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:159:0)
[13:43:22.464] Timer tick, asking Buildbucket for the build status
[13:43:22.541] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:22.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:160:0) after 1m0s
[13:44:22.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:160:0)
[13:44:22.739] Timer tick, asking Buildbucket for the build status
[13:44:22.823] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:22.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:161:0) after 1m0s
[13:45:22.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:161:0)
[13:45:22.924] Timer tick, asking Buildbucket for the build status
[13:45:23.093] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:23.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:162:0) after 1m0s
[13:46:23.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:162:0)
[13:46:23.158] Timer tick, asking Buildbucket for the build status
[13:46:23.260] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:23.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:163:0) after 1m0s
[13:47:23.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:163:0)
[13:47:23.358] Timer tick, asking Buildbucket for the build status
[13:47:23.448] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:23.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:164:0) after 1m0s
[13:48:23.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:164:0)
[13:48:23.558] Timer tick, asking Buildbucket for the build status
[13:48:23.808] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:23.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:165:0) after 1m0s
[13:49:23.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:165:0)
[13:49:23.858] Timer tick, asking Buildbucket for the build status
[13:49:24.282] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:24.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:166:0) after 1m0s
[13:50:24.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:166:0)
[13:50:24.383] Timer tick, asking Buildbucket for the build status
[13:50:25.698] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:25.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:167:0) after 1m0s
[13:51:25.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:167:0)
[13:51:25.799] Timer tick, asking Buildbucket for the build status
[13:51:26.011] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:26.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:168:0) after 1m0s
[13:52:26.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:168:0)
[13:52:26.039] Timer tick, asking Buildbucket for the build status
[13:52:27.006] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:27.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:169:0) after 1m0s
[13:53:27.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:169:0)
[13:53:27.885] Timer tick, asking Buildbucket for the build status
[13:53:27.985] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:27.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:170:0) after 1m0s
[13:54:28.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:170:0)
[13:54:28.035] Timer tick, asking Buildbucket for the build status
[13:54:28.300] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:28.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:171:0) after 1m0s
[13:55:28.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:171:0)
[13:55:28.460] Timer tick, asking Buildbucket for the build status
[13:55:28.572] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:28.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:172:0) after 1m0s
[13:56:28.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:172:0)
[13:56:28.618] Timer tick, asking Buildbucket for the build status
[13:56:29.188] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:29.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:173:0) after 1m0s
[13:57:29.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:173:0)
[13:57:29.248] Timer tick, asking Buildbucket for the build status
[13:57:29.347] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:29.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:174:0) after 1m0s
[13:58:29.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:174:0)
[13:58:29.441] Timer tick, asking Buildbucket for the build status
[13:58:29.554] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:29.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:175:0) after 1m0s
[13:59:29.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:175:0)
[13:59:29.752] Timer tick, asking Buildbucket for the build status
[13:59:29.837] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:29.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:176:0) after 1m0s
[14:00:29.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:176:0)
[14:00:29.890] Timer tick, asking Buildbucket for the build status
[14:00:31.547] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:31.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:177:0) after 1m0s
[14:01:31.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:177:0)
[14:01:31.539] Timer tick, asking Buildbucket for the build status
[14:01:31.679] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:31.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:178:0) after 1m0s
[14:02:31.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:178:0)
[14:02:31.710] Timer tick, asking Buildbucket for the build status
[14:02:31.822] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:31.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:179:0) after 1m0s
[14:03:32.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:179:0)
[14:03:32.353] Timer tick, asking Buildbucket for the build status
[14:03:32.476] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:32.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:180:0) after 1m0s
[14:04:33.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:180:0)
[14:04:33.450] Timer tick, asking Buildbucket for the build status
[14:04:33.839] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:33.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:181:0) after 1m0s
[14:05:33.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:181:0)
[14:05:33.832] Timer tick, asking Buildbucket for the build status
[14:05:34.618] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:34.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:182:0) after 1m0s
[14:06:34.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:182:0)
[14:06:34.763] Timer tick, asking Buildbucket for the build status
[14:06:34.854] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:34.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:183:0) after 1m0s
[14:07:34.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:183:0)
[14:07:34.967] Timer tick, asking Buildbucket for the build status
[14:07:35.206] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:35.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:184:0) after 1m0s
[14:08:35.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:184:0)
[14:08:35.298] Timer tick, asking Buildbucket for the build status
[14:08:35.390] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:35.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:185:0) after 1m0s
[14:09:35.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:185:0)
[14:09:35.486] Timer tick, asking Buildbucket for the build status
[14:09:35.576] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:35.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:186:0) after 1m0s
[14:10:35.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:186:0)
[14:10:35.624] Timer tick, asking Buildbucket for the build status
[14:10:35.726] Build 8863932016566338672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:35.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9029362079501360320:187:0) after 1m0s
[14:11:00.575] Received PubSub notification, asking Buildbucket for the build status
[14:11:00.654] Build 8863932016566338672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:11:00.654] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1605103859192255",
    "created_by": "project:infra",
    "created_ts": "1605092715637677",
    "id": "8863932016566338672",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4321.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"dfb5c600-2035-4f1d-8ced-7c33f20403bb\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4321.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-swfn\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"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\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1605092723796809",
    "status": "COMPLETED",
    "status_changed_ts": "1605103859838037",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9029362079501360320",
      "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/8863932016566338672/+/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:4fcfccc8f5578f10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1605103859957217",
    "url": "https://ci.chromium.org/b/8863932016566338672",
    "utcnow_ts": "1605103860644920"
  }
}
[14:11:00.654] Invocation finished in 3h5m47.199938249s with status SUCCEEDED