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

• parent_buildername:publish_tarball
• user_agent:recipe

[11:29:03.071] New invocation is queued and will start shortly
[11:29:03.071] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:29:04.169] Starting the invocation (attempt 1)
[11:29:04.264] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:29:04.264] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:29:04.264] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9028998192597094352",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4326.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"794bec38-08f0-4161-bed5-5c572304b928\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4326.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9028998192597094352",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[11:29:04.727] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1605439744379754",
    "id": "8863568130556194880",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4326.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"794bec38-08f0-4161-bed5-5c572304b928\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4326.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1605439744610766",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9028998192597094352",
      "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/8863568130556194880/+/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": "1605439744610929",
    "url": "https://ci.chromium.org/b/8863568130556194880",
    "utcnow_ts": "1605439744719352"
  }
}
[11:29:04.727] Task URL: https://ci.chromium.org/b/8863568130556194880
[11:29:04.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:2:0) after 1m0s
[11:30:04.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:2:0)
[11:30:04.851] Timer tick, asking Buildbucket for the build status
[11:30:04.974] Build 8863568130556194880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:04.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:3:0) after 1m0s
[11:30:10.804] Received PubSub notification, asking Buildbucket for the build status
[11:30:10.889] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:05.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:3:0)
[11:31:05.381] Timer tick, asking Buildbucket for the build status
[11:31:05.477] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:05.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:5:0) after 1m0s
[11:32:05.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:5:0)
[11:32:05.534] Timer tick, asking Buildbucket for the build status
[11:32:05.649] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:05.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:6:0) after 1m0s
[11:33:05.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:6:0)
[11:33:05.834] Timer tick, asking Buildbucket for the build status
[11:33:06.031] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:06.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:7:0) after 1m0s
[11:34:06.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:7:0)
[11:34:06.133] Timer tick, asking Buildbucket for the build status
[11:34:06.403] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:06.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:8:0) after 1m0s
[11:35:06.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:8:0)
[11:35:06.514] Timer tick, asking Buildbucket for the build status
[11:35:06.774] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:06.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:9:0) after 1m0s
[11:36:06.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:9:0)
[11:36:06.792] Timer tick, asking Buildbucket for the build status
[11:36:06.950] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:06.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:10:0) after 1m0s
[11:37:07.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:10:0)
[11:37:07.018] Timer tick, asking Buildbucket for the build status
[11:37:07.221] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:07.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:11:0) after 1m0s
[11:38:07.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:11:0)
[11:38:07.304] Timer tick, asking Buildbucket for the build status
[11:38:07.449] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:07.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:12:0) after 1m0s
[11:39:07.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:12:0)
[11:39:07.487] Timer tick, asking Buildbucket for the build status
[11:39:07.553] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:07.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:13:0) after 1m0s
[11:40:08.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:13:0)
[11:40:08.559] Timer tick, asking Buildbucket for the build status
[11:40:08.736] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:08.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:14:0) after 1m0s
[11:41:09.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:14:0)
[11:41:09.514] Timer tick, asking Buildbucket for the build status
[11:41:09.613] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:09.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:15:0) after 1m0s
[11:42:09.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:15:0)
[11:42:09.753] Timer tick, asking Buildbucket for the build status
[11:42:09.920] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:09.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:16:0) after 1m0s
[11:43:09.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:16:0)
[11:43:09.976] Timer tick, asking Buildbucket for the build status
[11:43:10.045] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:10.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:17:0) after 1m0s
[11:44:10.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:17:0)
[11:44:10.054] Timer tick, asking Buildbucket for the build status
[11:44:10.130] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:10.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:18:0) after 1m0s
[11:45:10.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:18:0)
[11:45:10.155] Timer tick, asking Buildbucket for the build status
[11:45:10.323] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:10.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:19:0) after 1m0s
[11:46:10.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:19:0)
[11:46:10.410] Timer tick, asking Buildbucket for the build status
[11:46:10.526] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:10.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:20:0) after 1m0s
[11:47:10.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:20:0)
[11:47:10.504] Timer tick, asking Buildbucket for the build status
[11:47:10.720] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:10.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:21:0) after 1m0s
[11:48:10.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:21:0)
[11:48:10.725] Timer tick, asking Buildbucket for the build status
[11:48:11.084] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:11.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:22:0) after 1m0s
[11:49:11.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:22:0)
[11:49:11.172] Timer tick, asking Buildbucket for the build status
[11:49:11.246] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:11.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:23:0) after 1m0s
[11:50:11.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:23:0)
[11:50:11.337] Timer tick, asking Buildbucket for the build status
[11:50:11.479] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:11.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:24:0) after 1m0s
[11:51:11.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:24:0)
[11:51:11.515] Timer tick, asking Buildbucket for the build status
[11:51:11.593] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:11.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:25:0) after 1m0s
[11:52:11.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:25:0)
[11:52:11.842] Timer tick, asking Buildbucket for the build status
[11:52:11.934] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:11.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:26:0) after 1m0s
[11:53:12.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:26:0)
[11:53:12.057] Timer tick, asking Buildbucket for the build status
[11:53:12.140] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:12.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:27:0) after 1m0s
[11:54:12.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:27:0)
[11:54:12.198] Timer tick, asking Buildbucket for the build status
[11:54:12.479] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:12.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:28:0) after 1m0s
[11:55:12.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:28:0)
[11:55:12.610] Timer tick, asking Buildbucket for the build status
[11:55:12.759] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:12.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:29:0) after 1m0s
[11:56:12.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:29:0)
[11:56:12.811] Timer tick, asking Buildbucket for the build status
[11:56:12.897] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:12.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:30:0) after 1m0s
[11:57:13.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:30:0)
[11:57:13.117] Timer tick, asking Buildbucket for the build status
[11:57:13.391] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:13.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:31:0) after 1m0s
[11:58:13.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:31:0)
[11:58:13.554] Timer tick, asking Buildbucket for the build status
[11:58:13.780] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:13.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:32:0) after 1m0s
[11:59:13.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:32:0)
[11:59:13.876] Timer tick, asking Buildbucket for the build status
[11:59:13.951] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:13.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:33:0) after 1m0s
[12:00:13.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:33:0)
[12:00:13.982] Timer tick, asking Buildbucket for the build status
[12:00:14.114] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:14.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:34:0) after 1m0s
[12:01:14.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:34:0)
[12:01:14.242] Timer tick, asking Buildbucket for the build status
[12:01:14.470] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:14.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:35:0) after 1m0s
[12:02:14.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:35:0)
[12:02:14.564] Timer tick, asking Buildbucket for the build status
[12:02:14.678] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:14.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:36:0) after 1m0s
[12:03:14.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:36:0)
[12:03:14.749] Timer tick, asking Buildbucket for the build status
[12:03:14.882] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:14.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:37:0) after 1m0s
[12:04:14.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:37:0)
[12:04:14.905] Timer tick, asking Buildbucket for the build status
[12:04:14.978] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:14.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:38:0) after 1m0s
[12:05:15.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:38:0)
[12:05:15.044] Timer tick, asking Buildbucket for the build status
[12:05:15.156] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:15.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:39:0) after 1m0s
[12:06:15.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:39:0)
[12:06:15.318] Timer tick, asking Buildbucket for the build status
[12:06:15.403] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:15.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:40:0) after 1m0s
[12:07:15.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:40:0)
[12:07:15.455] Timer tick, asking Buildbucket for the build status
[12:07:15.552] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:15.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:41:0) after 1m0s
[12:08:15.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:41:0)
[12:08:15.580] Timer tick, asking Buildbucket for the build status
[12:08:15.724] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:15.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:42:0) after 1m0s
[12:09:15.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:42:0)
[12:09:15.745] Timer tick, asking Buildbucket for the build status
[12:09:15.834] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:15.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:43:0) after 1m0s
[12:10:15.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:43:0)
[12:10:15.886] Timer tick, asking Buildbucket for the build status
[12:10:15.993] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:15.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:44:0) after 1m0s
[12:11:16.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:44:0)
[12:11:16.102] Timer tick, asking Buildbucket for the build status
[12:11:16.420] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:16.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:45:0) after 1m0s
[12:12:16.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:45:0)
[12:12:16.551] Timer tick, asking Buildbucket for the build status
[12:12:16.699] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:16.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:46:0) after 1m0s
[12:13:16.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:46:0)
[12:13:16.819] Timer tick, asking Buildbucket for the build status
[12:13:16.951] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:16.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:47:0) after 1m0s
[12:14:17.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:47:0)
[12:14:17.009] Timer tick, asking Buildbucket for the build status
[12:14:17.095] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:17.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:48:0) after 1m0s
[12:15:17.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:48:0)
[12:15:17.073] Timer tick, asking Buildbucket for the build status
[12:15:17.315] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:17.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:49:0) after 1m0s
[12:16:17.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:49:0)
[12:16:17.290] Timer tick, asking Buildbucket for the build status
[12:16:17.366] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:17.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:50:0) after 1m0s
[12:17:17.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:50:0)
[12:17:17.489] Timer tick, asking Buildbucket for the build status
[12:17:17.569] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:17.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:51:0) after 1m0s
[12:18:17.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:51:0)
[12:18:17.602] Timer tick, asking Buildbucket for the build status
[12:18:17.715] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:17.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:52:0) after 1m0s
[12:19:17.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:52:0)
[12:19:17.809] Timer tick, asking Buildbucket for the build status
[12:19:17.955] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:17.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:53:0) after 1m0s
[12:20:18.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:53:0)
[12:20:18.036] Timer tick, asking Buildbucket for the build status
[12:20:18.227] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:18.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:54:0) after 1m0s
[12:21:18.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:54:0)
[12:21:18.385] Timer tick, asking Buildbucket for the build status
[12:21:18.623] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:18.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:55:0) after 1m0s
[12:22:18.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:55:0)
[12:22:18.721] Timer tick, asking Buildbucket for the build status
[12:22:19.696] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:19.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:56:0) after 1m0s
[12:23:19.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:56:0)
[12:23:19.869] Timer tick, asking Buildbucket for the build status
[12:23:19.957] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:19.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:57:0) after 1m0s
[12:24:19.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:57:0)
[12:24:19.977] Timer tick, asking Buildbucket for the build status
[12:24:20.068] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:20.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:58:0) after 1m0s
[12:25:20.101] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:58:0)
[12:25:20.101] Timer tick, asking Buildbucket for the build status
[12:25:20.227] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:20.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:59:0) after 1m0s
[12:26:20.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:59:0)
[12:26:20.295] Timer tick, asking Buildbucket for the build status
[12:26:20.434] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:20.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:60:0) after 1m0s
[12:27:20.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:60:0)
[12:27:20.497] Timer tick, asking Buildbucket for the build status
[12:27:20.572] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:20.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:61:0) after 1m0s
[12:28:20.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:61:0)
[12:28:20.596] Timer tick, asking Buildbucket for the build status
[12:28:20.689] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:20.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:62:0) after 1m0s
[12:29:20.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:62:0)
[12:29:20.731] Timer tick, asking Buildbucket for the build status
[12:29:20.992] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:20.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:63:0) after 1m0s
[12:30:21.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:63:0)
[12:30:21.097] Timer tick, asking Buildbucket for the build status
[12:30:21.295] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:21.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:64:0) after 1m0s
[12:31:21.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:64:0)
[12:31:21.365] Timer tick, asking Buildbucket for the build status
[12:31:21.447] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:21.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:65:0) after 1m0s
[12:32:21.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:65:0)
[12:32:21.471] Timer tick, asking Buildbucket for the build status
[12:32:21.644] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:21.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:66:0) after 1m0s
[12:33:21.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:66:0)
[12:33:21.657] Timer tick, asking Buildbucket for the build status
[12:33:21.729] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:21.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:67:0) after 1m0s
[12:34:21.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:67:0)
[12:34:21.750] Timer tick, asking Buildbucket for the build status
[12:34:21.815] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:21.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:68:0) after 1m0s
[12:35:21.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:68:0)
[12:35:21.844] Timer tick, asking Buildbucket for the build status
[12:35:21.958] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:21.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:69:0) after 1m0s
[12:36:21.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:69:0)
[12:36:21.980] Timer tick, asking Buildbucket for the build status
[12:36:22.054] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:22.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:70:0) after 1m0s
[12:37:22.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:70:0)
[12:37:22.109] Timer tick, asking Buildbucket for the build status
[12:37:22.194] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:22.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:71:0) after 1m0s
[12:38:22.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:71:0)
[12:38:22.275] Timer tick, asking Buildbucket for the build status
[12:38:22.414] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:22.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:72:0) after 1m0s
[12:39:22.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:72:0)
[12:39:22.438] Timer tick, asking Buildbucket for the build status
[12:39:22.517] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:22.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:73:0) after 1m0s
[12:40:22.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:73:0)
[12:40:22.538] Timer tick, asking Buildbucket for the build status
[12:40:22.801] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:22.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:74:0) after 1m0s
[12:41:22.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:74:0)
[12:41:22.830] Timer tick, asking Buildbucket for the build status
[12:41:22.911] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:22.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:75:0) after 1m0s
[12:42:22.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:75:0)
[12:42:22.984] Timer tick, asking Buildbucket for the build status
[12:42:23.398] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:23.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:76:0) after 1m0s
[12:43:23.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:76:0)
[12:43:23.487] Timer tick, asking Buildbucket for the build status
[12:43:23.725] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:23.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:77:0) after 1m0s
[12:44:23.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:77:0)
[12:44:23.804] Timer tick, asking Buildbucket for the build status
[12:44:24.263] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:24.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:78:0) after 1m0s
[12:45:24.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:78:0)
[12:45:24.286] Timer tick, asking Buildbucket for the build status
[12:45:25.690] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:25.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:79:0) after 1m0s
[12:46:25.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:79:0)
[12:46:25.696] Timer tick, asking Buildbucket for the build status
[12:46:25.767] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:25.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:80:0) after 1m0s
[12:47:25.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:80:0)
[12:47:25.796] Timer tick, asking Buildbucket for the build status
[12:47:26.317] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:26.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:81:0) after 1m0s
[12:48:26.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:81:0)
[12:48:26.477] Timer tick, asking Buildbucket for the build status
[12:48:26.819] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:26.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:82:0) after 1m0s
[12:49:26.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:82:0)
[12:49:26.842] Timer tick, asking Buildbucket for the build status
[12:49:26.985] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:26.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:83:0) after 1m0s
[12:50:27.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:83:0)
[12:50:27.005] Timer tick, asking Buildbucket for the build status
[12:50:27.089] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:27.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:84:0) after 1m0s
[12:51:27.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:84:0)
[12:51:27.112] Timer tick, asking Buildbucket for the build status
[12:51:27.187] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:27.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:85:0) after 1m0s
[12:52:27.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:85:0)
[12:52:27.211] Timer tick, asking Buildbucket for the build status
[12:52:28.919] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:28.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:86:0) after 1m0s
[12:53:28.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:86:0)
[12:53:28.943] Timer tick, asking Buildbucket for the build status
[12:53:29.110] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:29.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:87:0) after 1m0s
[12:54:29.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:87:0)
[12:54:29.133] Timer tick, asking Buildbucket for the build status
[12:54:29.233] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:29.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:88:0) after 1m0s
[12:55:29.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:88:0)
[12:55:29.263] Timer tick, asking Buildbucket for the build status
[12:55:29.335] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:29.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:89:0) after 1m0s
[12:56:29.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:89:0)
[12:56:29.362] Timer tick, asking Buildbucket for the build status
[12:56:29.722] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:29.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:90:0) after 1m0s
[12:57:29.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:90:0)
[12:57:29.772] Timer tick, asking Buildbucket for the build status
[12:57:29.861] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:29.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:91:0) after 1m0s
[12:58:29.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:91:0)
[12:58:29.849] Timer tick, asking Buildbucket for the build status
[12:58:29.931] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:29.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:92:0) after 1m0s
[12:59:29.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:92:0)
[12:59:29.962] Timer tick, asking Buildbucket for the build status
[12:59:30.149] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:30.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:93:0) after 1m0s
[13:00:30.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:93:0)
[13:00:30.172] Timer tick, asking Buildbucket for the build status
[13:00:30.321] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:30.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:94:0) after 1m0s
[13:01:30.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:94:0)
[13:01:30.348] Timer tick, asking Buildbucket for the build status
[13:01:30.424] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:30.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:95:0) after 1m0s
[13:02:30.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:95:0)
[13:02:30.475] Timer tick, asking Buildbucket for the build status
[13:02:30.554] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:30.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:96:0) after 1m0s
[13:03:30.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:96:0)
[13:03:30.576] Timer tick, asking Buildbucket for the build status
[13:03:30.647] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:30.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:97:0) after 1m0s
[13:04:30.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:97:0)
[13:04:30.670] Timer tick, asking Buildbucket for the build status
[13:04:30.767] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:30.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:98:0) after 1m0s
[13:05:30.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:98:0)
[13:05:30.793] Timer tick, asking Buildbucket for the build status
[13:05:30.876] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:30.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:99:0) after 1m0s
[13:06:30.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:99:0)
[13:06:30.898] Timer tick, asking Buildbucket for the build status
[13:06:30.984] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:30.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:100:0) after 1m0s
[13:07:30.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:100:0)
[13:07:30.991] Timer tick, asking Buildbucket for the build status
[13:07:31.088] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:31.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:101:0) after 1m0s
[13:08:31.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:101:0)
[13:08:31.251] Timer tick, asking Buildbucket for the build status
[13:08:31.318] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:31.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:102:0) after 1m0s
[13:09:31.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:102:0)
[13:09:31.521] Timer tick, asking Buildbucket for the build status
[13:09:31.675] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:31.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:103:0) after 1m0s
[13:10:31.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:103:0)
[13:10:31.702] Timer tick, asking Buildbucket for the build status
[13:10:31.793] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:31.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:104:0) after 1m0s
[13:11:31.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:104:0)
[13:11:31.816] Timer tick, asking Buildbucket for the build status
[13:11:31.910] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:31.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:105:0) after 1m0s
[13:12:36.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:105:0)
[13:12:36.639] Timer tick, asking Buildbucket for the build status
[13:12:36.744] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:36.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:106:0) after 1m0s
[13:13:36.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:106:0)
[13:13:36.724] Timer tick, asking Buildbucket for the build status
[13:13:36.908] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:36.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:107:0) after 1m0s
[13:14:37.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:107:0)
[13:14:37.030] Timer tick, asking Buildbucket for the build status
[13:14:37.203] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:37.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:108:0) after 1m0s
[13:15:37.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:108:0)
[13:15:37.229] Timer tick, asking Buildbucket for the build status
[13:15:37.388] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:37.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:109:0) after 1m0s
[13:16:37.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:109:0)
[13:16:37.390] Timer tick, asking Buildbucket for the build status
[13:16:37.483] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:37.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:110:0) after 1m0s
[13:17:37.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:110:0)
[13:17:37.511] Timer tick, asking Buildbucket for the build status
[13:17:39.689] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:39.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:111:0) after 1m0s
[13:18:39.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:111:0)
[13:18:39.776] Timer tick, asking Buildbucket for the build status
[13:18:39.861] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:39.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:112:0) after 1m0s
[13:19:39.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:112:0)
[13:19:39.976] Timer tick, asking Buildbucket for the build status
[13:19:40.225] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:40.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:113:0) after 1m0s
[13:20:40.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:113:0)
[13:20:40.286] Timer tick, asking Buildbucket for the build status
[13:20:40.607] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:40.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:114:0) after 1m0s
[13:21:40.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:114:0)
[13:21:40.683] Timer tick, asking Buildbucket for the build status
[13:21:40.792] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:40.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:115:0) after 1m0s
[13:22:40.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:115:0)
[13:22:40.867] Timer tick, asking Buildbucket for the build status
[13:22:41.055] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:41.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:116:0) after 1m0s
[13:23:41.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:116:0)
[13:23:41.097] Timer tick, asking Buildbucket for the build status
[13:23:41.415] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:41.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:117:0) after 1m0s
[13:24:41.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:117:0)
[13:24:41.451] Timer tick, asking Buildbucket for the build status
[13:24:41.561] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:41.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:118:0) after 1m0s
[13:25:41.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:118:0)
[13:25:41.585] Timer tick, asking Buildbucket for the build status
[13:25:41.913] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:41.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:119:0) after 1m0s
[13:26:41.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:119:0)
[13:26:41.890] Timer tick, asking Buildbucket for the build status
[13:26:41.974] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:41.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:120:0) after 1m0s
[13:27:42.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:120:0)
[13:27:42.001] Timer tick, asking Buildbucket for the build status
[13:27:42.090] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:42.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:121:0) after 1m0s
[13:28:42.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:121:0)
[13:28:42.127] Timer tick, asking Buildbucket for the build status
[13:28:42.224] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:42.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:122:0) after 1m0s
[13:29:42.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:122:0)
[13:29:42.222] Timer tick, asking Buildbucket for the build status
[13:29:42.588] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:42.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:123:0) after 1m0s
[13:30:42.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:123:0)
[13:30:42.689] Timer tick, asking Buildbucket for the build status
[13:30:42.770] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:42.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:124:0) after 1m0s
[13:31:42.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:124:0)
[13:31:42.863] Timer tick, asking Buildbucket for the build status
[13:31:42.939] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:42.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:125:0) after 1m0s
[13:32:42.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:125:0)
[13:32:42.979] Timer tick, asking Buildbucket for the build status
[13:32:43.096] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:43.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:126:0) after 1m0s
[13:33:43.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:126:0)
[13:33:43.133] Timer tick, asking Buildbucket for the build status
[13:33:43.235] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:43.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:127:0) after 1m0s
[13:34:43.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:127:0)
[13:34:43.329] Timer tick, asking Buildbucket for the build status
[13:34:43.429] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:43.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:128:0) after 1m0s
[13:35:43.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:128:0)
[13:35:43.451] Timer tick, asking Buildbucket for the build status
[13:35:43.526] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:43.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:129:0) after 1m0s
[13:36:43.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:129:0)
[13:36:43.553] Timer tick, asking Buildbucket for the build status
[13:36:43.643] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:43.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:130:0) after 1m0s
[13:37:43.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:130:0)
[13:37:43.664] Timer tick, asking Buildbucket for the build status
[13:37:43.744] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:43.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:131:0) after 1m0s
[13:38:43.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:131:0)
[13:38:43.782] Timer tick, asking Buildbucket for the build status
[13:38:43.999] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:43.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:132:0) after 1m0s
[13:39:44.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:132:0)
[13:39:44.067] Timer tick, asking Buildbucket for the build status
[13:39:44.186] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:44.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:133:0) after 1m0s
[13:40:44.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:133:0)
[13:40:44.161] Timer tick, asking Buildbucket for the build status
[13:40:44.244] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:44.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:134:0) after 1m0s
[13:41:44.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:134:0)
[13:41:44.248] Timer tick, asking Buildbucket for the build status
[13:41:44.325] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:44.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:135:0) after 1m0s
[13:42:44.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:135:0)
[13:42:44.423] Timer tick, asking Buildbucket for the build status
[13:42:44.638] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:44.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:136:0) after 1m0s
[13:43:44.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:136:0)
[13:43:44.675] Timer tick, asking Buildbucket for the build status
[13:43:45.522] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:45.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:137:0) after 1m0s
[13:44:45.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:137:0)
[13:44:45.568] Timer tick, asking Buildbucket for the build status
[13:44:45.669] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:45.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:138:0) after 1m0s
[13:45:45.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:138:0)
[13:45:45.706] Timer tick, asking Buildbucket for the build status
[13:45:45.822] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:45.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:139:0) after 1m0s
[13:46:45.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:139:0)
[13:46:45.902] Timer tick, asking Buildbucket for the build status
[13:46:46.189] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:46.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:140:0) after 1m0s
[13:47:46.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:140:0)
[13:47:46.215] Timer tick, asking Buildbucket for the build status
[13:47:46.365] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:46.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:141:0) after 1m0s
[13:48:46.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:141:0)
[13:48:46.411] Timer tick, asking Buildbucket for the build status
[13:48:46.582] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:46.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:142:0) after 1m0s
[13:49:46.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:142:0)
[13:49:46.559] Timer tick, asking Buildbucket for the build status
[13:49:46.657] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:46.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:143:0) after 1m0s
[13:50:46.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:143:0)
[13:50:46.683] Timer tick, asking Buildbucket for the build status
[13:50:46.762] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:46.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:144:0) after 1m0s
[13:51:46.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:144:0)
[13:51:46.966] Timer tick, asking Buildbucket for the build status
[13:51:47.052] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:47.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:145:0) after 1m0s
[13:52:47.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:145:0)
[13:52:47.153] Timer tick, asking Buildbucket for the build status
[13:52:47.309] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:47.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:146:0) after 1m0s
[13:53:47.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:146:0)
[13:53:47.374] Timer tick, asking Buildbucket for the build status
[13:53:47.455] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:47.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:147:0) after 1m0s
[13:54:47.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:147:0)
[13:54:47.427] Timer tick, asking Buildbucket for the build status
[13:54:47.682] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:47.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:148:0) after 1m0s
[13:55:56.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:148:0)
[13:55:56.885] Timer tick, asking Buildbucket for the build status
[13:55:57.025] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:57.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:149:0) after 1m0s
[13:56:57.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:149:0)
[13:56:57.259] Timer tick, asking Buildbucket for the build status
[13:56:57.449] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:57.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:150:0) after 1m0s
[13:57:57.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:150:0)
[13:57:57.451] Timer tick, asking Buildbucket for the build status
[13:57:57.529] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:57.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:151:0) after 1m0s
[13:58:57.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:151:0)
[13:58:57.551] Timer tick, asking Buildbucket for the build status
[13:58:57.658] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:57.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:152:0) after 1m0s
[13:59:57.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:152:0)
[13:59:57.704] Timer tick, asking Buildbucket for the build status
[13:59:57.800] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:57.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:153:0) after 1m0s
[14:00:57.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:153:0)
[14:00:57.884] Timer tick, asking Buildbucket for the build status
[14:00:57.968] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:57.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:154:0) after 1m0s
[14:01:58.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:154:0)
[14:01:58.213] Timer tick, asking Buildbucket for the build status
[14:01:58.305] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:58.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:155:0) after 1m0s
[14:02:58.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:155:0)
[14:02:58.494] Timer tick, asking Buildbucket for the build status
[14:02:58.569] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:58.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:156:0) after 1m0s
[14:03:58.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:156:0)
[14:03:58.693] Timer tick, asking Buildbucket for the build status
[14:03:58.767] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:58.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:157:0) after 1m0s
[14:04:58.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:157:0)
[14:04:58.795] Timer tick, asking Buildbucket for the build status
[14:04:58.896] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:58.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:158:0) after 1m0s
[14:05:58.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:158:0)
[14:05:58.920] Timer tick, asking Buildbucket for the build status
[14:05:59.274] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:59.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:159:0) after 1m0s
[14:06:59.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:159:0)
[14:06:59.505] Timer tick, asking Buildbucket for the build status
[14:06:59.579] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:59.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:160:0) after 1m0s
[14:07:59.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:160:0)
[14:07:59.850] Timer tick, asking Buildbucket for the build status
[14:07:59.961] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:59.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:161:0) after 1m0s
[14:09:00.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:161:0)
[14:09:00.032] Timer tick, asking Buildbucket for the build status
[14:09:00.315] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:00.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:162:0) after 1m0s
[14:10:00.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:162:0)
[14:10:00.548] Timer tick, asking Buildbucket for the build status
[14:10:00.802] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:00.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:163:0) after 1m0s
[14:11:01.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:163:0)
[14:11:01.299] Timer tick, asking Buildbucket for the build status
[14:11:01.718] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:01.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:164:0) after 1m0s
[14:12:01.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:164:0)
[14:12:01.761] Timer tick, asking Buildbucket for the build status
[14:12:01.835] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:01.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:165:0) after 1m0s
[14:13:02.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:165:0)
[14:13:02.886] Timer tick, asking Buildbucket for the build status
[14:13:02.965] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:02.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:166:0) after 1m0s
[14:14:03.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:166:0)
[14:14:03.036] Timer tick, asking Buildbucket for the build status
[14:14:03.117] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:03.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:167:0) after 1m0s
[14:15:03.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:167:0)
[14:15:03.238] Timer tick, asking Buildbucket for the build status
[14:15:04.741] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:04.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:168:0) after 1m0s
[14:16:04.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:168:0)
[14:16:04.803] Timer tick, asking Buildbucket for the build status
[14:16:04.894] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:04.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:169:0) after 1m0s
[14:17:04.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:169:0)
[14:17:04.918] Timer tick, asking Buildbucket for the build status
[14:17:05.000] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:05.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:170:0) after 1m0s
[14:18:05.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:170:0)
[14:18:05.057] Timer tick, asking Buildbucket for the build status
[14:18:05.144] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:05.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:171:0) after 1m0s
[14:19:05.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:171:0)
[14:19:05.186] Timer tick, asking Buildbucket for the build status
[14:19:05.271] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:05.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:172:0) after 1m0s
[14:20:05.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:172:0)
[14:20:05.439] Timer tick, asking Buildbucket for the build status
[14:20:05.844] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:05.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:173:0) after 1m0s
[14:21:05.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:173:0)
[14:21:05.990] Timer tick, asking Buildbucket for the build status
[14:21:06.088] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:06.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:174:0) after 1m0s
[14:22:06.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:174:0)
[14:22:06.303] Timer tick, asking Buildbucket for the build status
[14:22:06.375] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:06.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:175:0) after 1m0s
[14:23:06.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:175:0)
[14:23:06.521] Timer tick, asking Buildbucket for the build status
[14:23:06.605] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:06.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:176:0) after 1m0s
[14:24:06.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:176:0)
[14:24:06.632] Timer tick, asking Buildbucket for the build status
[14:24:06.813] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:06.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:177:0) after 1m0s
[14:25:06.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:177:0)
[14:25:06.896] Timer tick, asking Buildbucket for the build status
[14:25:07.122] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:07.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:178:0) after 1m0s
[14:26:07.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:178:0)
[14:26:07.179] Timer tick, asking Buildbucket for the build status
[14:26:07.254] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:07.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:179:0) after 1m0s
[14:27:07.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:179:0)
[14:27:07.229] Timer tick, asking Buildbucket for the build status
[14:27:07.332] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:07.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:180:0) after 1m0s
[14:28:07.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:180:0)
[14:28:07.385] Timer tick, asking Buildbucket for the build status
[14:28:07.478] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:07.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:181:0) after 1m0s
[14:29:07.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:181:0)
[14:29:07.661] Timer tick, asking Buildbucket for the build status
[14:29:07.782] Build 8863568130556194880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:07.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028998192597094352:182:0) after 1m0s
[14:29:45.273] Received PubSub notification, asking Buildbucket for the build status
[14:29:45.336] Build 8863568130556194880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:29:45.336] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1605450583577224",
    "created_by": "project:infra",
    "created_ts": "1605439744379754",
    "id": "8863568130556194880",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4326.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"794bec38-08f0-4161-bed5-5c572304b928\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4326.0\"}}",
    "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-ybm5\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1605439807245859",
    "status": "COMPLETED",
    "status_changed_ts": "1605450584431954",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9028998192597094352",
      "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/8863568130556194880/+/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:4fe47c0175be9b10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1605450584587063",
    "url": "https://ci.chromium.org/b/8863568130556194880",
    "utcnow_ts": "1605450585328922"
  }
}
[14:29:45.336] Invocation finished in 3h0m42.287293668s with status SUCCEEDED