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

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

{
  "version": "85.0.4183.76"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[20:16:20.120] New invocation is queued and will start shortly
[20:16:20.120] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[20:16:21.209] Starting the invocation (attempt 1)
[20:16:21.312] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:16:21.312] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:16:21.312] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9036937551786310464",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4183.76\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"bf9d3924-e309-45ba-a359-b63cb3cadfed\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4183.76\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9036937551786310464",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[20:16:21.705] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1597868181396982",
    "id": "8871507489782579200",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4183.76\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"bf9d3924-e309-45ba-a359-b63cb3cadfed\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4183.76\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1597868181591447",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036937551786310464",
      "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/8871507489782579200/+/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": "1597868181591569",
    "url": "https://ci.chromium.org/b/8871507489782579200",
    "utcnow_ts": "1597868181696512"
  }
}
[20:16:21.705] Task URL: https://ci.chromium.org/b/8871507489782579200
[20:16:21.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:2:0) after 1m0s
[20:17:21.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:2:0)
[20:17:21.725] Timer tick, asking Buildbucket for the build status
[20:17:21.921] Build 8871507489782579200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:21.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:3:0) after 1m0s
[20:17:22.158] Received PubSub notification, asking Buildbucket for the build status
[20:17:22.278] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:22.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:3:0)
[20:18:22.020] Timer tick, asking Buildbucket for the build status
[20:18:22.172] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:22.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:5:0) after 1m0s
[20:19:22.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:5:0)
[20:19:22.213] Timer tick, asking Buildbucket for the build status
[20:19:22.306] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:22.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:6:0) after 1m0s
[20:20:22.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:6:0)
[20:20:22.361] Timer tick, asking Buildbucket for the build status
[20:20:22.498] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:22.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:7:0) after 1m0s
[20:21:22.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:7:0)
[20:21:22.535] Timer tick, asking Buildbucket for the build status
[20:21:22.607] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:22.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:8:0) after 1m0s
[20:22:22.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:8:0)
[20:22:22.664] Timer tick, asking Buildbucket for the build status
[20:22:22.789] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:22.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:9:0) after 1m0s
[20:23:22.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:9:0)
[20:23:22.830] Timer tick, asking Buildbucket for the build status
[20:23:23.128] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:23.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:10:0) after 1m0s
[20:24:23.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:10:0)
[20:24:23.161] Timer tick, asking Buildbucket for the build status
[20:24:23.222] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:23.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:11:0) after 1m0s
[20:25:23.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:11:0)
[20:25:23.351] Timer tick, asking Buildbucket for the build status
[20:25:23.455] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:23.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:12:0) after 1m0s
[20:26:23.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:12:0)
[20:26:23.791] Timer tick, asking Buildbucket for the build status
[20:26:23.890] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:23.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:13:0) after 1m0s
[20:27:23.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:13:0)
[20:27:23.918] Timer tick, asking Buildbucket for the build status
[20:27:24.156] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:24.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:14:0) after 1m0s
[20:28:24.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:14:0)
[20:28:24.368] Timer tick, asking Buildbucket for the build status
[20:28:24.604] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:24.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:15:0) after 1m0s
[20:29:24.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:15:0)
[20:29:24.752] Timer tick, asking Buildbucket for the build status
[20:29:25.830] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:25.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:16:0) after 1m0s
[20:30:25.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:16:0)
[20:30:25.996] Timer tick, asking Buildbucket for the build status
[20:30:26.934] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:26.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:17:0) after 1m0s
[20:31:27.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:17:0)
[20:31:27.077] Timer tick, asking Buildbucket for the build status
[20:31:27.827] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:27.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:18:0) after 1m0s
[20:32:27.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:18:0)
[20:32:27.889] Timer tick, asking Buildbucket for the build status
[20:32:28.087] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:28.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:19:0) after 1m0s
[20:33:28.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:19:0)
[20:33:28.903] Timer tick, asking Buildbucket for the build status
[20:33:28.969] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:28.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:20:0) after 1m0s
[20:34:29.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:20:0)
[20:34:29.286] Timer tick, asking Buildbucket for the build status
[20:34:29.403] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:29.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:21:0) after 1m0s
[20:35:29.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:21:0)
[20:35:29.574] Timer tick, asking Buildbucket for the build status
[20:35:29.670] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:29.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:22:0) after 1m0s
[20:36:30.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:22:0)
[20:36:30.139] Timer tick, asking Buildbucket for the build status
[20:36:30.298] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:30.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:23:0) after 1m0s
[20:37:30.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:23:0)
[20:37:30.926] Timer tick, asking Buildbucket for the build status
[20:37:30.988] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:30.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:24:0) after 1m0s
[20:38:31.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:24:0)
[20:38:31.147] Timer tick, asking Buildbucket for the build status
[20:38:31.220] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:31.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:25:0) after 1m0s
[20:39:31.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:25:0)
[20:39:31.326] Timer tick, asking Buildbucket for the build status
[20:39:31.604] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:31.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:26:0) after 1m0s
[20:40:32.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:26:0)
[20:40:32.045] Timer tick, asking Buildbucket for the build status
[20:40:32.102] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:32.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:27:0) after 1m0s
[20:41:32.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:27:0)
[20:41:32.307] Timer tick, asking Buildbucket for the build status
[20:41:32.555] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:32.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:28:0) after 1m0s
[20:42:33.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:28:0)
[20:42:33.183] Timer tick, asking Buildbucket for the build status
[20:42:33.487] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:33.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:29:0) after 1m0s
[20:43:33.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:29:0)
[20:43:33.707] Timer tick, asking Buildbucket for the build status
[20:43:33.776] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:33.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:30:0) after 1m0s
[20:44:33.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:30:0)
[20:44:33.850] Timer tick, asking Buildbucket for the build status
[20:44:33.987] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:33.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:31:0) after 1m0s
[20:45:34.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:31:0)
[20:45:34.707] Timer tick, asking Buildbucket for the build status
[20:45:34.821] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:34.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:32:0) after 1m0s
[20:46:35.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:32:0)
[20:46:35.106] Timer tick, asking Buildbucket for the build status
[20:46:35.210] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:35.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:33:0) after 1m0s
[20:47:35.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:33:0)
[20:47:35.472] Timer tick, asking Buildbucket for the build status
[20:47:35.562] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:35.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:34:0) after 1m0s
[20:48:36.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:34:0)
[20:48:36.811] Timer tick, asking Buildbucket for the build status
[20:48:36.948] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:36.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:35:0) after 1m0s
[20:49:36.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:35:0)
[20:49:36.970] Timer tick, asking Buildbucket for the build status
[20:49:37.117] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:37.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:36:0) after 1m0s
[20:50:37.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:36:0)
[20:50:37.139] Timer tick, asking Buildbucket for the build status
[20:50:37.655] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:37.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:37:0) after 1m0s
[20:51:37.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:37:0)
[20:51:37.916] Timer tick, asking Buildbucket for the build status
[20:51:38.079] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:38.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:38:0) after 1m0s
[20:52:38.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:38:0)
[20:52:38.115] Timer tick, asking Buildbucket for the build status
[20:52:38.201] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:38.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:39:0) after 1m0s
[20:53:38.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:39:0)
[20:53:38.591] Timer tick, asking Buildbucket for the build status
[20:53:38.757] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:38.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:40:0) after 1m0s
[20:54:39.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:40:0)
[20:54:39.383] Timer tick, asking Buildbucket for the build status
[20:54:39.601] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:39.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:41:0) after 1m0s
[20:55:39.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:41:0)
[20:55:39.693] Timer tick, asking Buildbucket for the build status
[20:55:39.789] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:39.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:42:0) after 1m0s
[20:56:41.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:42:0)
[20:56:41.189] Timer tick, asking Buildbucket for the build status
[20:56:41.882] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:41.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:43:0) after 1m0s
[20:57:41.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:43:0)
[20:57:41.987] Timer tick, asking Buildbucket for the build status
[20:57:42.062] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:42.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:44:0) after 1m0s
[20:58:42.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:44:0)
[20:58:42.170] Timer tick, asking Buildbucket for the build status
[20:58:42.552] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:42.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:45:0) after 1m0s
[20:59:42.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:45:0)
[20:59:42.670] Timer tick, asking Buildbucket for the build status
[20:59:42.769] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:42.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:46:0) after 1m0s
[21:00:42.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:46:0)
[21:00:42.821] Timer tick, asking Buildbucket for the build status
[21:00:43.125] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:43.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:47:0) after 1m0s
[21:01:43.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:47:0)
[21:01:43.197] Timer tick, asking Buildbucket for the build status
[21:01:43.837] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:43.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:48:0) after 1m0s
[21:02:43.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:48:0)
[21:02:43.890] Timer tick, asking Buildbucket for the build status
[21:02:44.197] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:44.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:49:0) after 1m0s
[21:03:44.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:49:0)
[21:03:44.288] Timer tick, asking Buildbucket for the build status
[21:03:44.391] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:44.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:50:0) after 1m0s
[21:04:44.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:50:0)
[21:04:44.490] Timer tick, asking Buildbucket for the build status
[21:04:44.883] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:44.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:51:0) after 1m0s
[21:05:44.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:51:0)
[21:05:44.904] Timer tick, asking Buildbucket for the build status
[21:05:44.987] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:44.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:52:0) after 1m0s
[21:06:45.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:52:0)
[21:06:45.108] Timer tick, asking Buildbucket for the build status
[21:06:45.347] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:45.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:53:0) after 1m0s
[21:07:45.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:53:0)
[21:07:45.398] Timer tick, asking Buildbucket for the build status
[21:07:45.523] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:45.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:54:0) after 1m0s
[21:08:45.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:54:0)
[21:08:45.601] Timer tick, asking Buildbucket for the build status
[21:08:45.699] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:45.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:55:0) after 1m0s
[21:09:45.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:55:0)
[21:09:45.750] Timer tick, asking Buildbucket for the build status
[21:09:45.867] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:45.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:56:0) after 1m0s
[21:10:45.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:56:0)
[21:10:45.917] Timer tick, asking Buildbucket for the build status
[21:10:45.998] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:45.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:57:0) after 1m0s
[21:11:46.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:57:0)
[21:11:46.052] Timer tick, asking Buildbucket for the build status
[21:11:46.242] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:46.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:58:0) after 1m0s
[21:12:46.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:58:0)
[21:12:46.266] Timer tick, asking Buildbucket for the build status
[21:12:47.978] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:47.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:59:0) after 1m0s
[21:13:48.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:59:0)
[21:13:48.027] Timer tick, asking Buildbucket for the build status
[21:13:48.135] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:48.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:60:0) after 1m0s
[21:14:48.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:60:0)
[21:14:48.245] Timer tick, asking Buildbucket for the build status
[21:14:48.604] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:48.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:61:0) after 1m0s
[21:15:48.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:61:0)
[21:15:48.671] Timer tick, asking Buildbucket for the build status
[21:15:48.891] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:48.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:62:0) after 1m0s
[21:16:48.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:62:0)
[21:16:48.917] Timer tick, asking Buildbucket for the build status
[21:16:49.087] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:49.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:63:0) after 1m0s
[21:17:49.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:63:0)
[21:17:49.135] Timer tick, asking Buildbucket for the build status
[21:17:49.251] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:49.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:64:0) after 1m0s
[21:18:49.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:64:0)
[21:18:49.302] Timer tick, asking Buildbucket for the build status
[21:18:49.398] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:49.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:65:0) after 1m0s
[21:19:49.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:65:0)
[21:19:49.402] Timer tick, asking Buildbucket for the build status
[21:19:49.690] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:49.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:66:0) after 1m0s
[21:20:49.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:66:0)
[21:20:49.687] Timer tick, asking Buildbucket for the build status
[21:20:49.750] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:49.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:67:0) after 1m0s
[21:21:49.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:67:0)
[21:21:49.912] Timer tick, asking Buildbucket for the build status
[21:21:50.089] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:50.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:68:0) after 1m0s
[21:22:50.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:68:0)
[21:22:50.142] Timer tick, asking Buildbucket for the build status
[21:22:50.239] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:50.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:69:0) after 1m0s
[21:23:50.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:69:0)
[21:23:50.331] Timer tick, asking Buildbucket for the build status
[21:23:50.394] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:50.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:70:0) after 1m0s
[21:24:50.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:70:0)
[21:24:50.566] Timer tick, asking Buildbucket for the build status
[21:24:50.673] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:50.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:71:0) after 1m0s
[21:25:50.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:71:0)
[21:25:50.750] Timer tick, asking Buildbucket for the build status
[21:25:51.216] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:51.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:72:0) after 1m0s
[21:26:51.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:72:0)
[21:26:51.313] Timer tick, asking Buildbucket for the build status
[21:26:51.467] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:51.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:73:0) after 1m0s
[21:27:51.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:73:0)
[21:27:51.545] Timer tick, asking Buildbucket for the build status
[21:27:51.823] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:51.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:74:0) after 1m0s
[21:28:51.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:74:0)
[21:28:51.921] Timer tick, asking Buildbucket for the build status
[21:28:52.185] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:52.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:75:0) after 1m0s
[21:29:52.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:75:0)
[21:29:52.226] Timer tick, asking Buildbucket for the build status
[21:29:52.488] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:52.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:76:0) after 1m0s
[21:30:52.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:76:0)
[21:30:52.546] Timer tick, asking Buildbucket for the build status
[21:30:52.839] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:52.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:77:0) after 1m0s
[21:31:52.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:77:0)
[21:31:52.865] Timer tick, asking Buildbucket for the build status
[21:31:53.047] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:53.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:78:0) after 1m0s
[21:32:53.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:78:0)
[21:32:53.095] Timer tick, asking Buildbucket for the build status
[21:32:53.430] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:53.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:79:0) after 1m0s
[21:33:53.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:79:0)
[21:33:53.471] Timer tick, asking Buildbucket for the build status
[21:33:53.848] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:53.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:80:0) after 1m0s
[21:34:53.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:80:0)
[21:34:53.888] Timer tick, asking Buildbucket for the build status
[21:34:54.304] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:54.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:81:0) after 1m0s
[21:35:54.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:81:0)
[21:35:54.403] Timer tick, asking Buildbucket for the build status
[21:35:54.528] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:54.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:82:0) after 1m0s
[21:36:54.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:82:0)
[21:36:54.616] Timer tick, asking Buildbucket for the build status
[21:36:54.746] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:54.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:83:0) after 1m0s
[21:37:54.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:83:0)
[21:37:54.793] Timer tick, asking Buildbucket for the build status
[21:37:56.057] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:56.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:84:0) after 1m0s
[21:38:56.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:84:0)
[21:38:56.111] Timer tick, asking Buildbucket for the build status
[21:38:56.491] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:56.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:85:0) after 1m0s
[21:39:56.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:85:0)
[21:39:56.541] Timer tick, asking Buildbucket for the build status
[21:39:56.654] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:56.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:86:0) after 1m0s
[21:40:56.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:86:0)
[21:40:56.715] Timer tick, asking Buildbucket for the build status
[21:40:56.860] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:56.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:87:0) after 1m0s
[21:41:56.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:87:0)
[21:41:56.894] Timer tick, asking Buildbucket for the build status
[21:41:57.024] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:57.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:88:0) after 1m0s
[21:42:57.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:88:0)
[21:42:57.049] Timer tick, asking Buildbucket for the build status
[21:42:57.168] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:57.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:89:0) after 1m0s
[21:43:57.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:89:0)
[21:43:57.199] Timer tick, asking Buildbucket for the build status
[21:43:57.498] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:57.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:90:0) after 1m0s
[21:44:57.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:90:0)
[21:44:57.539] Timer tick, asking Buildbucket for the build status
[21:44:57.785] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:57.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:91:0) after 1m0s
[21:45:57.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:91:0)
[21:45:57.853] Timer tick, asking Buildbucket for the build status
[21:45:58.070] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:58.070] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:92:0) after 1m0s
[21:46:58.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:92:0)
[21:46:58.093] Timer tick, asking Buildbucket for the build status
[21:46:58.282] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:58.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:93:0) after 1m0s
[21:47:59.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:93:0)
[21:47:59.039] Timer tick, asking Buildbucket for the build status
[21:47:59.115] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:59.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:94:0) after 1m0s
[21:48:59.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:94:0)
[21:48:59.166] Timer tick, asking Buildbucket for the build status
[21:48:59.630] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:59.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:95:0) after 1m0s
[21:50:00.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:95:0)
[21:50:00.197] Timer tick, asking Buildbucket for the build status
[21:50:00.334] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:00.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:96:0) after 1m0s
[21:51:00.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:96:0)
[21:51:00.844] Timer tick, asking Buildbucket for the build status
[21:51:00.925] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:00.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:97:0) after 1m0s
[21:52:02.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:97:0)
[21:52:02.835] Timer tick, asking Buildbucket for the build status
[21:52:03.019] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:03.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:98:0) after 1m0s
[21:53:03.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:98:0)
[21:53:03.122] Timer tick, asking Buildbucket for the build status
[21:53:03.227] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:03.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:99:0) after 1m0s
[21:54:03.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:99:0)
[21:54:03.601] Timer tick, asking Buildbucket for the build status
[21:54:03.869] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:03.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:100:0) after 1m0s
[21:55:03.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:100:0)
[21:55:03.885] Timer tick, asking Buildbucket for the build status
[21:55:05.336] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:05.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:101:0) after 1m0s
[21:56:05.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:101:0)
[21:56:05.425] Timer tick, asking Buildbucket for the build status
[21:56:05.758] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:05.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:102:0) after 1m0s
[21:57:05.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:102:0)
[21:57:05.782] Timer tick, asking Buildbucket for the build status
[21:57:05.953] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:05.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:103:0) after 1m0s
[21:58:05.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:103:0)
[21:58:05.975] Timer tick, asking Buildbucket for the build status
[21:58:06.080] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:06.080] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:104:0) after 1m0s
[21:59:06.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:104:0)
[21:59:06.901] Timer tick, asking Buildbucket for the build status
[21:59:06.971] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:06.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:105:0) after 1m0s
[22:00:07.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:105:0)
[22:00:07.184] Timer tick, asking Buildbucket for the build status
[22:00:07.829] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:07.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:106:0) after 1m0s
[22:01:08.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:106:0)
[22:01:08.087] Timer tick, asking Buildbucket for the build status
[22:01:08.328] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:08.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:107:0) after 1m0s
[22:02:08.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:107:0)
[22:02:08.356] Timer tick, asking Buildbucket for the build status
[22:02:08.613] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:08.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:108:0) after 1m0s
[22:03:08.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:108:0)
[22:03:08.721] Timer tick, asking Buildbucket for the build status
[22:03:08.971] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:08.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:109:0) after 1m0s
[22:04:09.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:109:0)
[22:04:09.016] Timer tick, asking Buildbucket for the build status
[22:04:09.262] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:09.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:110:0) after 1m0s
[22:05:09.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:110:0)
[22:05:09.304] Timer tick, asking Buildbucket for the build status
[22:05:09.457] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:09.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:111:0) after 1m0s
[22:06:09.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:111:0)
[22:06:09.559] Timer tick, asking Buildbucket for the build status
[22:06:09.687] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:09.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:112:0) after 1m0s
[22:07:09.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:112:0)
[22:07:09.761] Timer tick, asking Buildbucket for the build status
[22:07:09.906] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:09.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:113:0) after 1m0s
[22:08:09.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:113:0)
[22:08:09.908] Timer tick, asking Buildbucket for the build status
[22:08:10.002] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:10.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:114:0) after 1m0s
[22:09:10.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:114:0)
[22:09:10.044] Timer tick, asking Buildbucket for the build status
[22:09:10.121] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:10.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:115:0) after 1m0s
[22:10:10.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:115:0)
[22:10:10.191] Timer tick, asking Buildbucket for the build status
[22:10:10.510] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:10.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:116:0) after 1m0s
[22:11:10.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:116:0)
[22:11:10.533] Timer tick, asking Buildbucket for the build status
[22:11:10.699] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:10.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:117:0) after 1m0s
[22:12:10.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:117:0)
[22:12:10.722] Timer tick, asking Buildbucket for the build status
[22:12:10.849] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:10.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:118:0) after 1m0s
[22:13:10.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:118:0)
[22:13:10.871] Timer tick, asking Buildbucket for the build status
[22:13:10.975] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:10.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:119:0) after 1m0s
[22:14:11.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:119:0)
[22:14:11.042] Timer tick, asking Buildbucket for the build status
[22:14:11.174] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:11.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:120:0) after 1m0s
[22:15:11.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:120:0)
[22:15:11.224] Timer tick, asking Buildbucket for the build status
[22:15:11.441] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:11.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:121:0) after 1m0s
[22:16:11.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:121:0)
[22:16:11.480] Timer tick, asking Buildbucket for the build status
[22:16:11.573] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:11.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:122:0) after 1m0s
[22:17:11.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:122:0)
[22:17:11.626] Timer tick, asking Buildbucket for the build status
[22:17:12.156] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:12.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:123:0) after 1m0s
[22:18:12.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:123:0)
[22:18:12.178] Timer tick, asking Buildbucket for the build status
[22:18:12.260] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:12.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:124:0) after 1m0s
[22:19:12.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:124:0)
[22:19:12.316] Timer tick, asking Buildbucket for the build status
[22:19:12.387] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:12.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:125:0) after 1m0s
[22:20:12.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:125:0)
[22:20:12.373] Timer tick, asking Buildbucket for the build status
[22:20:12.519] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:12.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:126:0) after 1m0s
[22:21:12.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:126:0)
[22:21:12.594] Timer tick, asking Buildbucket for the build status
[22:21:12.658] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:12.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:127:0) after 1m0s
[22:22:12.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:127:0)
[22:22:12.700] Timer tick, asking Buildbucket for the build status
[22:22:12.932] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:12.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:128:0) after 1m0s
[22:23:12.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:128:0)
[22:23:12.946] Timer tick, asking Buildbucket for the build status
[22:23:13.097] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:13.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:129:0) after 1m0s
[22:24:13.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:129:0)
[22:24:13.119] Timer tick, asking Buildbucket for the build status
[22:24:13.193] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:13.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:130:0) after 1m0s
[22:25:13.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:130:0)
[22:25:13.219] Timer tick, asking Buildbucket for the build status
[22:25:13.465] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:13.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:131:0) after 1m0s
[22:26:13.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:131:0)
[22:26:13.469] Timer tick, asking Buildbucket for the build status
[22:26:13.595] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:13.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:132:0) after 1m0s
[22:27:13.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:132:0)
[22:27:13.631] Timer tick, asking Buildbucket for the build status
[22:27:13.806] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:13.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:133:0) after 1m0s
[22:28:13.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:133:0)
[22:28:13.833] Timer tick, asking Buildbucket for the build status
[22:28:13.898] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:13.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:134:0) after 1m0s
[22:29:13.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:134:0)
[22:29:13.933] Timer tick, asking Buildbucket for the build status
[22:29:14.946] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:14.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:135:0) after 1m0s
[22:30:15.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:135:0)
[22:30:15.150] Timer tick, asking Buildbucket for the build status
[22:30:15.244] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:15.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:136:0) after 1m0s
[22:31:15.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:136:0)
[22:31:15.266] Timer tick, asking Buildbucket for the build status
[22:31:15.360] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:15.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:137:0) after 1m0s
[22:32:15.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:137:0)
[22:32:15.508] Timer tick, asking Buildbucket for the build status
[22:32:16.403] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:16.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:138:0) after 1m0s
[22:33:16.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:138:0)
[22:33:16.431] Timer tick, asking Buildbucket for the build status
[22:33:16.751] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:16.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:139:0) after 1m0s
[22:34:16.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:139:0)
[22:34:16.814] Timer tick, asking Buildbucket for the build status
[22:34:17.002] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:17.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:140:0) after 1m0s
[22:35:17.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:140:0)
[22:35:17.059] Timer tick, asking Buildbucket for the build status
[22:35:19.130] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:19.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:141:0) after 1m0s
[22:36:19.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:141:0)
[22:36:19.204] Timer tick, asking Buildbucket for the build status
[22:36:19.305] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:19.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:142:0) after 1m0s
[22:37:19.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:142:0)
[22:37:19.360] Timer tick, asking Buildbucket for the build status
[22:37:19.867] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:19.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:143:0) after 1m0s
[22:38:19.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:143:0)
[22:38:19.891] Timer tick, asking Buildbucket for the build status
[22:38:20.057] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:20.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:144:0) after 1m0s
[22:39:20.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:144:0)
[22:39:20.087] Timer tick, asking Buildbucket for the build status
[22:39:20.181] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:20.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:145:0) after 1m0s
[22:40:20.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:145:0)
[22:40:20.214] Timer tick, asking Buildbucket for the build status
[22:40:20.315] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:20.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:146:0) after 1m0s
[22:41:20.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:146:0)
[22:41:20.364] Timer tick, asking Buildbucket for the build status
[22:41:20.717] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:20.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:147:0) after 1m0s
[22:42:20.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:147:0)
[22:42:20.892] Timer tick, asking Buildbucket for the build status
[22:42:21.238] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:21.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:148:0) after 1m0s
[22:43:21.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:148:0)
[22:43:21.307] Timer tick, asking Buildbucket for the build status
[22:43:21.607] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:21.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:149:0) after 1m0s
[22:44:21.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:149:0)
[22:44:21.675] Timer tick, asking Buildbucket for the build status
[22:44:21.742] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:21.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:150:0) after 1m0s
[22:45:21.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:150:0)
[22:45:21.841] Timer tick, asking Buildbucket for the build status
[22:45:21.945] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:21.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:151:0) after 1m0s
[22:46:22.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:151:0)
[22:46:22.181] Timer tick, asking Buildbucket for the build status
[22:46:22.401] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:22.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:152:0) after 1m0s
[22:47:22.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:152:0)
[22:47:22.563] Timer tick, asking Buildbucket for the build status
[22:47:22.741] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:22.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:153:0) after 1m0s
[22:48:22.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:153:0)
[22:48:22.888] Timer tick, asking Buildbucket for the build status
[22:48:24.332] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:24.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:154:0) after 1m0s
[22:49:24.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:154:0)
[22:49:24.358] Timer tick, asking Buildbucket for the build status
[22:49:24.533] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:24.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:155:0) after 1m0s
[22:50:24.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:155:0)
[22:50:24.729] Timer tick, asking Buildbucket for the build status
[22:50:24.899] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:24.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:156:0) after 1m0s
[22:51:25.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:156:0)
[22:51:25.038] Timer tick, asking Buildbucket for the build status
[22:51:25.109] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:25.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:157:0) after 1m0s
[22:52:25.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:157:0)
[22:52:25.175] Timer tick, asking Buildbucket for the build status
[22:52:25.596] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:25.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:158:0) after 1m0s
[22:53:25.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:158:0)
[22:53:25.686] Timer tick, asking Buildbucket for the build status
[22:53:26.171] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:26.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:159:0) after 1m0s
[22:54:26.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:159:0)
[22:54:26.264] Timer tick, asking Buildbucket for the build status
[22:54:26.387] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:26.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:160:0) after 1m0s
[22:55:26.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:160:0)
[22:55:26.440] Timer tick, asking Buildbucket for the build status
[22:55:26.542] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:26.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:161:0) after 1m0s
[22:56:26.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:161:0)
[22:56:26.528] Timer tick, asking Buildbucket for the build status
[22:56:26.616] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:26.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:162:0) after 1m0s
[22:57:26.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:162:0)
[22:57:26.663] Timer tick, asking Buildbucket for the build status
[22:57:26.840] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:26.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:163:0) after 1m0s
[22:58:26.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:163:0)
[22:58:26.885] Timer tick, asking Buildbucket for the build status
[22:58:26.996] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:26.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:164:0) after 1m0s
[22:59:27.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:164:0)
[22:59:27.002] Timer tick, asking Buildbucket for the build status
[22:59:27.194] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:27.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:165:0) after 1m0s
[23:00:27.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:165:0)
[23:00:27.248] Timer tick, asking Buildbucket for the build status
[23:00:27.340] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:27.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:166:0) after 1m0s
[23:01:27.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:166:0)
[23:01:27.414] Timer tick, asking Buildbucket for the build status
[23:01:27.510] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:27.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:167:0) after 1m0s
[23:02:27.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:167:0)
[23:02:27.524] Timer tick, asking Buildbucket for the build status
[23:02:27.654] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:27.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:168:0) after 1m0s
[23:03:27.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:168:0)
[23:03:27.684] Timer tick, asking Buildbucket for the build status
[23:03:27.800] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:27.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:169:0) after 1m0s
[23:04:27.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:169:0)
[23:04:27.831] Timer tick, asking Buildbucket for the build status
[23:04:28.046] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:28.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:170:0) after 1m0s
[23:05:28.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:170:0)
[23:05:28.087] Timer tick, asking Buildbucket for the build status
[23:05:28.358] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:28.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:171:0) after 1m0s
[23:06:28.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:171:0)
[23:06:28.483] Timer tick, asking Buildbucket for the build status
[23:06:28.898] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:28.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:172:0) after 1m0s
[23:07:29.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:172:0)
[23:07:29.133] Timer tick, asking Buildbucket for the build status
[23:07:29.253] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:29.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:173:0) after 1m0s
[23:08:29.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:173:0)
[23:08:29.331] Timer tick, asking Buildbucket for the build status
[23:08:29.441] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:29.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:174:0) after 1m0s
[23:09:29.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:174:0)
[23:09:29.567] Timer tick, asking Buildbucket for the build status
[23:09:29.711] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:29.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:175:0) after 1m0s
[23:10:29.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:175:0)
[23:10:29.770] Timer tick, asking Buildbucket for the build status
[23:10:29.861] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:29.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:176:0) after 1m0s
[23:11:29.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:176:0)
[23:11:29.919] Timer tick, asking Buildbucket for the build status
[23:11:30.236] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:30.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:177:0) after 1m0s
[23:12:30.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:177:0)
[23:12:30.394] Timer tick, asking Buildbucket for the build status
[23:12:30.569] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:30.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:178:0) after 1m0s
[23:13:30.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:178:0)
[23:13:30.624] Timer tick, asking Buildbucket for the build status
[23:13:32.024] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:32.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:179:0) after 1m0s
[23:14:32.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:179:0)
[23:14:32.246] Timer tick, asking Buildbucket for the build status
[23:14:32.330] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:32.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:180:0) after 1m0s
[23:15:32.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:180:0)
[23:15:32.408] Timer tick, asking Buildbucket for the build status
[23:15:32.474] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:32.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:181:0) after 1m0s
[23:16:32.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:181:0)
[23:16:32.522] Timer tick, asking Buildbucket for the build status
[23:16:32.635] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:32.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:182:0) after 1m0s
[23:17:32.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:182:0)
[23:17:32.741] Timer tick, asking Buildbucket for the build status
[23:17:32.898] Build 8871507489782579200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:32.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036937551786310464:183:0) after 1m0s
[23:17:40.253] Received PubSub notification, asking Buildbucket for the build status
[23:17:40.421] Build 8871507489782579200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:17:40.421] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1597879058882994",
    "created_by": "project:infra",
    "created_ts": "1597868181396982",
    "id": "8871507489782579200",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4183.76\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"bf9d3924-e309-45ba-a359-b63cb3cadfed\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4183.76\"}}",
    "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-n33d\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1597868240933083",
    "status": "COMPLETED",
    "status_changed_ts": "1597879059807092",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036937551786310464",
      "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/8871507489782579200/+/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:4e212f13f71b6810",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1597879059885969",
    "url": "https://ci.chromium.org/b/8871507489782579200",
    "utcnow_ts": "1597879060397930"
  }
}
[23:17:40.421] Invocation finished in 3h1m20.319241702s with status SUCCEEDED