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

• parent_buildername:publish_tarball
• user_agent:recipe

[12:39:05.309] New invocation is queued and will start shortly
[12:39:05.309] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:39:06.676] Starting the invocation (attempt 1)
[12:39:06.754] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:39:06.754] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:39:06.754] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9049649894607913296",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"83.0.4100.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"f2ce7770-efef-43a4-8629-f5af63e23067\",\"title\":\"publish_tarball/0\"}]},\"version\":\"83.0.4100.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:9049649894607913296",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:39:08.519] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1585744746978621",
    "id": "8884219832150676528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"83.0.4100.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f2ce7770-efef-43a4-8629-f5af63e23067\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"83.0.4100.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585744748284280",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9049649894607913296",
      "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/8884219832150676528/+/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": "1585744748284421",
    "url": "https://ci.chromium.org/b/8884219832150676528",
    "utcnow_ts": "1585744748503390"
  }
}
[12:39:08.519] Task URL: https://ci.chromium.org/b/8884219832150676528
[12:39:08.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:2:0) after 1m0s
[12:40:08.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:2:0)
[12:40:08.591] Timer tick, asking Buildbucket for the build status
[12:40:08.781] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:08.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:3:0) after 1m0s
[12:41:08.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:3:0)
[12:41:08.853] Timer tick, asking Buildbucket for the build status
[12:41:09.044] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:09.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:4:0) after 1m0s
[12:42:09.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:4:0)
[12:42:09.065] Timer tick, asking Buildbucket for the build status
[12:42:09.278] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:09.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:5:0) after 1m0s
[12:43:09.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:5:0)
[12:43:09.330] Timer tick, asking Buildbucket for the build status
[12:43:11.594] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:11.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:6:0) after 1m0s
[12:44:11.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:6:0)
[12:44:11.635] Timer tick, asking Buildbucket for the build status
[12:44:11.821] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:11.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:7:0) after 1m0s
[12:45:11.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:7:0)
[12:45:11.897] Timer tick, asking Buildbucket for the build status
[12:45:12.048] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:12.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:8:0) after 1m0s
[12:46:12.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:8:0)
[12:46:12.127] Timer tick, asking Buildbucket for the build status
[12:46:13.296] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:13.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:9:0) after 1m0s
[12:47:13.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:9:0)
[12:47:13.354] Timer tick, asking Buildbucket for the build status
[12:47:13.598] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:13.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:10:0) after 1m0s
[12:48:13.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:10:0)
[12:48:13.610] Timer tick, asking Buildbucket for the build status
[12:48:14.221] Build 8884219832150676528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:14.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:11:0) after 1m0s
[12:48:30.495] Received PubSub notification, asking Buildbucket for the build status
[12:48:30.726] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:14.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:11:0)
[12:49:14.246] Timer tick, asking Buildbucket for the build status
[12:49:14.432] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:14.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:13:0) after 1m0s
[12:50:14.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:13:0)
[12:50:14.487] Timer tick, asking Buildbucket for the build status
[12:50:14.965] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:14.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:14:0) after 1m0s
[12:51:15.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:14:0)
[12:51:15.017] Timer tick, asking Buildbucket for the build status
[12:51:15.247] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:15.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:15:0) after 1m0s
[12:52:15.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:15:0)
[12:52:15.304] Timer tick, asking Buildbucket for the build status
[12:52:15.699] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:15.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:16:0) after 1m0s
[12:53:15.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:16:0)
[12:53:15.717] Timer tick, asking Buildbucket for the build status
[12:53:15.906] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:15.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:17:0) after 1m0s
[12:54:15.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:17:0)
[12:54:15.927] Timer tick, asking Buildbucket for the build status
[12:54:16.153] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:16.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:18:0) after 1m0s
[12:55:16.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:18:0)
[12:55:16.184] Timer tick, asking Buildbucket for the build status
[12:55:16.704] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:16.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:19:0) after 1m0s
[12:56:16.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:19:0)
[12:56:16.722] Timer tick, asking Buildbucket for the build status
[12:56:16.892] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:16.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:20:0) after 1m0s
[12:57:16.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:20:0)
[12:57:16.945] Timer tick, asking Buildbucket for the build status
[12:57:18.122] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:18.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:21:0) after 1m0s
[12:58:18.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:21:0)
[12:58:18.143] Timer tick, asking Buildbucket for the build status
[12:58:18.682] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:18.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:22:0) after 1m0s
[12:59:18.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:22:0)
[12:59:18.694] Timer tick, asking Buildbucket for the build status
[12:59:18.814] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:18.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:23:0) after 1m0s
[13:00:18.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:23:0)
[13:00:18.868] Timer tick, asking Buildbucket for the build status
[13:00:19.172] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:19.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:24:0) after 1m0s
[13:01:19.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:24:0)
[13:01:19.228] Timer tick, asking Buildbucket for the build status
[13:01:19.569] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:19.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:25:0) after 1m0s
[13:02:19.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:25:0)
[13:02:19.591] Timer tick, asking Buildbucket for the build status
[13:02:19.742] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:19.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:26:0) after 1m0s
[13:03:19.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:26:0)
[13:03:19.792] Timer tick, asking Buildbucket for the build status
[13:03:20.054] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:20.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:27:0) after 1m0s
[13:04:20.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:27:0)
[13:04:20.047] Timer tick, asking Buildbucket for the build status
[13:04:20.185] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:20.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:28:0) after 1m0s
[13:05:20.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:28:0)
[13:05:20.228] Timer tick, asking Buildbucket for the build status
[13:05:20.375] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:20.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:29:0) after 1m0s
[13:06:20.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:29:0)
[13:06:20.396] Timer tick, asking Buildbucket for the build status
[13:06:20.529] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:20.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:30:0) after 1m0s
[13:07:20.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:30:0)
[13:07:20.552] Timer tick, asking Buildbucket for the build status
[13:07:20.704] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:20.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:31:0) after 1m0s
[13:08:20.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:31:0)
[13:08:20.709] Timer tick, asking Buildbucket for the build status
[13:08:20.984] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:20.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:32:0) after 1m0s
[13:09:20.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:32:0)
[13:09:20.982] Timer tick, asking Buildbucket for the build status
[13:09:21.124] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:21.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:33:0) after 1m0s
[13:10:21.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:33:0)
[13:10:21.143] Timer tick, asking Buildbucket for the build status
[13:10:21.311] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:21.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:34:0) after 1m0s
[13:11:21.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:34:0)
[13:11:21.331] Timer tick, asking Buildbucket for the build status
[13:11:21.541] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:21.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:35:0) after 1m0s
[13:12:21.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:35:0)
[13:12:21.575] Timer tick, asking Buildbucket for the build status
[13:12:21.889] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:21.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:36:0) after 1m0s
[13:13:21.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:36:0)
[13:13:21.953] Timer tick, asking Buildbucket for the build status
[13:13:22.819] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:22.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:37:0) after 1m0s
[13:14:22.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:37:0)
[13:14:22.841] Timer tick, asking Buildbucket for the build status
[13:14:22.998] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:22.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:38:0) after 1m0s
[13:15:23.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:38:0)
[13:15:23.097] Timer tick, asking Buildbucket for the build status
[13:15:23.408] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:23.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:39:0) after 1m0s
[13:16:23.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:39:0)
[13:16:23.457] Timer tick, asking Buildbucket for the build status
[13:16:23.604] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:23.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:40:0) after 1m0s
[13:17:23.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:40:0)
[13:17:23.662] Timer tick, asking Buildbucket for the build status
[13:17:23.783] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:23.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:41:0) after 1m0s
[13:18:23.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:41:0)
[13:18:23.821] Timer tick, asking Buildbucket for the build status
[13:18:24.395] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:24.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:42:0) after 1m0s
[13:19:24.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:42:0)
[13:19:24.443] Timer tick, asking Buildbucket for the build status
[13:19:24.714] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:24.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:43:0) after 1m0s
[13:20:24.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:43:0)
[13:20:24.797] Timer tick, asking Buildbucket for the build status
[13:20:25.068] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:25.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:44:0) after 1m0s
[13:21:25.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:44:0)
[13:21:25.125] Timer tick, asking Buildbucket for the build status
[13:21:25.501] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:25.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:45:0) after 1m0s
[13:22:25.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:45:0)
[13:22:25.557] Timer tick, asking Buildbucket for the build status
[13:22:26.063] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:26.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:46:0) after 1m0s
[13:23:26.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:46:0)
[13:23:26.141] Timer tick, asking Buildbucket for the build status
[13:23:27.468] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:27.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:47:0) after 1m0s
[13:24:27.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:47:0)
[13:24:27.497] Timer tick, asking Buildbucket for the build status
[13:24:27.835] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:27.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:48:0) after 1m0s
[13:25:27.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:48:0)
[13:25:27.856] Timer tick, asking Buildbucket for the build status
[13:25:28.261] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:28.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:49:0) after 1m0s
[13:26:28.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:49:0)
[13:26:28.343] Timer tick, asking Buildbucket for the build status
[13:26:28.441] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:28.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:50:0) after 1m0s
[13:27:28.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:50:0)
[13:27:28.434] Timer tick, asking Buildbucket for the build status
[13:27:28.657] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:28.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:51:0) after 1m0s
[13:28:28.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:51:0)
[13:28:28.673] Timer tick, asking Buildbucket for the build status
[13:28:30.166] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:30.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:52:0) after 1m0s
[13:29:30.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:52:0)
[13:29:30.187] Timer tick, asking Buildbucket for the build status
[13:29:30.372] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:30.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:53:0) after 1m0s
[13:30:30.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:53:0)
[13:30:30.395] Timer tick, asking Buildbucket for the build status
[13:30:30.505] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:30.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:54:0) after 1m0s
[13:31:30.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:54:0)
[13:31:30.692] Timer tick, asking Buildbucket for the build status
[13:31:30.895] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:30.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:55:0) after 1m0s
[13:32:30.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:55:0)
[13:32:30.916] Timer tick, asking Buildbucket for the build status
[13:32:31.109] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:31.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:56:0) after 1m0s
[13:33:31.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:56:0)
[13:33:31.152] Timer tick, asking Buildbucket for the build status
[13:33:32.207] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:32.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:57:0) after 1m0s
[13:34:32.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:57:0)
[13:34:32.217] Timer tick, asking Buildbucket for the build status
[13:34:32.412] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:32.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:58:0) after 1m0s
[13:35:32.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:58:0)
[13:35:32.480] Timer tick, asking Buildbucket for the build status
[13:35:32.600] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:32.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:59:0) after 1m0s
[13:36:32.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:59:0)
[13:36:32.611] Timer tick, asking Buildbucket for the build status
[13:36:33.187] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:33.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:60:0) after 1m0s
[13:37:33.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:60:0)
[13:37:33.255] Timer tick, asking Buildbucket for the build status
[13:37:33.419] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:33.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:61:0) after 1m0s
[13:38:33.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:61:0)
[13:38:33.509] Timer tick, asking Buildbucket for the build status
[13:38:37.321] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:37.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:62:0) after 1m0s
[13:39:37.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:62:0)
[13:39:37.505] Timer tick, asking Buildbucket for the build status
[13:39:37.621] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:37.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:63:0) after 1m0s
[13:40:37.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:63:0)
[13:40:37.661] Timer tick, asking Buildbucket for the build status
[13:40:37.791] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:37.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:64:0) after 1m0s
[13:41:37.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:64:0)
[13:41:37.815] Timer tick, asking Buildbucket for the build status
[13:41:37.957] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:37.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:65:0) after 1m0s
[13:42:37.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:65:0)
[13:42:37.978] Timer tick, asking Buildbucket for the build status
[13:42:38.123] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:38.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:66:0) after 1m0s
[13:43:38.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:66:0)
[13:43:38.240] Timer tick, asking Buildbucket for the build status
[13:43:40.358] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:40.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:67:0) after 1m0s
[13:44:40.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:67:0)
[13:44:40.370] Timer tick, asking Buildbucket for the build status
[13:44:40.907] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:40.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:68:0) after 1m0s
[13:45:40.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:68:0)
[13:45:40.929] Timer tick, asking Buildbucket for the build status
[13:45:41.078] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:41.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:69:0) after 1m0s
[13:46:41.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:69:0)
[13:46:41.119] Timer tick, asking Buildbucket for the build status
[13:46:41.272] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:41.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:70:0) after 1m0s
[13:47:41.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:70:0)
[13:47:41.272] Timer tick, asking Buildbucket for the build status
[13:47:41.530] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:41.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:71:0) after 1m0s
[13:48:41.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:71:0)
[13:48:41.645] Timer tick, asking Buildbucket for the build status
[13:48:42.677] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:42.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:72:0) after 1m0s
[13:49:42.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:72:0)
[13:49:42.726] Timer tick, asking Buildbucket for the build status
[13:49:42.827] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:42.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:73:0) after 1m0s
[13:50:42.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:73:0)
[13:50:42.903] Timer tick, asking Buildbucket for the build status
[13:50:43.058] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:43.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:74:0) after 1m0s
[13:51:43.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:74:0)
[13:51:43.185] Timer tick, asking Buildbucket for the build status
[13:51:43.336] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:43.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:75:0) after 1m0s
[13:52:43.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:75:0)
[13:52:43.435] Timer tick, asking Buildbucket for the build status
[13:52:43.666] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:43.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:76:0) after 1m0s
[13:53:43.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:76:0)
[13:53:43.739] Timer tick, asking Buildbucket for the build status
[13:53:44.407] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:44.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:77:0) after 1m0s
[13:54:44.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:77:0)
[13:54:44.435] Timer tick, asking Buildbucket for the build status
[13:54:44.649] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:44.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:78:0) after 1m0s
[13:55:44.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:78:0)
[13:55:44.714] Timer tick, asking Buildbucket for the build status
[13:55:44.892] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:44.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:79:0) after 1m0s
[13:56:44.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:79:0)
[13:56:44.935] Timer tick, asking Buildbucket for the build status
[13:56:45.093] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:45.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:80:0) after 1m0s
[13:57:45.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:80:0)
[13:57:45.156] Timer tick, asking Buildbucket for the build status
[13:57:45.317] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:45.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:81:0) after 1m0s
[13:58:45.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:81:0)
[13:58:45.335] Timer tick, asking Buildbucket for the build status
[13:58:45.825] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:45.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:82:0) after 1m0s
[13:59:45.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:82:0)
[13:59:45.859] Timer tick, asking Buildbucket for the build status
[13:59:46.067] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:46.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:83:0) after 1m0s
[14:00:46.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:83:0)
[14:00:46.092] Timer tick, asking Buildbucket for the build status
[14:00:46.273] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:46.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:84:0) after 1m0s
[14:01:46.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:84:0)
[14:01:46.306] Timer tick, asking Buildbucket for the build status
[14:01:46.587] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:46.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:85:0) after 1m0s
[14:02:46.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:85:0)
[14:02:46.615] Timer tick, asking Buildbucket for the build status
[14:02:46.889] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:46.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:86:0) after 1m0s
[14:03:46.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:86:0)
[14:03:46.899] Timer tick, asking Buildbucket for the build status
[14:03:47.097] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:47.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:87:0) after 1m0s
[14:04:47.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:87:0)
[14:04:47.130] Timer tick, asking Buildbucket for the build status
[14:04:47.785] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:47.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:88:0) after 1m0s
[14:05:47.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:88:0)
[14:05:47.875] Timer tick, asking Buildbucket for the build status
[14:05:48.177] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:48.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:89:0) after 1m0s
[14:06:48.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:89:0)
[14:06:48.301] Timer tick, asking Buildbucket for the build status
[14:06:48.520] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:48.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:90:0) after 1m0s
[14:07:48.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:90:0)
[14:07:48.646] Timer tick, asking Buildbucket for the build status
[14:07:48.769] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:48.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:91:0) after 1m0s
[14:08:48.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:91:0)
[14:08:48.885] Timer tick, asking Buildbucket for the build status
[14:08:49.022] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:49.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:92:0) after 1m0s
[14:09:49.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:92:0)
[14:09:49.048] Timer tick, asking Buildbucket for the build status
[14:09:49.504] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:49.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:93:0) after 1m0s
[14:10:49.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:93:0)
[14:10:49.498] Timer tick, asking Buildbucket for the build status
[14:10:49.795] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:49.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:94:0) after 1m0s
[14:11:49.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:94:0)
[14:11:49.836] Timer tick, asking Buildbucket for the build status
[14:11:50.007] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:50.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:95:0) after 1m0s
[14:12:50.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:95:0)
[14:12:50.059] Timer tick, asking Buildbucket for the build status
[14:12:50.496] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:50.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:96:0) after 1m0s
[14:13:50.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:96:0)
[14:13:50.489] Timer tick, asking Buildbucket for the build status
[14:13:50.831] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:50.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:97:0) after 1m0s
[14:14:50.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:97:0)
[14:14:50.892] Timer tick, asking Buildbucket for the build status
[14:14:51.190] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:51.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:98:0) after 1m0s
[14:15:51.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:98:0)
[14:15:51.265] Timer tick, asking Buildbucket for the build status
[14:15:51.499] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:51.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:99:0) after 1m0s
[14:16:51.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:99:0)
[14:16:51.523] Timer tick, asking Buildbucket for the build status
[14:16:51.685] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:51.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:100:0) after 1m0s
[14:17:51.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:100:0)
[14:17:51.719] Timer tick, asking Buildbucket for the build status
[14:17:52.054] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:52.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:101:0) after 1m0s
[14:18:52.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:101:0)
[14:18:52.082] Timer tick, asking Buildbucket for the build status
[14:18:52.265] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:52.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:102:0) after 1m0s
[14:19:52.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:102:0)
[14:19:52.294] Timer tick, asking Buildbucket for the build status
[14:19:52.432] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:52.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:103:0) after 1m0s
[14:20:52.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:103:0)
[14:20:52.459] Timer tick, asking Buildbucket for the build status
[14:20:52.631] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:52.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:104:0) after 1m0s
[14:21:52.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:104:0)
[14:21:52.656] Timer tick, asking Buildbucket for the build status
[14:21:52.929] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:52.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:105:0) after 1m0s
[14:22:53.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:105:0)
[14:22:53.020] Timer tick, asking Buildbucket for the build status
[14:22:53.237] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:53.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:106:0) after 1m0s
[14:23:53.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:106:0)
[14:23:53.260] Timer tick, asking Buildbucket for the build status
[14:23:53.402] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:53.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:107:0) after 1m0s
[14:24:53.420] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:107:0)
[14:24:53.420] Timer tick, asking Buildbucket for the build status
[14:24:53.632] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:53.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:108:0) after 1m0s
[14:25:53.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:108:0)
[14:25:53.628] Timer tick, asking Buildbucket for the build status
[14:25:53.888] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:53.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:109:0) after 1m0s
[14:26:53.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:109:0)
[14:26:53.915] Timer tick, asking Buildbucket for the build status
[14:26:54.076] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:54.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:110:0) after 1m0s
[14:27:54.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:110:0)
[14:27:54.108] Timer tick, asking Buildbucket for the build status
[14:27:54.628] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:54.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:111:0) after 1m0s
[14:28:54.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:111:0)
[14:28:54.649] Timer tick, asking Buildbucket for the build status
[14:28:55.023] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:55.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:112:0) after 1m0s
[14:29:55.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:112:0)
[14:29:55.053] Timer tick, asking Buildbucket for the build status
[14:29:55.352] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:55.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:113:0) after 1m0s
[14:30:55.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:113:0)
[14:30:55.392] Timer tick, asking Buildbucket for the build status
[14:30:55.513] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:55.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:114:0) after 1m0s
[14:31:55.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:114:0)
[14:31:55.570] Timer tick, asking Buildbucket for the build status
[14:31:55.813] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:55.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:115:0) after 1m0s
[14:32:55.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:115:0)
[14:32:55.875] Timer tick, asking Buildbucket for the build status
[14:32:56.021] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:56.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:116:0) after 1m0s
[14:33:56.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:116:0)
[14:33:56.046] Timer tick, asking Buildbucket for the build status
[14:33:56.219] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:56.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:117:0) after 1m0s
[14:34:56.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:117:0)
[14:34:56.300] Timer tick, asking Buildbucket for the build status
[14:34:56.590] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:56.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:118:0) after 1m0s
[14:35:56.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:118:0)
[14:35:56.660] Timer tick, asking Buildbucket for the build status
[14:35:56.785] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:56.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:119:0) after 1m0s
[14:36:56.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:119:0)
[14:36:56.955] Timer tick, asking Buildbucket for the build status
[14:36:57.341] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:57.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:120:0) after 1m0s
[14:37:57.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:120:0)
[14:37:57.407] Timer tick, asking Buildbucket for the build status
[14:37:57.710] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:57.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:121:0) after 1m0s
[14:38:57.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:121:0)
[14:38:57.731] Timer tick, asking Buildbucket for the build status
[14:38:58.225] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:58.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:122:0) after 1m0s
[14:39:58.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:122:0)
[14:39:58.419] Timer tick, asking Buildbucket for the build status
[14:39:58.542] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:58.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:123:0) after 1m0s
[14:40:58.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:123:0)
[14:40:58.564] Timer tick, asking Buildbucket for the build status
[14:40:59.054] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:59.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:124:0) after 1m0s
[14:41:59.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:124:0)
[14:41:59.122] Timer tick, asking Buildbucket for the build status
[14:41:59.486] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:59.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:125:0) after 1m0s
[14:42:59.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:125:0)
[14:42:59.543] Timer tick, asking Buildbucket for the build status
[14:42:59.775] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:59.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:126:0) after 1m0s
[14:43:59.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:126:0)
[14:43:59.845] Timer tick, asking Buildbucket for the build status
[14:44:00.125] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:00.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:127:0) after 1m0s
[14:45:00.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:127:0)
[14:45:00.136] Timer tick, asking Buildbucket for the build status
[14:45:00.277] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:00.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:128:0) after 1m0s
[14:46:00.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:128:0)
[14:46:00.398] Timer tick, asking Buildbucket for the build status
[14:46:00.807] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:00.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:129:0) after 1m0s
[14:47:01.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:129:0)
[14:47:01.114] Timer tick, asking Buildbucket for the build status
[14:47:01.378] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:01.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:130:0) after 1m0s
[14:48:01.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:130:0)
[14:48:01.408] Timer tick, asking Buildbucket for the build status
[14:48:01.570] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:01.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:131:0) after 1m0s
[14:49:01.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:131:0)
[14:49:01.607] Timer tick, asking Buildbucket for the build status
[14:49:01.974] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:01.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:132:0) after 1m0s
[14:50:01.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:132:0)
[14:50:01.997] Timer tick, asking Buildbucket for the build status
[14:50:02.380] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:02.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:133:0) after 1m0s
[14:51:02.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:133:0)
[14:51:02.550] Timer tick, asking Buildbucket for the build status
[14:51:02.941] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:02.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:134:0) after 1m0s
[14:52:03.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:134:0)
[14:52:03.029] Timer tick, asking Buildbucket for the build status
[14:52:03.492] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:03.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:135:0) after 1m0s
[14:53:03.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:135:0)
[14:53:03.603] Timer tick, asking Buildbucket for the build status
[14:53:03.763] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:03.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:136:0) after 1m0s
[14:54:03.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:136:0)
[14:54:03.814] Timer tick, asking Buildbucket for the build status
[14:54:04.073] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:04.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:137:0) after 1m0s
[14:55:04.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:137:0)
[14:55:04.193] Timer tick, asking Buildbucket for the build status
[14:55:04.390] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:04.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:138:0) after 1m0s
[14:56:04.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:138:0)
[14:56:04.466] Timer tick, asking Buildbucket for the build status
[14:56:04.744] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:04.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:139:0) after 1m0s
[14:57:04.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:139:0)
[14:57:04.809] Timer tick, asking Buildbucket for the build status
[14:57:05.536] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:05.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:140:0) after 1m0s
[14:58:05.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:140:0)
[14:58:05.566] Timer tick, asking Buildbucket for the build status
[14:58:05.918] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:05.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:141:0) after 1m0s
[14:59:05.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:141:0)
[14:59:05.965] Timer tick, asking Buildbucket for the build status
[14:59:06.187] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:06.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:142:0) after 1m0s
[15:00:06.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:142:0)
[15:00:06.284] Timer tick, asking Buildbucket for the build status
[15:00:06.511] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:06.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:143:0) after 1m0s
[15:01:06.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:143:0)
[15:01:06.560] Timer tick, asking Buildbucket for the build status
[15:01:06.737] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:06.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:144:0) after 1m0s
[15:02:06.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:144:0)
[15:02:06.856] Timer tick, asking Buildbucket for the build status
[15:02:07.628] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:07.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:145:0) after 1m0s
[15:03:07.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:145:0)
[15:03:07.655] Timer tick, asking Buildbucket for the build status
[15:03:07.962] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:07.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:146:0) after 1m0s
[15:04:07.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:146:0)
[15:04:07.994] Timer tick, asking Buildbucket for the build status
[15:04:08.166] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:08.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:147:0) after 1m0s
[15:05:08.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:147:0)
[15:05:08.222] Timer tick, asking Buildbucket for the build status
[15:05:08.498] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:08.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:148:0) after 1m0s
[15:06:08.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:148:0)
[15:06:08.627] Timer tick, asking Buildbucket for the build status
[15:06:09.145] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:09.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:149:0) after 1m0s
[15:07:09.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:149:0)
[15:07:09.230] Timer tick, asking Buildbucket for the build status
[15:07:09.505] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:09.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:150:0) after 1m0s
[15:08:09.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:150:0)
[15:08:09.614] Timer tick, asking Buildbucket for the build status
[15:08:10.604] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:10.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:151:0) after 1m0s
[15:09:10.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:151:0)
[15:09:10.695] Timer tick, asking Buildbucket for the build status
[15:09:10.826] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:10.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:152:0) after 1m0s
[15:10:10.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:152:0)
[15:10:10.863] Timer tick, asking Buildbucket for the build status
[15:10:11.050] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:11.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:153:0) after 1m0s
[15:11:11.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:153:0)
[15:11:11.097] Timer tick, asking Buildbucket for the build status
[15:11:11.222] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:11.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:154:0) after 1m0s
[15:12:11.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:154:0)
[15:12:11.266] Timer tick, asking Buildbucket for the build status
[15:12:11.744] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:11.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:155:0) after 1m0s
[15:13:11.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:155:0)
[15:13:11.838] Timer tick, asking Buildbucket for the build status
[15:13:14.552] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:14.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:156:0) after 1m0s
[15:14:14.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:156:0)
[15:14:14.644] Timer tick, asking Buildbucket for the build status
[15:14:14.989] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:14.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:157:0) after 1m0s
[15:15:15.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:157:0)
[15:15:15.029] Timer tick, asking Buildbucket for the build status
[15:15:15.304] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:15.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:158:0) after 1m0s
[15:16:15.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:158:0)
[15:16:15.339] Timer tick, asking Buildbucket for the build status
[15:16:15.552] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:15.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:159:0) after 1m0s
[15:17:15.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:159:0)
[15:17:15.713] Timer tick, asking Buildbucket for the build status
[15:17:16.947] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:16.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:160:0) after 1m0s
[15:18:17.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:160:0)
[15:18:17.031] Timer tick, asking Buildbucket for the build status
[15:18:17.148] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:17.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:161:0) after 1m0s
[15:19:17.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:161:0)
[15:19:17.351] Timer tick, asking Buildbucket for the build status
[15:19:18.011] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:18.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:162:0) after 1m0s
[15:20:18.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:162:0)
[15:20:18.034] Timer tick, asking Buildbucket for the build status
[15:20:18.204] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:18.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:163:0) after 1m0s
[15:21:18.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:163:0)
[15:21:18.243] Timer tick, asking Buildbucket for the build status
[15:21:18.707] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:18.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:164:0) after 1m0s
[15:22:18.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:164:0)
[15:22:18.841] Timer tick, asking Buildbucket for the build status
[15:22:19.017] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:19.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:165:0) after 1m0s
[15:23:19.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:165:0)
[15:23:19.128] Timer tick, asking Buildbucket for the build status
[15:23:19.780] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:19.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:166:0) after 1m0s
[15:24:19.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:166:0)
[15:24:19.817] Timer tick, asking Buildbucket for the build status
[15:24:20.016] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:20.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:167:0) after 1m0s
[15:25:20.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:167:0)
[15:25:20.093] Timer tick, asking Buildbucket for the build status
[15:25:20.549] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:20.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:168:0) after 1m0s
[15:26:20.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:168:0)
[15:26:20.602] Timer tick, asking Buildbucket for the build status
[15:26:20.753] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:20.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:169:0) after 1m0s
[15:27:20.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:169:0)
[15:27:20.788] Timer tick, asking Buildbucket for the build status
[15:27:21.601] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:21.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:170:0) after 1m0s
[15:28:21.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:170:0)
[15:28:21.675] Timer tick, asking Buildbucket for the build status
[15:28:22.228] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:22.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:171:0) after 1m0s
[15:29:22.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:171:0)
[15:29:22.284] Timer tick, asking Buildbucket for the build status
[15:29:23.077] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:23.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:172:0) after 1m0s
[15:30:23.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:172:0)
[15:30:23.146] Timer tick, asking Buildbucket for the build status
[15:30:23.351] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:23.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:173:0) after 1m0s
[15:31:23.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:173:0)
[15:31:23.487] Timer tick, asking Buildbucket for the build status
[15:31:23.658] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:23.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:174:0) after 1m0s
[15:32:23.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:174:0)
[15:32:23.797] Timer tick, asking Buildbucket for the build status
[15:32:24.131] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:24.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:175:0) after 1m0s
[15:33:24.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:175:0)
[15:33:24.196] Timer tick, asking Buildbucket for the build status
[15:33:24.566] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:24.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:176:0) after 1m0s
[15:34:24.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:176:0)
[15:34:24.662] Timer tick, asking Buildbucket for the build status
[15:34:24.792] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:24.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:177:0) after 1m0s
[15:35:24.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:177:0)
[15:35:24.860] Timer tick, asking Buildbucket for the build status
[15:35:25.213] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:25.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:178:0) after 1m0s
[15:36:25.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:178:0)
[15:36:25.330] Timer tick, asking Buildbucket for the build status
[15:36:25.775] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:25.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:179:0) after 1m0s
[15:37:25.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:179:0)
[15:37:25.805] Timer tick, asking Buildbucket for the build status
[15:37:25.952] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:25.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:180:0) after 1m0s
[15:38:26.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:180:0)
[15:38:26.045] Timer tick, asking Buildbucket for the build status
[15:38:26.799] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:26.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:181:0) after 1m0s
[15:39:26.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:181:0)
[15:39:26.868] Timer tick, asking Buildbucket for the build status
[15:39:27.022] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:27.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:182:0) after 1m0s
[15:40:27.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:182:0)
[15:40:27.058] Timer tick, asking Buildbucket for the build status
[15:40:27.211] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:27.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:183:0) after 1m0s
[15:41:27.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:183:0)
[15:41:27.311] Timer tick, asking Buildbucket for the build status
[15:41:27.517] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:27.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:184:0) after 1m0s
[15:42:27.605] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:184:0)
[15:42:27.605] Timer tick, asking Buildbucket for the build status
[15:42:28.257] Build 8884219832150676528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:28.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9049649894607913296:185:0) after 1m0s
[15:42:49.920] Received PubSub notification, asking Buildbucket for the build status
[15:42:50.007] Build 8884219832150676528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:42:50.007] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1585755769155438",
    "created_by": "project:infra",
    "created_ts": "1585744746978621",
    "id": "8884219832150676528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"83.0.4100.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f2ce7770-efef-43a4-8629-f5af63e23067\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"83.0.4100.2\"}}",
    "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\": \"83.0.4100.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f2ce7770-efef-43a4-8629-f5af63e23067\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-cvra\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"83.0.4100.2\"}, \"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-cvra\"], \"image\": [\"chrome-xenial-20032900-6802ab9ce34\"], \"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\": [\"4916-b2318d4\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1585745309676168",
    "status": "COMPLETED",
    "status_changed_ts": "1585755769629746",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9049649894607913296",
      "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/8884219832150676528/+/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:4b4e92292032eb10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1585755769657023",
    "url": "https://ci.chromium.org/b/8884219832150676528",
    "utcnow_ts": "1585755769994146"
  }
}
[15:42:50.007] Invocation finished in 3h3m44.755938295s with status SUCCEEDED