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

• parent_buildername:publish_tarball
• user_agent:recipe

[12:16:33.607] New invocation is queued and will start shortly
[12:16:33.607] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:16:34.702] Starting the invocation (attempt 1)
[12:16:34.741] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:16:34.741] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:16:34.741] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9045302657540435504",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4149.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"17bc1b96-44fd-4b71-a423-9a311e36c3c8\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4149.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9045302657540435504",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:16:35.988] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:infra",
    "created_ts": "1589890594881188",
    "id": "8879872595540250048",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4149.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"17bc1b96-44fd-4b71-a423-9a311e36c3c8\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4149.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1589890595606710",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9045302657540435504",
      "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/8879872595540250048/+/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": "1589890595606891",
    "url": "https://ci.chromium.org/b/8879872595540250048",
    "utcnow_ts": "1589890595966929"
  }
}
[12:16:35.988] Task URL: https://ci.chromium.org/b/8879872595540250048
[12:16:35.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:2:0) after 1m0s
[12:17:36.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:2:0)
[12:17:36.061] Timer tick, asking Buildbucket for the build status
[12:17:36.256] Build 8879872595540250048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:36.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:3:0) after 1m0s
[12:18:36.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:3:0)
[12:18:36.326] Timer tick, asking Buildbucket for the build status
[12:18:36.477] Build 8879872595540250048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:36.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:4:0) after 1m0s
[12:19:36.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:4:0)
[12:19:36.707] Timer tick, asking Buildbucket for the build status
[12:19:37.043] Build 8879872595540250048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:37.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:5:0) after 1m0s
[12:20:37.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:5:0)
[12:20:37.237] Timer tick, asking Buildbucket for the build status
[12:20:38.173] Build 8879872595540250048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:38.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:6:0) after 1m0s
[12:21:38.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:6:0)
[12:21:38.274] Timer tick, asking Buildbucket for the build status
[12:21:38.463] Build 8879872595540250048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:21:38.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:7:0) after 1m0s
[12:22:38.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:7:0)
[12:22:38.551] Timer tick, asking Buildbucket for the build status
[12:22:38.652] Build 8879872595540250048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:38.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:8:0) after 1m0s
[12:23:38.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:8:0)
[12:23:38.851] Timer tick, asking Buildbucket for the build status
[12:23:39.212] Build 8879872595540250048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:39.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:9:0) after 1m0s
[12:24:29.794] Received PubSub notification, asking Buildbucket for the build status
[12:24:29.878] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:39.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:9:0)
[12:24:39.251] Timer tick, asking Buildbucket for the build status
[12:24:39.563] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:39.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:11:0) after 1m0s
[12:25:39.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:11:0)
[12:25:39.666] Timer tick, asking Buildbucket for the build status
[12:25:39.869] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:39.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:12:0) after 1m0s
[12:26:39.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:12:0)
[12:26:39.916] Timer tick, asking Buildbucket for the build status
[12:26:40.180] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:40.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:13:0) after 1m0s
[12:27:40.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:13:0)
[12:27:40.177] Timer tick, asking Buildbucket for the build status
[12:27:40.494] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:40.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:14:0) after 1m0s
[12:28:40.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:14:0)
[12:28:40.576] Timer tick, asking Buildbucket for the build status
[12:28:40.710] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:40.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:15:0) after 1m0s
[12:29:40.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:15:0)
[12:29:40.877] Timer tick, asking Buildbucket for the build status
[12:29:41.029] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:41.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:16:0) after 1m0s
[12:30:41.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:16:0)
[12:30:41.076] Timer tick, asking Buildbucket for the build status
[12:30:41.252] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:41.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:17:0) after 1m0s
[12:31:41.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:17:0)
[12:31:41.309] Timer tick, asking Buildbucket for the build status
[12:31:41.620] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:41.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:18:0) after 1m0s
[12:32:41.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:18:0)
[12:32:41.594] Timer tick, asking Buildbucket for the build status
[12:32:41.779] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:41.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:19:0) after 1m0s
[12:33:41.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:19:0)
[12:33:41.791] Timer tick, asking Buildbucket for the build status
[12:33:42.111] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:42.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:20:0) after 1m0s
[12:34:42.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:20:0)
[12:34:42.147] Timer tick, asking Buildbucket for the build status
[12:34:43.477] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:43.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:21:0) after 1m0s
[12:35:43.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:21:0)
[12:35:43.536] Timer tick, asking Buildbucket for the build status
[12:35:43.726] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:43.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:22:0) after 1m0s
[12:36:43.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:22:0)
[12:36:43.758] Timer tick, asking Buildbucket for the build status
[12:36:43.982] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:43.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:23:0) after 1m0s
[12:37:44.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:23:0)
[12:37:44.023] Timer tick, asking Buildbucket for the build status
[12:37:44.258] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:44.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:24:0) after 1m0s
[12:38:44.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:24:0)
[12:38:44.256] Timer tick, asking Buildbucket for the build status
[12:38:44.514] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:44.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:25:0) after 1m0s
[12:39:44.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:25:0)
[12:39:44.684] Timer tick, asking Buildbucket for the build status
[12:39:45.208] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:45.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:26:0) after 1m0s
[12:40:45.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:26:0)
[12:40:45.240] Timer tick, asking Buildbucket for the build status
[12:40:47.778] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:47.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:27:0) after 1m0s
[12:41:47.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:27:0)
[12:41:47.938] Timer tick, asking Buildbucket for the build status
[12:41:48.535] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:48.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:28:0) after 1m0s
[12:42:48.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:28:0)
[12:42:48.654] Timer tick, asking Buildbucket for the build status
[12:42:48.817] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:48.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:29:0) after 1m0s
[12:43:48.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:29:0)
[12:43:48.901] Timer tick, asking Buildbucket for the build status
[12:43:49.263] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:49.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:30:0) after 1m0s
[12:44:49.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:30:0)
[12:44:49.379] Timer tick, asking Buildbucket for the build status
[12:44:49.518] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:49.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:31:0) after 1m0s
[12:45:49.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:31:0)
[12:45:49.534] Timer tick, asking Buildbucket for the build status
[12:45:49.707] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:49.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:32:0) after 1m0s
[12:46:50.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:32:0)
[12:46:50.125] Timer tick, asking Buildbucket for the build status
[12:46:50.380] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:50.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:33:0) after 1m0s
[12:47:50.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:33:0)
[12:47:50.533] Timer tick, asking Buildbucket for the build status
[12:47:50.840] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:50.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:34:0) after 1m0s
[12:48:50.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:34:0)
[12:48:50.986] Timer tick, asking Buildbucket for the build status
[12:48:51.212] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:51.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:35:0) after 1m0s
[12:49:51.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:35:0)
[12:49:51.399] Timer tick, asking Buildbucket for the build status
[12:49:51.515] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:51.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:36:0) after 1m0s
[12:50:51.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:36:0)
[12:50:51.647] Timer tick, asking Buildbucket for the build status
[12:50:52.120] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:52.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:37:0) after 1m0s
[12:51:52.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:37:0)
[12:51:52.311] Timer tick, asking Buildbucket for the build status
[12:51:52.658] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:52.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:38:0) after 1m0s
[12:52:52.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:38:0)
[12:52:52.748] Timer tick, asking Buildbucket for the build status
[12:52:52.878] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:52.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:39:0) after 1m0s
[12:53:53.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:39:0)
[12:53:53.071] Timer tick, asking Buildbucket for the build status
[12:53:53.499] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:53.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:40:0) after 1m0s
[12:54:53.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:40:0)
[12:54:53.554] Timer tick, asking Buildbucket for the build status
[12:54:53.699] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:53.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:41:0) after 1m0s
[12:55:53.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:41:0)
[12:55:53.791] Timer tick, asking Buildbucket for the build status
[12:55:54.733] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:54.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:42:0) after 1m0s
[12:56:54.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:42:0)
[12:56:54.936] Timer tick, asking Buildbucket for the build status
[12:56:55.117] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:55.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:43:0) after 1m0s
[12:57:55.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:43:0)
[12:57:55.267] Timer tick, asking Buildbucket for the build status
[12:57:55.557] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:55.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:44:0) after 1m0s
[12:58:55.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:44:0)
[12:58:55.740] Timer tick, asking Buildbucket for the build status
[12:58:56.374] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:56.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:45:0) after 1m0s
[12:59:56.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:45:0)
[12:59:56.478] Timer tick, asking Buildbucket for the build status
[12:59:56.594] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:56.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:46:0) after 1m0s
[13:00:56.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:46:0)
[13:00:56.657] Timer tick, asking Buildbucket for the build status
[13:00:57.167] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:57.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:47:0) after 1m0s
[13:01:57.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:47:0)
[13:01:57.560] Timer tick, asking Buildbucket for the build status
[13:01:57.727] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:57.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:48:0) after 1m0s
[13:02:57.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:48:0)
[13:02:57.754] Timer tick, asking Buildbucket for the build status
[13:02:57.902] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:57.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:49:0) after 1m0s
[13:03:57.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:49:0)
[13:03:57.955] Timer tick, asking Buildbucket for the build status
[13:03:58.279] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:58.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:50:0) after 1m0s
[13:04:58.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:50:0)
[13:04:58.329] Timer tick, asking Buildbucket for the build status
[13:04:58.563] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:58.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:51:0) after 1m0s
[13:05:58.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:51:0)
[13:05:58.723] Timer tick, asking Buildbucket for the build status
[13:05:59.241] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:59.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:52:0) after 1m0s
[13:06:59.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:52:0)
[13:06:59.343] Timer tick, asking Buildbucket for the build status
[13:06:59.484] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:59.484] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:53:0) after 1m0s
[13:07:59.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:53:0)
[13:07:59.526] Timer tick, asking Buildbucket for the build status
[13:07:59.685] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:59.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:54:0) after 1m0s
[13:08:59.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:54:0)
[13:08:59.709] Timer tick, asking Buildbucket for the build status
[13:08:59.864] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:59.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:55:0) after 1m0s
[13:10:00.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:55:0)
[13:10:00.051] Timer tick, asking Buildbucket for the build status
[13:10:00.495] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:00.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:56:0) after 1m0s
[13:11:01.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:56:0)
[13:11:01.716] Timer tick, asking Buildbucket for the build status
[13:11:01.862] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:01.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:57:0) after 1m0s
[13:12:01.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:57:0)
[13:12:01.920] Timer tick, asking Buildbucket for the build status
[13:12:02.083] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:02.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:58:0) after 1m0s
[13:13:02.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:58:0)
[13:13:02.259] Timer tick, asking Buildbucket for the build status
[13:13:02.380] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:02.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:59:0) after 1m0s
[13:14:02.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:59:0)
[13:14:02.399] Timer tick, asking Buildbucket for the build status
[13:14:02.589] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:02.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:60:0) after 1m0s
[13:15:02.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:60:0)
[13:15:02.622] Timer tick, asking Buildbucket for the build status
[13:15:03.469] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:03.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:61:0) after 1m0s
[13:16:03.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:61:0)
[13:16:03.456] Timer tick, asking Buildbucket for the build status
[13:16:03.594] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:03.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:62:0) after 1m0s
[13:17:03.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:62:0)
[13:17:03.638] Timer tick, asking Buildbucket for the build status
[13:17:03.797] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:03.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:63:0) after 1m0s
[13:18:04.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:63:0)
[13:18:04.005] Timer tick, asking Buildbucket for the build status
[13:18:04.151] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:04.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:64:0) after 1m0s
[13:19:04.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:64:0)
[13:19:04.204] Timer tick, asking Buildbucket for the build status
[13:19:04.620] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:04.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:65:0) after 1m0s
[13:20:04.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:65:0)
[13:20:04.672] Timer tick, asking Buildbucket for the build status
[13:20:04.902] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:04.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:66:0) after 1m0s
[13:21:04.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:66:0)
[13:21:04.962] Timer tick, asking Buildbucket for the build status
[13:21:05.377] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:05.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:67:0) after 1m0s
[13:22:05.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:67:0)
[13:22:05.468] Timer tick, asking Buildbucket for the build status
[13:22:05.652] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:05.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:68:0) after 1m0s
[13:23:05.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:68:0)
[13:23:05.692] Timer tick, asking Buildbucket for the build status
[13:23:05.852] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:05.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:69:0) after 1m0s
[13:24:05.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:69:0)
[13:24:05.888] Timer tick, asking Buildbucket for the build status
[13:24:06.102] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:06.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:70:0) after 1m0s
[13:25:06.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:70:0)
[13:25:06.205] Timer tick, asking Buildbucket for the build status
[13:25:06.340] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:06.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:71:0) after 1m0s
[13:26:06.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:71:0)
[13:26:06.379] Timer tick, asking Buildbucket for the build status
[13:26:06.521] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:06.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:72:0) after 1m0s
[13:27:06.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:72:0)
[13:27:06.581] Timer tick, asking Buildbucket for the build status
[13:27:06.712] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:06.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:73:0) after 1m0s
[13:28:06.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:73:0)
[13:28:06.854] Timer tick, asking Buildbucket for the build status
[13:28:06.972] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:06.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:74:0) after 1m0s
[13:29:06.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:74:0)
[13:29:06.996] Timer tick, asking Buildbucket for the build status
[13:29:07.347] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:07.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:75:0) after 1m0s
[13:30:07.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:75:0)
[13:30:07.323] Timer tick, asking Buildbucket for the build status
[13:30:07.587] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:07.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:76:0) after 1m0s
[13:31:08.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:76:0)
[13:31:08.182] Timer tick, asking Buildbucket for the build status
[13:31:08.434] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:08.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:77:0) after 1m0s
[13:32:08.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:77:0)
[13:32:08.610] Timer tick, asking Buildbucket for the build status
[13:32:08.754] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:08.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:78:0) after 1m0s
[13:33:08.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:78:0)
[13:33:08.795] Timer tick, asking Buildbucket for the build status
[13:33:09.477] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:09.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:79:0) after 1m0s
[13:34:09.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:79:0)
[13:34:09.553] Timer tick, asking Buildbucket for the build status
[13:34:09.926] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:09.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:80:0) after 1m0s
[13:35:10.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:80:0)
[13:35:10.163] Timer tick, asking Buildbucket for the build status
[13:35:10.432] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:10.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:81:0) after 1m0s
[13:36:10.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:81:0)
[13:36:10.668] Timer tick, asking Buildbucket for the build status
[13:36:10.912] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:10.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:82:0) after 1m0s
[13:37:11.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:82:0)
[13:37:11.046] Timer tick, asking Buildbucket for the build status
[13:37:11.453] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:11.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:83:0) after 1m0s
[13:38:11.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:83:0)
[13:38:11.606] Timer tick, asking Buildbucket for the build status
[13:38:11.927] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:11.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:84:0) after 1m0s
[13:39:12.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:84:0)
[13:39:12.401] Timer tick, asking Buildbucket for the build status
[13:39:12.648] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:12.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:85:0) after 1m0s
[13:40:12.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:85:0)
[13:40:12.757] Timer tick, asking Buildbucket for the build status
[13:40:14.578] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:14.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:86:0) after 1m0s
[13:41:14.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:86:0)
[13:41:14.623] Timer tick, asking Buildbucket for the build status
[13:41:14.866] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:14.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:87:0) after 1m0s
[13:42:14.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:87:0)
[13:42:14.988] Timer tick, asking Buildbucket for the build status
[13:42:15.192] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:15.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:88:0) after 1m0s
[13:43:15.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:88:0)
[13:43:15.329] Timer tick, asking Buildbucket for the build status
[13:43:15.744] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:15.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:89:0) after 1m0s
[13:44:15.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:89:0)
[13:44:15.947] Timer tick, asking Buildbucket for the build status
[13:44:16.328] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:16.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:90:0) after 1m0s
[13:45:16.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:90:0)
[13:45:16.423] Timer tick, asking Buildbucket for the build status
[13:45:16.889] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:16.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:91:0) after 1m0s
[13:46:16.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:91:0)
[13:46:16.974] Timer tick, asking Buildbucket for the build status
[13:46:17.188] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:17.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:92:0) after 1m0s
[13:47:17.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:92:0)
[13:47:17.251] Timer tick, asking Buildbucket for the build status
[13:47:17.361] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:17.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:93:0) after 1m0s
[13:48:17.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:93:0)
[13:48:17.542] Timer tick, asking Buildbucket for the build status
[13:48:17.734] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:17.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:94:0) after 1m0s
[13:49:17.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:94:0)
[13:49:17.841] Timer tick, asking Buildbucket for the build status
[13:49:18.071] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:18.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:95:0) after 1m0s
[13:50:18.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:95:0)
[13:50:18.176] Timer tick, asking Buildbucket for the build status
[13:50:18.382] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:18.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:96:0) after 1m0s
[13:51:18.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:96:0)
[13:51:18.440] Timer tick, asking Buildbucket for the build status
[13:51:18.667] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:18.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:97:0) after 1m0s
[13:52:18.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:97:0)
[13:52:18.743] Timer tick, asking Buildbucket for the build status
[13:52:18.873] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:18.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:98:0) after 1m0s
[13:53:18.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:98:0)
[13:53:18.930] Timer tick, asking Buildbucket for the build status
[13:53:19.533] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:19.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:99:0) after 1m0s
[13:54:19.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:99:0)
[13:54:19.602] Timer tick, asking Buildbucket for the build status
[13:54:19.906] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:19.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:100:0) after 1m0s
[13:55:20.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:100:0)
[13:55:20.011] Timer tick, asking Buildbucket for the build status
[13:55:20.365] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:20.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:101:0) after 1m0s
[13:56:20.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:101:0)
[13:56:20.400] Timer tick, asking Buildbucket for the build status
[13:56:20.714] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:20.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:102:0) after 1m0s
[13:57:20.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:102:0)
[13:57:20.836] Timer tick, asking Buildbucket for the build status
[13:57:22.007] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:22.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:103:0) after 1m0s
[13:58:22.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:103:0)
[13:58:22.022] Timer tick, asking Buildbucket for the build status
[13:58:22.135] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:22.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:104:0) after 1m0s
[13:59:22.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:104:0)
[13:59:22.158] Timer tick, asking Buildbucket for the build status
[13:59:22.457] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:22.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:105:0) after 1m0s
[14:00:22.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:105:0)
[14:00:22.493] Timer tick, asking Buildbucket for the build status
[14:00:22.888] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:22.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:106:0) after 1m0s
[14:01:23.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:106:0)
[14:01:23.023] Timer tick, asking Buildbucket for the build status
[14:01:23.284] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:23.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:107:0) after 1m0s
[14:02:23.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:107:0)
[14:02:23.381] Timer tick, asking Buildbucket for the build status
[14:02:23.549] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:23.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:108:0) after 1m0s
[14:03:23.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:108:0)
[14:03:23.642] Timer tick, asking Buildbucket for the build status
[14:03:23.850] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:23.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:109:0) after 1m0s
[14:04:23.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:109:0)
[14:04:23.859] Timer tick, asking Buildbucket for the build status
[14:04:24.266] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:24.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:110:0) after 1m0s
[14:05:24.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:110:0)
[14:05:24.338] Timer tick, asking Buildbucket for the build status
[14:05:24.599] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:24.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:111:0) after 1m0s
[14:06:24.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:111:0)
[14:06:24.637] Timer tick, asking Buildbucket for the build status
[14:06:24.771] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:24.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:112:0) after 1m0s
[14:07:24.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:112:0)
[14:07:24.870] Timer tick, asking Buildbucket for the build status
[14:07:25.041] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:25.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:113:0) after 1m0s
[14:08:25.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:113:0)
[14:08:25.048] Timer tick, asking Buildbucket for the build status
[14:08:25.200] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:25.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:114:0) after 1m0s
[14:09:25.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:114:0)
[14:09:25.252] Timer tick, asking Buildbucket for the build status
[14:09:25.415] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:25.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:115:0) after 1m0s
[14:10:25.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:115:0)
[14:10:25.444] Timer tick, asking Buildbucket for the build status
[14:10:26.369] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:26.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:116:0) after 1m0s
[14:11:26.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:116:0)
[14:11:26.424] Timer tick, asking Buildbucket for the build status
[14:11:26.576] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:26.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:117:0) after 1m0s
[14:12:26.605] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:117:0)
[14:12:26.605] Timer tick, asking Buildbucket for the build status
[14:12:26.714] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:26.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:118:0) after 1m0s
[14:13:26.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:118:0)
[14:13:26.743] Timer tick, asking Buildbucket for the build status
[14:13:26.911] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:26.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:119:0) after 1m0s
[14:14:26.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:119:0)
[14:14:26.924] Timer tick, asking Buildbucket for the build status
[14:14:27.134] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:27.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:120:0) after 1m0s
[14:15:27.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:120:0)
[14:15:27.206] Timer tick, asking Buildbucket for the build status
[14:15:27.791] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:27.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:121:0) after 1m0s
[14:16:27.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:121:0)
[14:16:27.814] Timer tick, asking Buildbucket for the build status
[14:16:27.927] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:27.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:122:0) after 1m0s
[14:17:27.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:122:0)
[14:17:27.995] Timer tick, asking Buildbucket for the build status
[14:17:28.132] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:28.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:123:0) after 1m0s
[14:18:28.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:123:0)
[14:18:28.318] Timer tick, asking Buildbucket for the build status
[14:18:28.447] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:28.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:124:0) after 1m0s
[14:19:28.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:124:0)
[14:19:28.497] Timer tick, asking Buildbucket for the build status
[14:19:28.663] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:28.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:125:0) after 1m0s
[14:20:28.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:125:0)
[14:20:28.770] Timer tick, asking Buildbucket for the build status
[14:20:30.527] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:30.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:126:0) after 1m0s
[14:21:30.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:126:0)
[14:21:30.581] Timer tick, asking Buildbucket for the build status
[14:21:31.326] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:31.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:127:0) after 1m0s
[14:22:31.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:127:0)
[14:22:31.404] Timer tick, asking Buildbucket for the build status
[14:22:31.924] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:31.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:128:0) after 1m0s
[14:23:31.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:128:0)
[14:23:31.985] Timer tick, asking Buildbucket for the build status
[14:23:32.383] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:32.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:129:0) after 1m0s
[14:24:32.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:129:0)
[14:24:32.406] Timer tick, asking Buildbucket for the build status
[14:24:32.561] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:32.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:130:0) after 1m0s
[14:25:32.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:130:0)
[14:25:32.659] Timer tick, asking Buildbucket for the build status
[14:25:32.907] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:32.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:131:0) after 1m0s
[14:26:33.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:131:0)
[14:26:33.007] Timer tick, asking Buildbucket for the build status
[14:26:33.276] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:33.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:132:0) after 1m0s
[14:27:33.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:132:0)
[14:27:33.418] Timer tick, asking Buildbucket for the build status
[14:27:33.909] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:33.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:133:0) after 1m0s
[14:28:33.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:133:0)
[14:28:33.921] Timer tick, asking Buildbucket for the build status
[14:28:34.106] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:34.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:134:0) after 1m0s
[14:29:34.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:134:0)
[14:29:34.142] Timer tick, asking Buildbucket for the build status
[14:29:34.283] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:34.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:135:0) after 1m0s
[14:30:34.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:135:0)
[14:30:34.325] Timer tick, asking Buildbucket for the build status
[14:30:35.972] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:35.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:136:0) after 1m0s
[14:31:35.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:136:0)
[14:31:35.999] Timer tick, asking Buildbucket for the build status
[14:31:38.502] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:38.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:137:0) after 1m0s
[14:32:38.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:137:0)
[14:32:38.537] Timer tick, asking Buildbucket for the build status
[14:32:38.879] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:38.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:138:0) after 1m0s
[14:33:38.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:138:0)
[14:33:38.894] Timer tick, asking Buildbucket for the build status
[14:33:39.063] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:39.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:139:0) after 1m0s
[14:34:39.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:139:0)
[14:34:39.163] Timer tick, asking Buildbucket for the build status
[14:34:39.528] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:39.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:140:0) after 1m0s
[14:35:39.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:140:0)
[14:35:39.552] Timer tick, asking Buildbucket for the build status
[14:35:39.765] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:39.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:141:0) after 1m0s
[14:36:40.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:141:0)
[14:36:40.005] Timer tick, asking Buildbucket for the build status
[14:36:40.221] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:40.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:142:0) after 1m0s
[14:37:40.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:142:0)
[14:37:40.380] Timer tick, asking Buildbucket for the build status
[14:37:40.756] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:40.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:143:0) after 1m0s
[14:38:40.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:143:0)
[14:38:40.904] Timer tick, asking Buildbucket for the build status
[14:38:41.214] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:41.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:144:0) after 1m0s
[14:39:41.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:144:0)
[14:39:41.391] Timer tick, asking Buildbucket for the build status
[14:39:41.578] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:41.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:145:0) after 1m0s
[14:40:41.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:145:0)
[14:40:41.651] Timer tick, asking Buildbucket for the build status
[14:40:41.922] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:41.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:146:0) after 1m0s
[14:41:42.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:146:0)
[14:41:42.076] Timer tick, asking Buildbucket for the build status
[14:41:42.308] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:42.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:147:0) after 1m0s
[14:42:42.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:147:0)
[14:42:42.439] Timer tick, asking Buildbucket for the build status
[14:42:42.806] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:42.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:148:0) after 1m0s
[14:43:42.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:148:0)
[14:43:42.801] Timer tick, asking Buildbucket for the build status
[14:43:43.343] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:43.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:149:0) after 1m0s
[14:44:43.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:149:0)
[14:44:43.462] Timer tick, asking Buildbucket for the build status
[14:44:43.595] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:43.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:150:0) after 1m0s
[14:45:43.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:150:0)
[14:45:43.750] Timer tick, asking Buildbucket for the build status
[14:45:43.978] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:43.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:151:0) after 1m0s
[14:46:44.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:151:0)
[14:46:44.294] Timer tick, asking Buildbucket for the build status
[14:46:44.901] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:44.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:152:0) after 1m0s
[14:47:45.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:152:0)
[14:47:45.047] Timer tick, asking Buildbucket for the build status
[14:47:45.283] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:45.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:153:0) after 1m0s
[14:48:45.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:153:0)
[14:48:45.309] Timer tick, asking Buildbucket for the build status
[14:48:45.497] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:45.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:154:0) after 1m0s
[14:49:45.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:154:0)
[14:49:45.564] Timer tick, asking Buildbucket for the build status
[14:49:46.162] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:46.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:155:0) after 1m0s
[14:50:46.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:155:0)
[14:50:46.183] Timer tick, asking Buildbucket for the build status
[14:50:46.497] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:46.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:156:0) after 1m0s
[14:51:46.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:156:0)
[14:51:46.591] Timer tick, asking Buildbucket for the build status
[14:51:46.785] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:46.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:157:0) after 1m0s
[14:52:46.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:157:0)
[14:52:46.815] Timer tick, asking Buildbucket for the build status
[14:52:47.436] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:47.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:158:0) after 1m0s
[14:53:47.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:158:0)
[14:53:47.462] Timer tick, asking Buildbucket for the build status
[14:53:47.618] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:47.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:159:0) after 1m0s
[14:54:47.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:159:0)
[14:54:47.703] Timer tick, asking Buildbucket for the build status
[14:54:47.914] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:47.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:160:0) after 1m0s
[14:55:47.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:160:0)
[14:55:47.971] Timer tick, asking Buildbucket for the build status
[14:55:48.285] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:48.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:161:0) after 1m0s
[14:56:48.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:161:0)
[14:56:48.311] Timer tick, asking Buildbucket for the build status
[14:56:48.453] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:48.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:162:0) after 1m0s
[14:57:48.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:162:0)
[14:57:48.524] Timer tick, asking Buildbucket for the build status
[14:57:48.725] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:48.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:163:0) after 1m0s
[14:58:48.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:163:0)
[14:58:48.901] Timer tick, asking Buildbucket for the build status
[14:58:49.094] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:49.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:164:0) after 1m0s
[14:59:49.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:164:0)
[14:59:49.188] Timer tick, asking Buildbucket for the build status
[14:59:49.337] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:49.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:165:0) after 1m0s
[15:00:49.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:165:0)
[15:00:49.446] Timer tick, asking Buildbucket for the build status
[15:00:49.612] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:49.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:166:0) after 1m0s
[15:01:49.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:166:0)
[15:01:49.775] Timer tick, asking Buildbucket for the build status
[15:01:49.923] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:49.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:167:0) after 1m0s
[15:02:49.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:167:0)
[15:02:49.998] Timer tick, asking Buildbucket for the build status
[15:02:51.519] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:51.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:168:0) after 1m0s
[15:03:52.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:168:0)
[15:03:52.405] Timer tick, asking Buildbucket for the build status
[15:03:52.596] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:52.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:169:0) after 1m0s
[15:04:52.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:169:0)
[15:04:52.789] Timer tick, asking Buildbucket for the build status
[15:04:52.948] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:52.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:170:0) after 1m0s
[15:05:53.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:170:0)
[15:05:53.003] Timer tick, asking Buildbucket for the build status
[15:05:53.265] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:53.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:171:0) after 1m0s
[15:06:53.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:171:0)
[15:06:53.357] Timer tick, asking Buildbucket for the build status
[15:06:53.601] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:53.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:172:0) after 1m0s
[15:07:53.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:172:0)
[15:07:53.695] Timer tick, asking Buildbucket for the build status
[15:07:54.652] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:54.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:173:0) after 1m0s
[15:08:54.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:173:0)
[15:08:54.729] Timer tick, asking Buildbucket for the build status
[15:08:55.124] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:55.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:174:0) after 1m0s
[15:09:55.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:174:0)
[15:09:55.279] Timer tick, asking Buildbucket for the build status
[15:09:55.394] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:55.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:175:0) after 1m0s
[15:10:55.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:175:0)
[15:10:55.409] Timer tick, asking Buildbucket for the build status
[15:10:55.519] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:55.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:176:0) after 1m0s
[15:11:55.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:176:0)
[15:11:55.557] Timer tick, asking Buildbucket for the build status
[15:11:55.716] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:55.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:177:0) after 1m0s
[15:12:56.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:177:0)
[15:12:56.062] Timer tick, asking Buildbucket for the build status
[15:12:56.215] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:56.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:178:0) after 1m0s
[15:13:56.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:178:0)
[15:13:56.310] Timer tick, asking Buildbucket for the build status
[15:13:56.479] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:56.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:179:0) after 1m0s
[15:14:56.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:179:0)
[15:14:56.667] Timer tick, asking Buildbucket for the build status
[15:14:56.924] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:56.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:180:0) after 1m0s
[15:15:57.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:180:0)
[15:15:57.065] Timer tick, asking Buildbucket for the build status
[15:15:57.310] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:57.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:181:0) after 1m0s
[15:16:57.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:181:0)
[15:16:57.424] Timer tick, asking Buildbucket for the build status
[15:16:57.804] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:57.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:182:0) after 1m0s
[15:17:57.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:182:0)
[15:17:57.848] Timer tick, asking Buildbucket for the build status
[15:17:58.018] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:58.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:183:0) after 1m0s
[15:18:58.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:183:0)
[15:18:58.152] Timer tick, asking Buildbucket for the build status
[15:18:58.480] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:58.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:184:0) after 1m0s
[15:19:58.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:184:0)
[15:19:58.543] Timer tick, asking Buildbucket for the build status
[15:19:58.827] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:58.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:185:0) after 1m0s
[15:20:59.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:185:0)
[15:20:59.139] Timer tick, asking Buildbucket for the build status
[15:20:59.514] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:59.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:186:0) after 1m0s
[15:21:59.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:186:0)
[15:21:59.563] Timer tick, asking Buildbucket for the build status
[15:22:00.208] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:00.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:187:0) after 1m0s
[15:23:00.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:187:0)
[15:23:00.341] Timer tick, asking Buildbucket for the build status
[15:23:00.546] Build 8879872595540250048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:00.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9045302657540435504:188:0) after 1m0s
[15:23:47.594] Received PubSub notification, asking Buildbucket for the build status
[15:23:47.855] Build 8879872595540250048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:23:47.855] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1589901826552225",
    "created_by": "project:infra",
    "created_ts": "1589890594881188",
    "id": "8879872595540250048",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4149.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"17bc1b96-44fd-4b71-a423-9a311e36c3c8\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4149.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4149.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"17bc1b96-44fd-4b71-a423-9a311e36c3c8\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-ny7m\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"85.0.4149.0\"}, \"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-ny7m\"], \"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\": [\"5100-d2625c1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1589891067235567",
    "status": "COMPLETED",
    "status_changed_ts": "1589901827056892",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9045302657540435504",
      "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/8879872595540250048/+/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:4c45aec5f73d2210",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1589901827074343",
    "url": "https://ci.chromium.org/b/8879872595540250048",
    "utcnow_ts": "1589901827834762"
  }
}
[15:23:47.855] Invocation finished in 3h7m14.264895445s with status SUCCEEDED