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

• parent_buildername:publish_tarball
• user_agent:recipe

[02:17:12.456] New invocation is queued and will start shortly
[02:17:12.456] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[02:17:13.537] Starting the invocation (attempt 1)
[02:17:13.581] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:17:13.581] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:17:13.581] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9037458429464527568",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"84.0.4147.127\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"b478dc05-a023-44e9-ae75-fb063470d20f\",\"title\":\"publish_tarball/0\"}]},\"version\":\"84.0.4147.127\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9037458429464527568",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[02:17:14.505] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1597371433694682",
    "id": "8872028367500959872",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4147.127\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b478dc05-a023-44e9-ae75-fb063470d20f\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4147.127\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1597371434231276",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9037458429464527568",
      "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/8872028367500959872/+/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": "1597371434231451",
    "url": "https://ci.chromium.org/b/8872028367500959872",
    "utcnow_ts": "1597371434485497"
  }
}
[02:17:14.505] Task URL: https://ci.chromium.org/b/8872028367500959872
[02:17:14.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:2:0) after 1m0s
[02:18:02.837] Received PubSub notification, asking Buildbucket for the build status
[02:18:03.213] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:14.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:2:0)
[02:18:14.523] Timer tick, asking Buildbucket for the build status
[02:18:14.601] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:14.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:4:0) after 1m0s
[02:19:14.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:4:0)
[02:19:14.643] Timer tick, asking Buildbucket for the build status
[02:19:14.802] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:14.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:5:0) after 1m0s
[02:20:14.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:5:0)
[02:20:14.844] Timer tick, asking Buildbucket for the build status
[02:20:14.930] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:14.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:6:0) after 1m0s
[02:21:14.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:6:0)
[02:21:14.949] Timer tick, asking Buildbucket for the build status
[02:21:15.160] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:15.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:7:0) after 1m0s
[02:22:15.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:7:0)
[02:22:15.378] Timer tick, asking Buildbucket for the build status
[02:22:15.472] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:15.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:8:0) after 1m0s
[02:23:15.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:8:0)
[02:23:15.615] Timer tick, asking Buildbucket for the build status
[02:23:15.756] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:15.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:9:0) after 1m0s
[02:24:15.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:9:0)
[02:24:15.809] Timer tick, asking Buildbucket for the build status
[02:24:15.923] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:15.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:10:0) after 1m0s
[02:25:16.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:10:0)
[02:25:16.089] Timer tick, asking Buildbucket for the build status
[02:25:16.198] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:16.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:11:0) after 1m0s
[02:26:16.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:11:0)
[02:26:16.221] Timer tick, asking Buildbucket for the build status
[02:26:16.307] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:16.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:12:0) after 1m0s
[02:27:16.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:12:0)
[02:27:16.394] Timer tick, asking Buildbucket for the build status
[02:27:16.536] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:16.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:13:0) after 1m0s
[02:28:16.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:13:0)
[02:28:16.573] Timer tick, asking Buildbucket for the build status
[02:28:16.849] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:16.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:14:0) after 1m0s
[02:29:17.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:14:0)
[02:29:17.048] Timer tick, asking Buildbucket for the build status
[02:29:18.159] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:18.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:15:0) after 1m0s
[02:30:18.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:15:0)
[02:30:18.424] Timer tick, asking Buildbucket for the build status
[02:30:18.527] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:18.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:16:0) after 1m0s
[02:31:18.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:16:0)
[02:31:18.633] Timer tick, asking Buildbucket for the build status
[02:31:18.751] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:18.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:17:0) after 1m0s
[02:32:18.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:17:0)
[02:32:18.777] Timer tick, asking Buildbucket for the build status
[02:32:18.930] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:18.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:18:0) after 1m0s
[02:33:18.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:18:0)
[02:33:18.988] Timer tick, asking Buildbucket for the build status
[02:33:19.044] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:19.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:19:0) after 1m0s
[02:34:19.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:19:0)
[02:34:19.064] Timer tick, asking Buildbucket for the build status
[02:34:19.248] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:19.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:20:0) after 1m0s
[02:35:19.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:20:0)
[02:35:19.293] Timer tick, asking Buildbucket for the build status
[02:35:19.367] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:19.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:21:0) after 1m0s
[02:36:19.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:21:0)
[02:36:19.385] Timer tick, asking Buildbucket for the build status
[02:36:19.467] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:19.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:22:0) after 1m0s
[02:37:19.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:22:0)
[02:37:19.510] Timer tick, asking Buildbucket for the build status
[02:37:19.726] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:19.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:23:0) after 1m0s
[02:38:19.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:23:0)
[02:38:19.719] Timer tick, asking Buildbucket for the build status
[02:38:19.826] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:19.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:24:0) after 1m0s
[02:39:19.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:24:0)
[02:39:19.899] Timer tick, asking Buildbucket for the build status
[02:39:20.032] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:20.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:25:0) after 1m0s
[02:40:20.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:25:0)
[02:40:20.108] Timer tick, asking Buildbucket for the build status
[02:40:20.238] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:20.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:26:0) after 1m0s
[02:41:20.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:26:0)
[02:41:20.291] Timer tick, asking Buildbucket for the build status
[02:41:20.362] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:20.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:27:0) after 1m0s
[02:42:20.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:27:0)
[02:42:20.475] Timer tick, asking Buildbucket for the build status
[02:42:20.643] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:20.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:28:0) after 1m0s
[02:43:20.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:28:0)
[02:43:20.665] Timer tick, asking Buildbucket for the build status
[02:43:20.926] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:20.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:29:0) after 1m0s
[02:44:20.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:29:0)
[02:44:20.934] Timer tick, asking Buildbucket for the build status
[02:44:21.227] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:21.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:30:0) after 1m0s
[02:45:21.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:30:0)
[02:45:21.257] Timer tick, asking Buildbucket for the build status
[02:45:21.384] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:21.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:31:0) after 1m0s
[02:46:21.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:31:0)
[02:46:21.465] Timer tick, asking Buildbucket for the build status
[02:46:21.569] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:21.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:32:0) after 1m0s
[02:47:21.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:32:0)
[02:47:21.829] Timer tick, asking Buildbucket for the build status
[02:47:22.042] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:22.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:33:0) after 1m0s
[02:48:22.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:33:0)
[02:48:22.112] Timer tick, asking Buildbucket for the build status
[02:48:22.374] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:22.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:34:0) after 1m0s
[02:49:22.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:34:0)
[02:49:22.451] Timer tick, asking Buildbucket for the build status
[02:49:22.538] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:22.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:35:0) after 1m0s
[02:50:22.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:35:0)
[02:50:22.623] Timer tick, asking Buildbucket for the build status
[02:50:22.706] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:22.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:36:0) after 1m0s
[02:51:22.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:36:0)
[02:51:22.726] Timer tick, asking Buildbucket for the build status
[02:51:22.857] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:22.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:37:0) after 1m0s
[02:52:22.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:37:0)
[02:52:22.870] Timer tick, asking Buildbucket for the build status
[02:52:23.032] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:23.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:38:0) after 1m0s
[02:53:23.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:38:0)
[02:53:23.150] Timer tick, asking Buildbucket for the build status
[02:53:23.526] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:23.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:39:0) after 1m0s
[02:54:23.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:39:0)
[02:54:23.563] Timer tick, asking Buildbucket for the build status
[02:54:23.677] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:23.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:40:0) after 1m0s
[02:55:23.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:40:0)
[02:55:23.700] Timer tick, asking Buildbucket for the build status
[02:55:24.102] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:24.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:41:0) after 1m0s
[02:56:24.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:41:0)
[02:56:24.133] Timer tick, asking Buildbucket for the build status
[02:56:24.191] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:24.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:42:0) after 1m0s
[02:57:24.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:42:0)
[02:57:24.312] Timer tick, asking Buildbucket for the build status
[02:57:24.461] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:24.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:43:0) after 1m0s
[02:58:24.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:43:0)
[02:58:24.558] Timer tick, asking Buildbucket for the build status
[02:58:24.636] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:24.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:44:0) after 1m0s
[02:59:24.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:44:0)
[02:59:24.924] Timer tick, asking Buildbucket for the build status
[02:59:25.019] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:25.020] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:45:0) after 1m0s
[03:00:25.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:45:0)
[03:00:25.339] Timer tick, asking Buildbucket for the build status
[03:00:25.435] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:25.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:46:0) after 1m0s
[03:01:25.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:46:0)
[03:01:25.717] Timer tick, asking Buildbucket for the build status
[03:01:25.790] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:25.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:47:0) after 1m0s
[03:02:26.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:47:0)
[03:02:26.062] Timer tick, asking Buildbucket for the build status
[03:02:26.197] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:26.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:48:0) after 1m0s
[03:03:26.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:48:0)
[03:03:26.384] Timer tick, asking Buildbucket for the build status
[03:03:26.512] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:26.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:49:0) after 1m0s
[03:04:26.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:49:0)
[03:04:26.549] Timer tick, asking Buildbucket for the build status
[03:04:26.848] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:26.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:50:0) after 1m0s
[03:05:27.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:50:0)
[03:05:27.514] Timer tick, asking Buildbucket for the build status
[03:05:27.726] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:27.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:51:0) after 1m0s
[03:06:27.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:51:0)
[03:06:27.803] Timer tick, asking Buildbucket for the build status
[03:06:27.912] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:27.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:52:0) after 1m0s
[03:07:28.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:52:0)
[03:07:28.029] Timer tick, asking Buildbucket for the build status
[03:07:28.276] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:28.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:53:0) after 1m0s
[03:08:28.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:53:0)
[03:08:28.290] Timer tick, asking Buildbucket for the build status
[03:08:28.599] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:28.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:54:0) after 1m0s
[03:09:28.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:54:0)
[03:09:28.667] Timer tick, asking Buildbucket for the build status
[03:09:28.783] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:28.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:55:0) after 1m0s
[03:10:28.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:55:0)
[03:10:28.841] Timer tick, asking Buildbucket for the build status
[03:10:28.926] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:28.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:56:0) after 1m0s
[03:11:29.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:56:0)
[03:11:29.008] Timer tick, asking Buildbucket for the build status
[03:11:29.196] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:29.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:57:0) after 1m0s
[03:12:29.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:57:0)
[03:12:29.217] Timer tick, asking Buildbucket for the build status
[03:12:29.349] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:29.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:58:0) after 1m0s
[03:13:29.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:58:0)
[03:13:29.409] Timer tick, asking Buildbucket for the build status
[03:13:29.475] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:29.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:59:0) after 1m0s
[03:14:29.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:59:0)
[03:14:29.534] Timer tick, asking Buildbucket for the build status
[03:14:29.918] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:29.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:60:0) after 1m0s
[03:15:29.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:60:0)
[03:15:29.966] Timer tick, asking Buildbucket for the build status
[03:15:30.654] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:30.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:61:0) after 1m0s
[03:16:30.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:61:0)
[03:16:30.709] Timer tick, asking Buildbucket for the build status
[03:16:33.179] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:33.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:62:0) after 1m0s
[03:17:33.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:62:0)
[03:17:33.180] Timer tick, asking Buildbucket for the build status
[03:17:33.305] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:33.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:63:0) after 1m0s
[03:18:33.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:63:0)
[03:18:33.356] Timer tick, asking Buildbucket for the build status
[03:18:33.440] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:33.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:64:0) after 1m0s
[03:19:33.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:64:0)
[03:19:33.490] Timer tick, asking Buildbucket for the build status
[03:19:33.628] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:33.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:65:0) after 1m0s
[03:20:33.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:65:0)
[03:20:33.649] Timer tick, asking Buildbucket for the build status
[03:20:33.727] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:33.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:66:0) after 1m0s
[03:21:33.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:66:0)
[03:21:33.763] Timer tick, asking Buildbucket for the build status
[03:21:33.921] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:33.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:67:0) after 1m0s
[03:22:33.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:67:0)
[03:22:33.944] Timer tick, asking Buildbucket for the build status
[03:22:34.242] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:34.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:68:0) after 1m0s
[03:23:34.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:68:0)
[03:23:34.293] Timer tick, asking Buildbucket for the build status
[03:23:34.508] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:34.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:69:0) after 1m0s
[03:24:34.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:69:0)
[03:24:34.592] Timer tick, asking Buildbucket for the build status
[03:24:34.695] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:34.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:70:0) after 1m0s
[03:25:34.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:70:0)
[03:25:34.759] Timer tick, asking Buildbucket for the build status
[03:25:34.841] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:34.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:71:0) after 1m0s
[03:26:34.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:71:0)
[03:26:34.889] Timer tick, asking Buildbucket for the build status
[03:26:35.044] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:35.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:72:0) after 1m0s
[03:27:35.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:72:0)
[03:27:35.088] Timer tick, asking Buildbucket for the build status
[03:27:35.296] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:35.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:73:0) after 1m0s
[03:28:35.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:73:0)
[03:28:35.344] Timer tick, asking Buildbucket for the build status
[03:28:35.432] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:35.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:74:0) after 1m0s
[03:29:35.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:74:0)
[03:29:35.438] Timer tick, asking Buildbucket for the build status
[03:29:35.559] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:35.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:75:0) after 1m0s
[03:30:35.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:75:0)
[03:30:35.587] Timer tick, asking Buildbucket for the build status
[03:30:35.664] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:35.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:76:0) after 1m0s
[03:31:35.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:76:0)
[03:31:35.650] Timer tick, asking Buildbucket for the build status
[03:31:35.923] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:35.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:77:0) after 1m0s
[03:32:35.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:77:0)
[03:32:35.947] Timer tick, asking Buildbucket for the build status
[03:32:36.512] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:36.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:78:0) after 1m0s
[03:33:36.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:78:0)
[03:33:36.852] Timer tick, asking Buildbucket for the build status
[03:33:37.062] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:37.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:79:0) after 1m0s
[03:34:37.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:79:0)
[03:34:37.265] Timer tick, asking Buildbucket for the build status
[03:34:37.351] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:37.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:80:0) after 1m0s
[03:35:37.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:80:0)
[03:35:37.377] Timer tick, asking Buildbucket for the build status
[03:35:37.512] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:37.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:81:0) after 1m0s
[03:36:37.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:81:0)
[03:36:37.604] Timer tick, asking Buildbucket for the build status
[03:36:38.354] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:38.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:82:0) after 1m0s
[03:37:38.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:82:0)
[03:37:38.499] Timer tick, asking Buildbucket for the build status
[03:37:38.656] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:38.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:83:0) after 1m0s
[03:38:38.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:83:0)
[03:38:38.880] Timer tick, asking Buildbucket for the build status
[03:38:38.939] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:38.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:84:0) after 1m0s
[03:39:39.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:84:0)
[03:39:39.056] Timer tick, asking Buildbucket for the build status
[03:39:39.208] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:39.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:85:0) after 1m0s
[03:40:39.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:85:0)
[03:40:39.316] Timer tick, asking Buildbucket for the build status
[03:40:39.427] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:39.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:86:0) after 1m0s
[03:41:39.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:86:0)
[03:41:39.515] Timer tick, asking Buildbucket for the build status
[03:41:39.580] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:39.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:87:0) after 1m0s
[03:42:39.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:87:0)
[03:42:39.738] Timer tick, asking Buildbucket for the build status
[03:42:39.872] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:39.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:88:0) after 1m0s
[03:43:40.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:88:0)
[03:43:40.035] Timer tick, asking Buildbucket for the build status
[03:43:40.123] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:40.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:89:0) after 1m0s
[03:44:40.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:89:0)
[03:44:40.189] Timer tick, asking Buildbucket for the build status
[03:44:40.310] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:40.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:90:0) after 1m0s
[03:45:40.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:90:0)
[03:45:40.332] Timer tick, asking Buildbucket for the build status
[03:45:40.451] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:40.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:91:0) after 1m0s
[03:46:40.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:91:0)
[03:46:40.513] Timer tick, asking Buildbucket for the build status
[03:46:40.643] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:40.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:92:0) after 1m0s
[03:47:40.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:92:0)
[03:47:40.715] Timer tick, asking Buildbucket for the build status
[03:47:40.817] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:40.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:93:0) after 1m0s
[03:48:40.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:93:0)
[03:48:40.870] Timer tick, asking Buildbucket for the build status
[03:48:41.054] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:41.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:94:0) after 1m0s
[03:49:41.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:94:0)
[03:49:41.118] Timer tick, asking Buildbucket for the build status
[03:49:41.257] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:41.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:95:0) after 1m0s
[03:50:41.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:95:0)
[03:50:41.322] Timer tick, asking Buildbucket for the build status
[03:50:41.420] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:41.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:96:0) after 1m0s
[03:51:41.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:96:0)
[03:51:41.447] Timer tick, asking Buildbucket for the build status
[03:51:41.523] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:41.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:97:0) after 1m0s
[03:52:42.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:97:0)
[03:52:42.400] Timer tick, asking Buildbucket for the build status
[03:52:42.560] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:42.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:98:0) after 1m0s
[03:53:42.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:98:0)
[03:53:42.787] Timer tick, asking Buildbucket for the build status
[03:53:43.071] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:43.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:99:0) after 1m0s
[03:54:43.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:99:0)
[03:54:43.099] Timer tick, asking Buildbucket for the build status
[03:54:43.309] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:43.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:100:0) after 1m0s
[03:55:43.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:100:0)
[03:55:43.360] Timer tick, asking Buildbucket for the build status
[03:55:43.482] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:43.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:101:0) after 1m0s
[03:56:43.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:101:0)
[03:56:43.539] Timer tick, asking Buildbucket for the build status
[03:56:43.601] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:43.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:102:0) after 1m0s
[03:57:43.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:102:0)
[03:57:43.592] Timer tick, asking Buildbucket for the build status
[03:57:43.850] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:43.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:103:0) after 1m0s
[03:58:43.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:103:0)
[03:58:43.871] Timer tick, asking Buildbucket for the build status
[03:58:44.084] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:44.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:104:0) after 1m0s
[03:59:44.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:104:0)
[03:59:44.143] Timer tick, asking Buildbucket for the build status
[03:59:44.219] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:44.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:105:0) after 1m0s
[04:00:44.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:105:0)
[04:00:44.236] Timer tick, asking Buildbucket for the build status
[04:00:44.328] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:44.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:106:0) after 1m0s
[04:01:44.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:106:0)
[04:01:44.448] Timer tick, asking Buildbucket for the build status
[04:01:44.914] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:44.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:107:0) after 1m0s
[04:02:44.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:107:0)
[04:02:44.951] Timer tick, asking Buildbucket for the build status
[04:02:45.118] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:45.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:108:0) after 1m0s
[04:03:45.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:108:0)
[04:03:45.123] Timer tick, asking Buildbucket for the build status
[04:03:45.219] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:45.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:109:0) after 1m0s
[04:04:45.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:109:0)
[04:04:45.276] Timer tick, asking Buildbucket for the build status
[04:04:45.344] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:45.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:110:0) after 1m0s
[04:05:45.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:110:0)
[04:05:45.514] Timer tick, asking Buildbucket for the build status
[04:05:45.593] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:45.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:111:0) after 1m0s
[04:06:45.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:111:0)
[04:06:45.678] Timer tick, asking Buildbucket for the build status
[04:06:46.458] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:46.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:112:0) after 1m0s
[04:07:46.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:112:0)
[04:07:46.541] Timer tick, asking Buildbucket for the build status
[04:07:46.764] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:46.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:113:0) after 1m0s
[04:08:47.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:113:0)
[04:08:47.114] Timer tick, asking Buildbucket for the build status
[04:08:47.298] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:47.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:114:0) after 1m0s
[04:09:47.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:114:0)
[04:09:47.394] Timer tick, asking Buildbucket for the build status
[04:09:47.479] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:47.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:115:0) after 1m0s
[04:10:47.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:115:0)
[04:10:47.593] Timer tick, asking Buildbucket for the build status
[04:10:47.664] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:47.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:116:0) after 1m0s
[04:11:47.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:116:0)
[04:11:47.750] Timer tick, asking Buildbucket for the build status
[04:11:47.931] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:47.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:117:0) after 1m0s
[04:12:47.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:117:0)
[04:12:47.959] Timer tick, asking Buildbucket for the build status
[04:12:48.262] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:48.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:118:0) after 1m0s
[04:13:48.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:118:0)
[04:13:48.367] Timer tick, asking Buildbucket for the build status
[04:13:48.485] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:48.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:119:0) after 1m0s
[04:14:48.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:119:0)
[04:14:48.555] Timer tick, asking Buildbucket for the build status
[04:14:48.636] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:48.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:120:0) after 1m0s
[04:15:48.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:120:0)
[04:15:48.731] Timer tick, asking Buildbucket for the build status
[04:15:48.793] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:48.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:121:0) after 1m0s
[04:16:48.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:121:0)
[04:16:48.883] Timer tick, asking Buildbucket for the build status
[04:16:48.988] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:48.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:122:0) after 1m0s
[04:17:49.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:122:0)
[04:17:49.018] Timer tick, asking Buildbucket for the build status
[04:17:49.167] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:49.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:123:0) after 1m0s
[04:18:49.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:123:0)
[04:18:49.185] Timer tick, asking Buildbucket for the build status
[04:18:49.576] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:49.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:124:0) after 1m0s
[04:19:49.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:124:0)
[04:19:49.701] Timer tick, asking Buildbucket for the build status
[04:19:50.013] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:50.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:125:0) after 1m0s
[04:20:50.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:125:0)
[04:20:50.039] Timer tick, asking Buildbucket for the build status
[04:20:50.578] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:50.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:126:0) after 1m0s
[04:21:50.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:126:0)
[04:21:50.682] Timer tick, asking Buildbucket for the build status
[04:21:50.769] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:50.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:127:0) after 1m0s
[04:22:50.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:127:0)
[04:22:50.839] Timer tick, asking Buildbucket for the build status
[04:22:50.962] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:50.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:128:0) after 1m0s
[04:23:51.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:128:0)
[04:23:51.068] Timer tick, asking Buildbucket for the build status
[04:23:51.162] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:51.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:129:0) after 1m0s
[04:24:51.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:129:0)
[04:24:51.286] Timer tick, asking Buildbucket for the build status
[04:24:51.510] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:51.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:130:0) after 1m0s
[04:25:51.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:130:0)
[04:25:51.532] Timer tick, asking Buildbucket for the build status
[04:25:51.590] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:51.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:131:0) after 1m0s
[04:26:51.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:131:0)
[04:26:51.638] Timer tick, asking Buildbucket for the build status
[04:26:51.721] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:51.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:132:0) after 1m0s
[04:27:51.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:132:0)
[04:27:51.709] Timer tick, asking Buildbucket for the build status
[04:27:51.927] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:51.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:133:0) after 1m0s
[04:28:51.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:133:0)
[04:28:51.987] Timer tick, asking Buildbucket for the build status
[04:28:52.143] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:52.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:134:0) after 1m0s
[04:29:52.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:134:0)
[04:29:52.189] Timer tick, asking Buildbucket for the build status
[04:29:52.925] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:52.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:135:0) after 1m0s
[04:30:53.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:135:0)
[04:30:53.011] Timer tick, asking Buildbucket for the build status
[04:30:53.313] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:53.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:136:0) after 1m0s
[04:31:53.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:136:0)
[04:31:53.488] Timer tick, asking Buildbucket for the build status
[04:31:53.628] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:53.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:137:0) after 1m0s
[04:32:53.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:137:0)
[04:32:53.668] Timer tick, asking Buildbucket for the build status
[04:32:54.033] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:54.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:138:0) after 1m0s
[04:33:54.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:138:0)
[04:33:54.084] Timer tick, asking Buildbucket for the build status
[04:33:54.173] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:54.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:139:0) after 1m0s
[04:34:54.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:139:0)
[04:34:54.318] Timer tick, asking Buildbucket for the build status
[04:34:54.385] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:54.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:140:0) after 1m0s
[04:35:54.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:140:0)
[04:35:54.469] Timer tick, asking Buildbucket for the build status
[04:35:54.572] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:54.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:141:0) after 1m0s
[04:36:54.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:141:0)
[04:36:54.617] Timer tick, asking Buildbucket for the build status
[04:36:55.039] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:55.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:142:0) after 1m0s
[04:37:55.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:142:0)
[04:37:55.168] Timer tick, asking Buildbucket for the build status
[04:37:55.482] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:55.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:143:0) after 1m0s
[04:38:55.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:143:0)
[04:38:55.551] Timer tick, asking Buildbucket for the build status
[04:38:55.837] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:55.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:144:0) after 1m0s
[04:39:55.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:144:0)
[04:39:55.864] Timer tick, asking Buildbucket for the build status
[04:39:55.970] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:55.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:145:0) after 1m0s
[04:40:56.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:145:0)
[04:40:56.066] Timer tick, asking Buildbucket for the build status
[04:40:56.172] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:56.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:146:0) after 1m0s
[04:41:56.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:146:0)
[04:41:56.236] Timer tick, asking Buildbucket for the build status
[04:41:56.386] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:56.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:147:0) after 1m0s
[04:42:56.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:147:0)
[04:42:56.422] Timer tick, asking Buildbucket for the build status
[04:42:57.306] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:57.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:148:0) after 1m0s
[04:43:57.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:148:0)
[04:43:57.344] Timer tick, asking Buildbucket for the build status
[04:43:57.428] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:57.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:149:0) after 1m0s
[04:44:57.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:149:0)
[04:44:57.425] Timer tick, asking Buildbucket for the build status
[04:44:57.476] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:57.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:150:0) after 1m0s
[04:45:57.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:150:0)
[04:45:57.514] Timer tick, asking Buildbucket for the build status
[04:45:57.595] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:57.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:151:0) after 1m0s
[04:46:57.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:151:0)
[04:46:57.620] Timer tick, asking Buildbucket for the build status
[04:46:57.681] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:57.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:152:0) after 1m0s
[04:47:57.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:152:0)
[04:47:57.780] Timer tick, asking Buildbucket for the build status
[04:47:57.935] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:57.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:153:0) after 1m0s
[04:48:57.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:153:0)
[04:48:57.956] Timer tick, asking Buildbucket for the build status
[04:48:58.408] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:58.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:154:0) after 1m0s
[04:49:58.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:154:0)
[04:49:58.439] Timer tick, asking Buildbucket for the build status
[04:49:58.567] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:58.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:155:0) after 1m0s
[04:50:58.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:155:0)
[04:50:58.591] Timer tick, asking Buildbucket for the build status
[04:50:58.703] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:58.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:156:0) after 1m0s
[04:51:58.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:156:0)
[04:51:58.726] Timer tick, asking Buildbucket for the build status
[04:51:58.799] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:58.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:157:0) after 1m0s
[04:52:58.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:157:0)
[04:52:58.854] Timer tick, asking Buildbucket for the build status
[04:52:59.030] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:59.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:158:0) after 1m0s
[04:53:59.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:158:0)
[04:53:59.081] Timer tick, asking Buildbucket for the build status
[04:53:59.201] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:59.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:159:0) after 1m0s
[04:54:59.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:159:0)
[04:54:59.235] Timer tick, asking Buildbucket for the build status
[04:54:59.573] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:59.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:160:0) after 1m0s
[04:55:59.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:160:0)
[04:55:59.599] Timer tick, asking Buildbucket for the build status
[04:55:59.673] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:59.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:161:0) after 1m0s
[04:56:59.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:161:0)
[04:56:59.720] Timer tick, asking Buildbucket for the build status
[04:56:59.790] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:59.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:162:0) after 1m0s
[04:57:59.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:162:0)
[04:57:59.842] Timer tick, asking Buildbucket for the build status
[04:57:59.988] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:59.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:163:0) after 1m0s
[04:59:00.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:163:0)
[04:59:00.025] Timer tick, asking Buildbucket for the build status
[04:59:00.202] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:00.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:164:0) after 1m0s
[05:00:00.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:164:0)
[05:00:00.276] Timer tick, asking Buildbucket for the build status
[05:00:00.363] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:00.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:165:0) after 1m0s
[05:01:00.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:165:0)
[05:01:00.435] Timer tick, asking Buildbucket for the build status
[05:01:00.611] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:00.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:166:0) after 1m0s
[05:02:00.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:166:0)
[05:02:00.986] Timer tick, asking Buildbucket for the build status
[05:02:01.109] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:01.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:167:0) after 1m0s
[05:03:01.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:167:0)
[05:03:01.529] Timer tick, asking Buildbucket for the build status
[05:03:01.664] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:01.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:168:0) after 1m0s
[05:04:01.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:168:0)
[05:04:01.786] Timer tick, asking Buildbucket for the build status
[05:04:01.853] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:01.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:169:0) after 1m0s
[05:05:02.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:169:0)
[05:05:02.992] Timer tick, asking Buildbucket for the build status
[05:05:03.073] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:03.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:170:0) after 1m0s
[05:06:03.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:170:0)
[05:06:03.116] Timer tick, asking Buildbucket for the build status
[05:06:03.192] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:03.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:171:0) after 1m0s
[05:07:03.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:171:0)
[05:07:03.404] Timer tick, asking Buildbucket for the build status
[05:07:03.483] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:03.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:172:0) after 1m0s
[05:08:03.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:172:0)
[05:08:03.627] Timer tick, asking Buildbucket for the build status
[05:08:03.773] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:03.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:173:0) after 1m0s
[05:09:03.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:173:0)
[05:09:03.927] Timer tick, asking Buildbucket for the build status
[05:09:03.990] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:03.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:174:0) after 1m0s
[05:10:04.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:174:0)
[05:10:04.029] Timer tick, asking Buildbucket for the build status
[05:10:04.099] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:04.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:175:0) after 1m0s
[05:11:04.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:175:0)
[05:11:04.192] Timer tick, asking Buildbucket for the build status
[05:11:04.323] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:04.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:176:0) after 1m0s
[05:12:04.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:176:0)
[05:12:04.337] Timer tick, asking Buildbucket for the build status
[05:12:04.497] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:04.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:177:0) after 1m0s
[05:13:04.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:177:0)
[05:13:04.548] Timer tick, asking Buildbucket for the build status
[05:13:05.886] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:05.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:178:0) after 1m0s
[05:14:05.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:178:0)
[05:14:05.895] Timer tick, asking Buildbucket for the build status
[05:14:06.130] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:06.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:179:0) after 1m0s
[05:15:06.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:179:0)
[05:15:06.210] Timer tick, asking Buildbucket for the build status
[05:15:06.426] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:06.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:180:0) after 1m0s
[05:16:06.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:180:0)
[05:16:06.473] Timer tick, asking Buildbucket for the build status
[05:16:06.706] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:06.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:181:0) after 1m0s
[05:17:06.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:181:0)
[05:17:06.708] Timer tick, asking Buildbucket for the build status
[05:17:06.803] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:06.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:182:0) after 1m0s
[05:18:06.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:182:0)
[05:18:06.792] Timer tick, asking Buildbucket for the build status
[05:18:06.939] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:06.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:183:0) after 1m0s
[05:19:06.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:183:0)
[05:19:06.967] Timer tick, asking Buildbucket for the build status
[05:19:07.091] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:07.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:184:0) after 1m0s
[05:20:07.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:184:0)
[05:20:07.133] Timer tick, asking Buildbucket for the build status
[05:20:07.277] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:07.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:185:0) after 1m0s
[05:21:07.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:185:0)
[05:21:07.335] Timer tick, asking Buildbucket for the build status
[05:21:07.422] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:07.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:186:0) after 1m0s
[05:22:07.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:186:0)
[05:22:07.446] Timer tick, asking Buildbucket for the build status
[05:22:07.566] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:07.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:187:0) after 1m0s
[05:23:07.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:187:0)
[05:23:07.580] Timer tick, asking Buildbucket for the build status
[05:23:08.031] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:08.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:188:0) after 1m0s
[05:24:08.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:188:0)
[05:24:08.030] Timer tick, asking Buildbucket for the build status
[05:24:08.131] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:08.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:189:0) after 1m0s
[05:25:08.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:189:0)
[05:25:08.189] Timer tick, asking Buildbucket for the build status
[05:25:08.352] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:08.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:190:0) after 1m0s
[05:26:08.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:190:0)
[05:26:08.408] Timer tick, asking Buildbucket for the build status
[05:26:08.619] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:08.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:191:0) after 1m0s
[05:27:08.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:191:0)
[05:27:08.692] Timer tick, asking Buildbucket for the build status
[05:27:09.139] Build 8872028367500959872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:09.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9037458429464527568:192:0) after 1m0s
[05:27:30.148] Received PubSub notification, asking Buildbucket for the build status
[05:27:30.256] Build 8872028367500959872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:27:30.256] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1597382849284236",
    "created_by": "project:infra",
    "created_ts": "1597371433694682",
    "id": "8872028367500959872",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"84.0.4147.127\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b478dc05-a023-44e9-ae75-fb063470d20f\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"84.0.4147.127\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-iejw\"], \"image\": [\"chrome-xenial-20081200-b8de48ff3cf\"], \"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\": [\"5486-569c017\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1597371481692924",
    "status": "COMPLETED",
    "status_changed_ts": "1597382849746689",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9037458429464527568",
      "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/8872028367500959872/+/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:4e03934e27c59910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1597382849772404",
    "url": "https://ci.chromium.org/b/8872028367500959872",
    "utcnow_ts": "1597382850248533"
  }
}
[05:27:30.256] Invocation finished in 3h10m17.818569907s with status SUCCEEDED