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

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

{
  "version": "86.0.4221.2"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[12:16:40.845] New invocation is queued and will start shortly
[12:16:40.845] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:16:41.926] Starting the invocation (attempt 1)
[12:16:41.994] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:16:41.994] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:16:41.994] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9038326683538159888",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4221.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"f0c53444-7877-46ea-83c4-82d52255bafe\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4221.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:9038326683538159888",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:16:42.667] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1596543402177690",
    "id": "8872896621476908688",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4221.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f0c53444-7877-46ea-83c4-82d52255bafe\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4221.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1596543402513995",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9038326683538159888",
      "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/8872896621476908688/+/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": "1596543402514295",
    "url": "https://ci.chromium.org/b/8872896621476908688",
    "utcnow_ts": "1596543402656198"
  }
}
[12:16:42.667] Task URL: https://ci.chromium.org/b/8872896621476908688
[12:16:42.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:2:0) after 1m0s
[12:17:42.956] Received PubSub notification, asking Buildbucket for the build status
[12:17:43.042] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:43.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:2:0)
[12:17:43.996] Timer tick, asking Buildbucket for the build status
[12:17:44.095] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:44.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:4:0) after 1m0s
[12:18:44.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:4:0)
[12:18:44.428] Timer tick, asking Buildbucket for the build status
[12:18:44.605] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:44.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:5:0) after 1m0s
[12:19:44.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:5:0)
[12:19:44.963] Timer tick, asking Buildbucket for the build status
[12:19:45.166] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:45.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:6:0) after 1m0s
[12:20:45.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:6:0)
[12:20:45.310] Timer tick, asking Buildbucket for the build status
[12:20:45.418] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:45.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:7:0) after 1m0s
[12:21:46.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:7:0)
[12:21:46.107] Timer tick, asking Buildbucket for the build status
[12:21:46.288] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:46.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:8:0) after 1m0s
[12:22:46.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:8:0)
[12:22:46.402] Timer tick, asking Buildbucket for the build status
[12:22:46.477] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:46.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:9:0) after 1m0s
[12:23:46.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:9:0)
[12:23:46.532] Timer tick, asking Buildbucket for the build status
[12:23:46.684] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:46.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:10:0) after 1m0s
[12:24:46.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:10:0)
[12:24:46.979] Timer tick, asking Buildbucket for the build status
[12:24:47.078] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:47.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:11:0) after 1m0s
[12:25:47.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:11:0)
[12:25:47.118] Timer tick, asking Buildbucket for the build status
[12:25:47.248] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:47.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:12:0) after 1m0s
[12:26:47.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:12:0)
[12:26:47.362] Timer tick, asking Buildbucket for the build status
[12:26:47.518] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:47.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:13:0) after 1m0s
[12:27:47.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:13:0)
[12:27:47.537] Timer tick, asking Buildbucket for the build status
[12:27:47.651] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:47.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:14:0) after 1m0s
[12:28:47.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:14:0)
[12:28:47.691] Timer tick, asking Buildbucket for the build status
[12:28:47.896] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:47.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:15:0) after 1m0s
[12:29:47.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:15:0)
[12:29:47.927] Timer tick, asking Buildbucket for the build status
[12:29:48.209] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:48.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:16:0) after 1m0s
[12:30:48.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:16:0)
[12:30:48.350] Timer tick, asking Buildbucket for the build status
[12:30:48.713] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:48.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:17:0) after 1m0s
[12:31:48.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:17:0)
[12:31:48.802] Timer tick, asking Buildbucket for the build status
[12:31:49.104] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:49.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:18:0) after 1m0s
[12:32:49.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:18:0)
[12:32:49.296] Timer tick, asking Buildbucket for the build status
[12:32:49.670] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:49.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:19:0) after 1m0s
[12:33:49.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:19:0)
[12:33:49.876] Timer tick, asking Buildbucket for the build status
[12:33:50.124] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:50.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:20:0) after 1m0s
[12:34:50.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:20:0)
[12:34:50.268] Timer tick, asking Buildbucket for the build status
[12:34:51.308] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:51.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:21:0) after 1m0s
[12:35:51.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:21:0)
[12:35:51.329] Timer tick, asking Buildbucket for the build status
[12:35:51.421] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:51.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:22:0) after 1m0s
[12:36:51.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:22:0)
[12:36:51.438] Timer tick, asking Buildbucket for the build status
[12:36:51.613] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:51.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:23:0) after 1m0s
[12:37:51.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:23:0)
[12:37:51.703] Timer tick, asking Buildbucket for the build status
[12:37:51.776] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:51.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:24:0) after 1m0s
[12:38:51.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:24:0)
[12:38:51.819] Timer tick, asking Buildbucket for the build status
[12:38:51.903] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:51.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:25:0) after 1m0s
[12:39:52.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:25:0)
[12:39:52.100] Timer tick, asking Buildbucket for the build status
[12:39:52.264] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:52.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:26:0) after 1m0s
[12:40:52.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:26:0)
[12:40:52.294] Timer tick, asking Buildbucket for the build status
[12:40:52.428] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:52.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:27:0) after 1m0s
[12:41:52.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:27:0)
[12:41:52.546] Timer tick, asking Buildbucket for the build status
[12:41:52.750] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:52.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:28:0) after 1m0s
[12:42:52.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:28:0)
[12:42:52.813] Timer tick, asking Buildbucket for the build status
[12:42:52.961] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:52.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:29:0) after 1m0s
[12:43:53.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:29:0)
[12:43:53.049] Timer tick, asking Buildbucket for the build status
[12:43:53.227] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:53.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:30:0) after 1m0s
[12:44:53.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:30:0)
[12:44:53.282] Timer tick, asking Buildbucket for the build status
[12:44:53.520] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:53.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:31:0) after 1m0s
[12:45:53.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:31:0)
[12:45:53.535] Timer tick, asking Buildbucket for the build status
[12:45:53.661] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:53.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:32:0) after 1m0s
[12:46:53.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:32:0)
[12:46:53.736] Timer tick, asking Buildbucket for the build status
[12:46:53.874] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:53.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:33:0) after 1m0s
[12:47:54.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:33:0)
[12:47:54.101] Timer tick, asking Buildbucket for the build status
[12:47:54.184] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:54.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:34:0) after 1m0s
[12:48:54.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:34:0)
[12:48:54.641] Timer tick, asking Buildbucket for the build status
[12:48:55.017] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:55.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:35:0) after 1m0s
[12:49:55.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:35:0)
[12:49:55.131] Timer tick, asking Buildbucket for the build status
[12:49:55.280] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:55.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:36:0) after 1m0s
[12:50:55.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:36:0)
[12:50:55.429] Timer tick, asking Buildbucket for the build status
[12:50:55.504] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:55.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:37:0) after 1m0s
[12:51:55.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:37:0)
[12:51:55.598] Timer tick, asking Buildbucket for the build status
[12:51:56.222] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:56.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:38:0) after 1m0s
[12:52:56.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:38:0)
[12:52:56.679] Timer tick, asking Buildbucket for the build status
[12:52:57.041] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:57.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:39:0) after 1m0s
[12:53:57.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:39:0)
[12:53:57.218] Timer tick, asking Buildbucket for the build status
[12:53:57.383] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:57.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:40:0) after 1m0s
[12:54:57.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:40:0)
[12:54:57.545] Timer tick, asking Buildbucket for the build status
[12:54:57.628] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:57.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:41:0) after 1m0s
[12:55:57.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:41:0)
[12:55:57.657] Timer tick, asking Buildbucket for the build status
[12:55:57.761] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:57.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:42:0) after 1m0s
[12:56:57.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:42:0)
[12:56:57.844] Timer tick, asking Buildbucket for the build status
[12:56:58.353] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:58.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:43:0) after 1m0s
[12:57:58.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:43:0)
[12:57:58.426] Timer tick, asking Buildbucket for the build status
[12:57:58.594] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:58.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:44:0) after 1m0s
[12:58:58.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:44:0)
[12:58:58.666] Timer tick, asking Buildbucket for the build status
[12:58:58.801] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:58.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:45:0) after 1m0s
[12:59:58.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:45:0)
[12:59:58.925] Timer tick, asking Buildbucket for the build status
[13:00:00.166] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:00.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:46:0) after 1m0s
[13:01:00.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:46:0)
[13:01:00.275] Timer tick, asking Buildbucket for the build status
[13:01:00.401] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:00.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:47:0) after 1m0s
[13:02:00.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:47:0)
[13:02:00.598] Timer tick, asking Buildbucket for the build status
[13:02:00.847] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:00.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:48:0) after 1m0s
[13:03:00.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:48:0)
[13:03:00.920] Timer tick, asking Buildbucket for the build status
[13:03:01.000] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:01.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:49:0) after 1m0s
[13:04:01.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:49:0)
[13:04:01.053] Timer tick, asking Buildbucket for the build status
[13:04:01.172] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:01.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:50:0) after 1m0s
[13:05:01.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:50:0)
[13:05:01.429] Timer tick, asking Buildbucket for the build status
[13:05:01.677] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:01.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:51:0) after 1m0s
[13:06:02.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:51:0)
[13:06:02.217] Timer tick, asking Buildbucket for the build status
[13:06:02.323] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:02.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:52:0) after 1m0s
[13:07:02.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:52:0)
[13:07:02.401] Timer tick, asking Buildbucket for the build status
[13:07:02.577] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:02.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:53:0) after 1m0s
[13:08:02.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:53:0)
[13:08:02.629] Timer tick, asking Buildbucket for the build status
[13:08:02.844] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:02.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:54:0) after 1m0s
[13:09:02.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:54:0)
[13:09:02.872] Timer tick, asking Buildbucket for the build status
[13:09:02.982] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:02.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:55:0) after 1m0s
[13:10:03.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:55:0)
[13:10:03.053] Timer tick, asking Buildbucket for the build status
[13:10:03.516] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:03.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:56:0) after 1m0s
[13:11:03.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:56:0)
[13:11:03.525] Timer tick, asking Buildbucket for the build status
[13:11:03.887] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:03.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:57:0) after 1m0s
[13:12:03.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:57:0)
[13:12:03.942] Timer tick, asking Buildbucket for the build status
[13:12:04.132] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:04.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:58:0) after 1m0s
[13:13:04.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:58:0)
[13:13:04.206] Timer tick, asking Buildbucket for the build status
[13:13:04.285] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:04.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:59:0) after 1m0s
[13:14:04.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:59:0)
[13:14:04.300] Timer tick, asking Buildbucket for the build status
[13:14:04.382] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:04.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:60:0) after 1m0s
[13:15:04.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:60:0)
[13:15:04.406] Timer tick, asking Buildbucket for the build status
[13:15:04.496] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:04.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:61:0) after 1m0s
[13:16:04.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:61:0)
[13:16:04.523] Timer tick, asking Buildbucket for the build status
[13:16:04.617] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:04.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:62:0) after 1m0s
[13:17:04.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:62:0)
[13:17:04.616] Timer tick, asking Buildbucket for the build status
[13:17:04.751] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:04.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:63:0) after 1m0s
[13:18:04.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:63:0)
[13:18:04.833] Timer tick, asking Buildbucket for the build status
[13:18:04.917] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:04.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:64:0) after 1m0s
[13:19:05.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:64:0)
[13:19:05.105] Timer tick, asking Buildbucket for the build status
[13:19:05.174] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:05.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:65:0) after 1m0s
[13:20:05.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:65:0)
[13:20:05.168] Timer tick, asking Buildbucket for the build status
[13:20:05.306] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:05.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:66:0) after 1m0s
[13:21:05.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:66:0)
[13:21:05.458] Timer tick, asking Buildbucket for the build status
[13:21:05.542] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:05.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:67:0) after 1m0s
[13:22:05.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:67:0)
[13:22:05.812] Timer tick, asking Buildbucket for the build status
[13:22:06.240] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:06.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:68:0) after 1m0s
[13:23:06.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:68:0)
[13:23:06.296] Timer tick, asking Buildbucket for the build status
[13:23:07.262] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:07.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:69:0) after 1m0s
[13:24:07.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:69:0)
[13:24:07.531] Timer tick, asking Buildbucket for the build status
[13:24:07.629] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:07.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:70:0) after 1m0s
[13:25:07.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:70:0)
[13:25:07.709] Timer tick, asking Buildbucket for the build status
[13:25:07.772] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:07.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:71:0) after 1m0s
[13:26:07.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:71:0)
[13:26:07.817] Timer tick, asking Buildbucket for the build status
[13:26:07.948] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:07.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:72:0) after 1m0s
[13:27:08.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:72:0)
[13:27:08.001] Timer tick, asking Buildbucket for the build status
[13:27:08.405] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:08.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:73:0) after 1m0s
[13:28:08.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:73:0)
[13:28:08.530] Timer tick, asking Buildbucket for the build status
[13:28:08.620] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:08.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:74:0) after 1m0s
[13:29:08.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:74:0)
[13:29:08.771] Timer tick, asking Buildbucket for the build status
[13:29:08.988] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:08.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:75:0) after 1m0s
[13:30:09.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:75:0)
[13:30:09.075] Timer tick, asking Buildbucket for the build status
[13:30:09.220] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:09.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:76:0) after 1m0s
[13:31:09.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:76:0)
[13:31:09.254] Timer tick, asking Buildbucket for the build status
[13:31:09.498] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:09.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:77:0) after 1m0s
[13:32:09.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:77:0)
[13:32:09.542] Timer tick, asking Buildbucket for the build status
[13:32:09.921] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:09.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:78:0) after 1m0s
[13:33:09.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:78:0)
[13:33:09.961] Timer tick, asking Buildbucket for the build status
[13:33:10.032] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:10.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:79:0) after 1m0s
[13:34:10.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:79:0)
[13:34:10.057] Timer tick, asking Buildbucket for the build status
[13:34:10.273] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:10.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:80:0) after 1m0s
[13:35:10.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:80:0)
[13:35:10.301] Timer tick, asking Buildbucket for the build status
[13:35:10.376] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:10.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:81:0) after 1m0s
[13:36:10.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:81:0)
[13:36:10.411] Timer tick, asking Buildbucket for the build status
[13:36:10.498] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:10.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:82:0) after 1m0s
[13:37:10.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:82:0)
[13:37:10.625] Timer tick, asking Buildbucket for the build status
[13:37:10.757] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:10.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:83:0) after 1m0s
[13:38:10.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:83:0)
[13:38:10.825] Timer tick, asking Buildbucket for the build status
[13:38:10.934] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:10.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:84:0) after 1m0s
[13:39:11.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:84:0)
[13:39:11.014] Timer tick, asking Buildbucket for the build status
[13:39:11.101] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:11.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:85:0) after 1m0s
[13:40:11.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:85:0)
[13:40:11.163] Timer tick, asking Buildbucket for the build status
[13:40:11.240] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:11.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:86:0) after 1m0s
[13:41:11.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:86:0)
[13:41:11.317] Timer tick, asking Buildbucket for the build status
[13:41:11.424] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:11.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:87:0) after 1m0s
[13:42:11.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:87:0)
[13:42:11.502] Timer tick, asking Buildbucket for the build status
[13:42:11.873] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:11.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:88:0) after 1m0s
[13:43:11.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:88:0)
[13:43:11.986] Timer tick, asking Buildbucket for the build status
[13:43:12.083] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:12.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:89:0) after 1m0s
[13:44:12.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:89:0)
[13:44:12.165] Timer tick, asking Buildbucket for the build status
[13:44:12.288] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:12.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:90:0) after 1m0s
[13:45:12.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:90:0)
[13:45:12.353] Timer tick, asking Buildbucket for the build status
[13:45:13.119] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:13.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:91:0) after 1m0s
[13:46:13.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:91:0)
[13:46:13.182] Timer tick, asking Buildbucket for the build status
[13:46:13.323] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:13.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:92:0) after 1m0s
[13:47:13.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:92:0)
[13:47:13.540] Timer tick, asking Buildbucket for the build status
[13:47:13.762] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:13.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:93:0) after 1m0s
[13:48:13.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:93:0)
[13:48:13.847] Timer tick, asking Buildbucket for the build status
[13:48:14.146] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:14.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:94:0) after 1m0s
[13:49:14.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:94:0)
[13:49:14.269] Timer tick, asking Buildbucket for the build status
[13:49:14.361] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:14.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:95:0) after 1m0s
[13:50:14.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:95:0)
[13:50:14.687] Timer tick, asking Buildbucket for the build status
[13:50:14.805] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:14.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:96:0) after 1m0s
[13:51:14.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:96:0)
[13:51:14.949] Timer tick, asking Buildbucket for the build status
[13:51:15.029] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:15.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:97:0) after 1m0s
[13:52:15.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:97:0)
[13:52:15.147] Timer tick, asking Buildbucket for the build status
[13:52:15.329] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:15.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:98:0) after 1m0s
[13:53:15.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:98:0)
[13:53:15.354] Timer tick, asking Buildbucket for the build status
[13:53:17.785] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:17.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:99:0) after 1m0s
[13:54:17.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:99:0)
[13:54:17.964] Timer tick, asking Buildbucket for the build status
[13:54:18.042] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:18.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:100:0) after 1m0s
[13:55:18.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:100:0)
[13:55:18.532] Timer tick, asking Buildbucket for the build status
[13:55:18.596] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:18.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:101:0) after 1m0s
[13:56:18.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:101:0)
[13:56:18.728] Timer tick, asking Buildbucket for the build status
[13:56:18.856] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:18.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:102:0) after 1m0s
[13:57:18.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:102:0)
[13:57:18.977] Timer tick, asking Buildbucket for the build status
[13:57:19.111] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:19.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:103:0) after 1m0s
[13:58:19.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:103:0)
[13:58:19.307] Timer tick, asking Buildbucket for the build status
[13:58:19.383] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:19.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:104:0) after 1m0s
[13:59:19.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:104:0)
[13:59:19.422] Timer tick, asking Buildbucket for the build status
[13:59:19.523] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:19.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:105:0) after 1m0s
[14:00:19.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:105:0)
[14:00:19.575] Timer tick, asking Buildbucket for the build status
[14:00:19.800] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:19.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:106:0) after 1m0s
[14:01:19.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:106:0)
[14:01:19.987] Timer tick, asking Buildbucket for the build status
[14:01:20.087] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:20.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:107:0) after 1m0s
[14:02:20.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:107:0)
[14:02:20.216] Timer tick, asking Buildbucket for the build status
[14:02:20.374] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:20.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:108:0) after 1m0s
[14:03:20.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:108:0)
[14:03:20.551] Timer tick, asking Buildbucket for the build status
[14:03:20.630] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:20.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:109:0) after 1m0s
[14:04:20.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:109:0)
[14:04:20.692] Timer tick, asking Buildbucket for the build status
[14:04:20.799] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:20.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:110:0) after 1m0s
[14:05:21.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:110:0)
[14:05:21.003] Timer tick, asking Buildbucket for the build status
[14:05:21.584] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:21.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:111:0) after 1m0s
[14:06:21.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:111:0)
[14:06:21.672] Timer tick, asking Buildbucket for the build status
[14:06:21.738] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:21.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:112:0) after 1m0s
[14:07:21.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:112:0)
[14:07:21.742] Timer tick, asking Buildbucket for the build status
[14:07:22.064] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:22.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:113:0) after 1m0s
[14:08:22.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:113:0)
[14:08:22.105] Timer tick, asking Buildbucket for the build status
[14:08:22.186] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:22.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:114:0) after 1m0s
[14:09:22.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:114:0)
[14:09:22.230] Timer tick, asking Buildbucket for the build status
[14:09:22.374] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:22.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:115:0) after 1m0s
[14:10:22.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:115:0)
[14:10:22.472] Timer tick, asking Buildbucket for the build status
[14:10:22.566] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:22.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:116:0) after 1m0s
[14:11:22.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:116:0)
[14:11:22.603] Timer tick, asking Buildbucket for the build status
[14:11:22.812] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:22.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:117:0) after 1m0s
[14:12:22.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:117:0)
[14:12:22.941] Timer tick, asking Buildbucket for the build status
[14:12:23.131] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:23.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:118:0) after 1m0s
[14:13:23.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:118:0)
[14:13:23.181] Timer tick, asking Buildbucket for the build status
[14:13:23.471] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:23.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:119:0) after 1m0s
[14:14:23.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:119:0)
[14:14:23.617] Timer tick, asking Buildbucket for the build status
[14:14:23.743] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:23.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:120:0) after 1m0s
[14:15:24.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:120:0)
[14:15:24.441] Timer tick, asking Buildbucket for the build status
[14:15:24.558] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:24.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:121:0) after 1m0s
[14:16:24.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:121:0)
[14:16:24.546] Timer tick, asking Buildbucket for the build status
[14:16:24.642] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:24.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:122:0) after 1m0s
[14:17:24.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:122:0)
[14:17:24.666] Timer tick, asking Buildbucket for the build status
[14:17:24.915] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:24.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:123:0) after 1m0s
[14:18:25.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:123:0)
[14:18:25.038] Timer tick, asking Buildbucket for the build status
[14:18:25.294] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:25.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:124:0) after 1m0s
[14:19:25.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:124:0)
[14:19:25.442] Timer tick, asking Buildbucket for the build status
[14:19:25.786] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:25.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:125:0) after 1m0s
[14:20:25.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:125:0)
[14:20:25.886] Timer tick, asking Buildbucket for the build status
[14:20:26.340] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:26.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:126:0) after 1m0s
[14:21:26.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:126:0)
[14:21:26.449] Timer tick, asking Buildbucket for the build status
[14:21:26.625] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:26.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:127:0) after 1m0s
[14:22:26.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:127:0)
[14:22:26.752] Timer tick, asking Buildbucket for the build status
[14:22:26.911] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:26.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:128:0) after 1m0s
[14:23:27.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:128:0)
[14:23:27.061] Timer tick, asking Buildbucket for the build status
[14:23:27.139] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:27.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:129:0) after 1m0s
[14:24:27.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:129:0)
[14:24:27.277] Timer tick, asking Buildbucket for the build status
[14:24:27.873] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:27.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:130:0) after 1m0s
[14:25:27.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:130:0)
[14:25:27.924] Timer tick, asking Buildbucket for the build status
[14:25:28.079] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:28.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:131:0) after 1m0s
[14:26:28.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:131:0)
[14:26:28.122] Timer tick, asking Buildbucket for the build status
[14:26:28.336] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:28.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:132:0) after 1m0s
[14:27:28.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:132:0)
[14:27:28.364] Timer tick, asking Buildbucket for the build status
[14:27:29.026] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:29.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:133:0) after 1m0s
[14:28:30.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:133:0)
[14:28:30.068] Timer tick, asking Buildbucket for the build status
[14:28:30.152] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:30.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:134:0) after 1m0s
[14:29:30.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:134:0)
[14:29:30.425] Timer tick, asking Buildbucket for the build status
[14:29:30.535] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:30.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:135:0) after 1m0s
[14:30:30.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:135:0)
[14:30:30.711] Timer tick, asking Buildbucket for the build status
[14:30:30.796] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:30.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:136:0) after 1m0s
[14:31:30.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:136:0)
[14:31:30.853] Timer tick, asking Buildbucket for the build status
[14:31:30.960] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:30.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:137:0) after 1m0s
[14:32:31.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:137:0)
[14:32:31.013] Timer tick, asking Buildbucket for the build status
[14:32:31.149] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:31.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:138:0) after 1m0s
[14:33:31.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:138:0)
[14:33:31.176] Timer tick, asking Buildbucket for the build status
[14:33:31.534] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:31.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:139:0) after 1m0s
[14:34:31.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:139:0)
[14:34:31.541] Timer tick, asking Buildbucket for the build status
[14:34:31.633] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:31.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:140:0) after 1m0s
[14:35:31.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:140:0)
[14:35:31.662] Timer tick, asking Buildbucket for the build status
[14:35:32.137] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:32.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:141:0) after 1m0s
[14:36:32.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:141:0)
[14:36:32.164] Timer tick, asking Buildbucket for the build status
[14:36:32.276] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:32.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:142:0) after 1m0s
[14:37:32.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:142:0)
[14:37:32.337] Timer tick, asking Buildbucket for the build status
[14:37:32.585] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:32.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:143:0) after 1m0s
[14:38:32.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:143:0)
[14:38:32.708] Timer tick, asking Buildbucket for the build status
[14:38:32.936] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:32.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:144:0) after 1m0s
[14:39:33.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:144:0)
[14:39:33.035] Timer tick, asking Buildbucket for the build status
[14:39:33.258] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:33.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:145:0) after 1m0s
[14:40:33.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:145:0)
[14:40:33.399] Timer tick, asking Buildbucket for the build status
[14:40:33.955] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:33.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:146:0) after 1m0s
[14:41:34.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:146:0)
[14:41:34.062] Timer tick, asking Buildbucket for the build status
[14:41:34.195] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:34.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:147:0) after 1m0s
[14:42:34.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:147:0)
[14:42:34.276] Timer tick, asking Buildbucket for the build status
[14:42:34.815] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:34.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:148:0) after 1m0s
[14:43:34.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:148:0)
[14:43:34.925] Timer tick, asking Buildbucket for the build status
[14:43:35.008] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:35.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:149:0) after 1m0s
[14:44:35.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:149:0)
[14:44:35.101] Timer tick, asking Buildbucket for the build status
[14:44:35.218] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:35.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:150:0) after 1m0s
[14:45:35.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:150:0)
[14:45:35.236] Timer tick, asking Buildbucket for the build status
[14:45:35.330] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:35.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:151:0) after 1m0s
[14:46:35.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:151:0)
[14:46:35.381] Timer tick, asking Buildbucket for the build status
[14:46:35.492] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:35.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:152:0) after 1m0s
[14:47:35.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:152:0)
[14:47:35.499] Timer tick, asking Buildbucket for the build status
[14:47:36.331] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:36.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:153:0) after 1m0s
[14:48:36.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:153:0)
[14:48:36.400] Timer tick, asking Buildbucket for the build status
[14:48:36.491] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:36.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:154:0) after 1m0s
[14:49:36.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:154:0)
[14:49:36.576] Timer tick, asking Buildbucket for the build status
[14:49:36.652] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:36.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:155:0) after 1m0s
[14:50:36.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:155:0)
[14:50:36.709] Timer tick, asking Buildbucket for the build status
[14:50:37.027] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:37.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:156:0) after 1m0s
[14:51:37.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:156:0)
[14:51:37.059] Timer tick, asking Buildbucket for the build status
[14:51:37.381] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:37.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:157:0) after 1m0s
[14:52:37.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:157:0)
[14:52:37.446] Timer tick, asking Buildbucket for the build status
[14:52:37.634] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:37.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:158:0) after 1m0s
[14:53:37.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:158:0)
[14:53:37.641] Timer tick, asking Buildbucket for the build status
[14:53:37.912] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:37.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:159:0) after 1m0s
[14:54:38.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:159:0)
[14:54:38.047] Timer tick, asking Buildbucket for the build status
[14:54:38.108] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:38.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:160:0) after 1m0s
[14:55:38.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:160:0)
[14:55:38.204] Timer tick, asking Buildbucket for the build status
[14:55:38.267] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:38.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:161:0) after 1m0s
[14:56:38.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:161:0)
[14:56:38.349] Timer tick, asking Buildbucket for the build status
[14:56:38.444] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:38.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:162:0) after 1m0s
[14:57:38.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:162:0)
[14:57:38.473] Timer tick, asking Buildbucket for the build status
[14:57:38.701] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:38.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:163:0) after 1m0s
[14:58:38.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:163:0)
[14:58:38.777] Timer tick, asking Buildbucket for the build status
[14:58:39.876] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:39.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:164:0) after 1m0s
[14:59:39.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:164:0)
[14:59:39.992] Timer tick, asking Buildbucket for the build status
[14:59:40.143] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:40.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:165:0) after 1m0s
[15:00:40.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:165:0)
[15:00:40.270] Timer tick, asking Buildbucket for the build status
[15:00:40.348] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:40.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:166:0) after 1m0s
[15:01:40.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:166:0)
[15:01:40.483] Timer tick, asking Buildbucket for the build status
[15:01:40.618] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:40.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:167:0) after 1m0s
[15:02:40.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:167:0)
[15:02:40.707] Timer tick, asking Buildbucket for the build status
[15:02:41.411] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:41.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:168:0) after 1m0s
[15:03:41.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:168:0)
[15:03:41.447] Timer tick, asking Buildbucket for the build status
[15:03:42.047] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:42.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:169:0) after 1m0s
[15:04:42.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:169:0)
[15:04:42.110] Timer tick, asking Buildbucket for the build status
[15:04:42.317] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:42.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:170:0) after 1m0s
[15:05:42.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:170:0)
[15:05:42.417] Timer tick, asking Buildbucket for the build status
[15:05:42.490] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:42.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:171:0) after 1m0s
[15:06:42.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:171:0)
[15:06:42.530] Timer tick, asking Buildbucket for the build status
[15:06:42.712] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:42.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:172:0) after 1m0s
[15:07:42.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:172:0)
[15:07:42.820] Timer tick, asking Buildbucket for the build status
[15:07:42.973] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:42.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:173:0) after 1m0s
[15:08:43.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:173:0)
[15:08:43.011] Timer tick, asking Buildbucket for the build status
[15:08:43.162] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:43.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:174:0) after 1m0s
[15:09:43.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:174:0)
[15:09:43.301] Timer tick, asking Buildbucket for the build status
[15:09:43.558] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:43.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:175:0) after 1m0s
[15:10:43.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:175:0)
[15:10:43.608] Timer tick, asking Buildbucket for the build status
[15:10:43.813] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:43.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:176:0) after 1m0s
[15:11:43.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:176:0)
[15:11:43.847] Timer tick, asking Buildbucket for the build status
[15:11:43.966] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:43.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:177:0) after 1m0s
[15:12:43.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:177:0)
[15:12:43.992] Timer tick, asking Buildbucket for the build status
[15:12:44.235] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:44.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:178:0) after 1m0s
[15:13:44.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:178:0)
[15:13:44.249] Timer tick, asking Buildbucket for the build status
[15:13:44.359] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:44.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:179:0) after 1m0s
[15:14:44.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:179:0)
[15:14:44.401] Timer tick, asking Buildbucket for the build status
[15:14:44.522] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:44.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:180:0) after 1m0s
[15:15:44.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:180:0)
[15:15:44.553] Timer tick, asking Buildbucket for the build status
[15:15:47.241] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:47.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:181:0) after 1m0s
[15:16:47.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:181:0)
[15:16:47.254] Timer tick, asking Buildbucket for the build status
[15:16:47.471] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:47.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:182:0) after 1m0s
[15:17:47.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:182:0)
[15:17:47.479] Timer tick, asking Buildbucket for the build status
[15:17:47.640] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:47.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:183:0) after 1m0s
[15:18:47.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:183:0)
[15:18:47.674] Timer tick, asking Buildbucket for the build status
[15:18:48.058] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:48.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:184:0) after 1m0s
[15:19:48.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:184:0)
[15:19:48.115] Timer tick, asking Buildbucket for the build status
[15:19:48.194] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:48.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:185:0) after 1m0s
[15:20:48.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:185:0)
[15:20:48.291] Timer tick, asking Buildbucket for the build status
[15:20:48.366] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:48.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:186:0) after 1m0s
[15:21:48.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:186:0)
[15:21:48.527] Timer tick, asking Buildbucket for the build status
[15:21:49.000] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:49.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:187:0) after 1m0s
[15:22:49.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:187:0)
[15:22:49.087] Timer tick, asking Buildbucket for the build status
[15:22:49.291] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:49.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:188:0) after 1m0s
[15:23:49.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:188:0)
[15:23:49.392] Timer tick, asking Buildbucket for the build status
[15:23:49.456] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:49.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:189:0) after 1m0s
[15:24:49.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:189:0)
[15:24:49.485] Timer tick, asking Buildbucket for the build status
[15:24:49.700] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:49.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:190:0) after 1m0s
[15:25:49.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:190:0)
[15:25:49.762] Timer tick, asking Buildbucket for the build status
[15:25:49.831] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:49.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:191:0) after 1m0s
[15:26:49.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:191:0)
[15:26:49.893] Timer tick, asking Buildbucket for the build status
[15:26:50.117] Build 8872896621476908688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:50.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9038326683538159888:192:0) after 1m0s
[15:27:30.220] Received PubSub notification, asking Buildbucket for the build status
[15:27:30.301] Build 8872896621476908688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:27:30.301] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1596554849525736",
    "created_by": "project:infra",
    "created_ts": "1596543402177690",
    "id": "8872896621476908688",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4221.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"f0c53444-7877-46ea-83c4-82d52255bafe\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4221.2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-x8mm\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5436-adf7f8a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1596543462146071",
    "status": "COMPLETED",
    "status_changed_ts": "1596554849902226",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9038326683538159888",
      "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/8872896621476908688/+/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:4dd2388b5145f110",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1596554849922373",
    "url": "https://ci.chromium.org/b/8872896621476908688",
    "utcnow_ts": "1596554850290881"
  }
}
[15:27:30.301] Invocation finished in 3h10m49.473058949s with status SUCCEEDED