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.4169.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[12:46:26.342] New invocation is queued and will start shortly
[12:46:26.342] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[12:46:27.765] Starting the invocation (attempt 1)
[12:46:27.804] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:46:27.804] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:46:27.804] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9043217047532472464",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4169.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"a01c2695-4eec-4edc-ba80-4395441b5f8d\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4169.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9043217047532472464",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[12:46:28.361] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1591879587913042",
    "id": "8877786985182478528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4169.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"a01c2695-4eec-4edc-ba80-4395441b5f8d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4169.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1591879588192296",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9043217047532472464",
      "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/8877786985182478528/+/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": "1591879588192443",
    "url": "https://ci.chromium.org/b/8877786985182478528",
    "utcnow_ts": "1591879588345959"
  }
}
[12:46:28.361] Task URL: https://ci.chromium.org/b/8877786985182478528
[12:46:28.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:2:0) after 1m0s
[12:47:28.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:2:0)
[12:47:28.561] Timer tick, asking Buildbucket for the build status
[12:47:28.710] Build 8877786985182478528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:28.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:3:0) after 1m0s
[12:47:29.012] Received PubSub notification, asking Buildbucket for the build status
[12:47:29.088] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:28.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:3:0)
[12:48:28.800] Timer tick, asking Buildbucket for the build status
[12:48:28.930] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:28.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:5:0) after 1m0s
[12:49:29.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:5:0)
[12:49:29.068] Timer tick, asking Buildbucket for the build status
[12:49:29.362] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:29.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:6:0) after 1m0s
[12:50:29.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:6:0)
[12:50:29.414] Timer tick, asking Buildbucket for the build status
[12:50:30.056] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:30.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:7:0) after 1m0s
[12:51:30.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:7:0)
[12:51:30.177] Timer tick, asking Buildbucket for the build status
[12:51:30.401] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:30.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:8:0) after 1m0s
[12:52:30.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:8:0)
[12:52:30.421] Timer tick, asking Buildbucket for the build status
[12:52:30.842] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:30.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:9:0) after 1m0s
[12:53:30.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:9:0)
[12:53:30.863] Timer tick, asking Buildbucket for the build status
[12:53:30.994] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:30.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:10:0) after 1m0s
[12:54:31.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:10:0)
[12:54:31.002] Timer tick, asking Buildbucket for the build status
[12:54:31.260] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:31.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:11:0) after 1m0s
[12:55:31.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:11:0)
[12:55:31.371] Timer tick, asking Buildbucket for the build status
[12:55:31.530] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:31.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:12:0) after 1m0s
[12:56:31.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:12:0)
[12:56:31.655] Timer tick, asking Buildbucket for the build status
[12:56:31.973] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:31.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:13:0) after 1m0s
[12:57:32.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:13:0)
[12:57:32.037] Timer tick, asking Buildbucket for the build status
[12:57:32.153] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:32.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:14:0) after 1m0s
[12:58:32.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:14:0)
[12:58:32.193] Timer tick, asking Buildbucket for the build status
[12:58:32.356] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:32.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:15:0) after 1m0s
[12:59:32.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:15:0)
[12:59:32.396] Timer tick, asking Buildbucket for the build status
[12:59:32.590] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:32.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:16:0) after 1m0s
[13:00:32.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:16:0)
[13:00:32.744] Timer tick, asking Buildbucket for the build status
[13:00:32.894] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:32.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:17:0) after 1m0s
[13:01:32.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:17:0)
[13:01:32.909] Timer tick, asking Buildbucket for the build status
[13:01:33.181] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:33.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:18:0) after 1m0s
[13:02:33.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:18:0)
[13:02:33.259] Timer tick, asking Buildbucket for the build status
[13:02:33.425] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:33.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:19:0) after 1m0s
[13:03:33.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:19:0)
[13:03:33.482] Timer tick, asking Buildbucket for the build status
[13:03:33.833] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:33.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:20:0) after 1m0s
[13:04:33.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:20:0)
[13:04:33.911] Timer tick, asking Buildbucket for the build status
[13:04:34.086] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:34.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:21:0) after 1m0s
[13:05:34.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:21:0)
[13:05:34.192] Timer tick, asking Buildbucket for the build status
[13:05:34.362] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:34.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:22:0) after 1m0s
[13:06:34.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:22:0)
[13:06:34.449] Timer tick, asking Buildbucket for the build status
[13:06:37.614] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:37.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:23:0) after 1m0s
[13:07:37.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:23:0)
[13:07:37.723] Timer tick, asking Buildbucket for the build status
[13:07:37.910] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:37.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:24:0) after 1m0s
[13:08:37.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:24:0)
[13:08:37.932] Timer tick, asking Buildbucket for the build status
[13:08:38.123] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:38.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:25:0) after 1m0s
[13:09:38.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:25:0)
[13:09:38.145] Timer tick, asking Buildbucket for the build status
[13:09:38.340] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:38.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:26:0) after 1m0s
[13:10:38.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:26:0)
[13:10:38.386] Timer tick, asking Buildbucket for the build status
[13:10:38.553] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:38.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:27:0) after 1m0s
[13:11:38.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:27:0)
[13:11:38.586] Timer tick, asking Buildbucket for the build status
[13:11:38.729] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:38.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:28:0) after 1m0s
[13:12:38.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:28:0)
[13:12:38.777] Timer tick, asking Buildbucket for the build status
[13:12:38.992] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:38.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:29:0) after 1m0s
[13:13:39.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:29:0)
[13:13:39.017] Timer tick, asking Buildbucket for the build status
[13:13:39.427] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:39.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:30:0) after 1m0s
[13:14:39.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:30:0)
[13:14:39.498] Timer tick, asking Buildbucket for the build status
[13:14:39.633] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:39.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:31:0) after 1m0s
[13:15:39.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:31:0)
[13:15:39.686] Timer tick, asking Buildbucket for the build status
[13:15:39.816] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:39.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:32:0) after 1m0s
[13:16:39.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:32:0)
[13:16:39.884] Timer tick, asking Buildbucket for the build status
[13:16:40.342] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:40.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:33:0) after 1m0s
[13:17:40.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:33:0)
[13:17:40.375] Timer tick, asking Buildbucket for the build status
[13:17:40.566] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:40.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:34:0) after 1m0s
[13:18:40.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:34:0)
[13:18:40.609] Timer tick, asking Buildbucket for the build status
[13:18:40.771] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:40.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:35:0) after 1m0s
[13:19:40.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:35:0)
[13:19:40.791] Timer tick, asking Buildbucket for the build status
[13:19:40.956] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:40.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:36:0) after 1m0s
[13:20:41.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:36:0)
[13:20:41.009] Timer tick, asking Buildbucket for the build status
[13:20:41.149] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:41.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:37:0) after 1m0s
[13:21:41.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:37:0)
[13:21:41.186] Timer tick, asking Buildbucket for the build status
[13:21:42.454] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:42.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:38:0) after 1m0s
[13:22:42.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:38:0)
[13:22:42.484] Timer tick, asking Buildbucket for the build status
[13:22:42.613] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:42.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:39:0) after 1m0s
[13:23:42.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:39:0)
[13:23:42.597] Timer tick, asking Buildbucket for the build status
[13:23:42.834] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:42.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:40:0) after 1m0s
[13:24:42.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:40:0)
[13:24:42.910] Timer tick, asking Buildbucket for the build status
[13:24:43.042] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:43.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:41:0) after 1m0s
[13:25:43.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:41:0)
[13:25:43.129] Timer tick, asking Buildbucket for the build status
[13:25:43.434] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:43.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:42:0) after 1m0s
[13:26:43.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:42:0)
[13:26:43.514] Timer tick, asking Buildbucket for the build status
[13:26:44.440] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:44.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:43:0) after 1m0s
[13:27:44.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:43:0)
[13:27:44.457] Timer tick, asking Buildbucket for the build status
[13:27:44.654] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:44.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:44:0) after 1m0s
[13:28:44.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:44:0)
[13:28:44.708] Timer tick, asking Buildbucket for the build status
[13:28:44.991] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:44.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:45:0) after 1m0s
[13:29:45.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:45:0)
[13:29:45.063] Timer tick, asking Buildbucket for the build status
[13:29:45.297] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:45.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:46:0) after 1m0s
[13:30:45.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:46:0)
[13:30:45.327] Timer tick, asking Buildbucket for the build status
[13:30:45.783] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:45.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:47:0) after 1m0s
[13:31:45.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:47:0)
[13:31:45.842] Timer tick, asking Buildbucket for the build status
[13:31:46.439] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:46.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:48:0) after 1m0s
[13:32:46.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:48:0)
[13:32:46.481] Timer tick, asking Buildbucket for the build status
[13:32:46.833] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:46.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:49:0) after 1m0s
[13:33:46.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:49:0)
[13:33:46.872] Timer tick, asking Buildbucket for the build status
[13:33:47.017] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:47.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:50:0) after 1m0s
[13:34:47.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:50:0)
[13:34:47.079] Timer tick, asking Buildbucket for the build status
[13:34:47.224] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:47.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:51:0) after 1m0s
[13:35:47.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:51:0)
[13:35:47.374] Timer tick, asking Buildbucket for the build status
[13:35:47.609] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:47.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:52:0) after 1m0s
[13:36:47.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:52:0)
[13:36:47.611] Timer tick, asking Buildbucket for the build status
[13:36:48.944] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:48.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:53:0) after 1m0s
[13:37:48.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:53:0)
[13:37:48.965] Timer tick, asking Buildbucket for the build status
[13:37:49.241] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:49.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:54:0) after 1m0s
[13:38:49.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:54:0)
[13:38:49.222] Timer tick, asking Buildbucket for the build status
[13:38:49.384] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:49.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:55:0) after 1m0s
[13:39:49.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:55:0)
[13:39:49.448] Timer tick, asking Buildbucket for the build status
[13:39:49.661] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:49.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:56:0) after 1m0s
[13:40:49.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:56:0)
[13:40:49.736] Timer tick, asking Buildbucket for the build status
[13:40:49.942] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:49.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:57:0) after 1m0s
[13:41:50.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:57:0)
[13:41:50.018] Timer tick, asking Buildbucket for the build status
[13:41:50.517] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:50.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:58:0) after 1m0s
[13:42:50.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:58:0)
[13:42:50.691] Timer tick, asking Buildbucket for the build status
[13:42:50.816] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:50.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:59:0) after 1m0s
[13:43:50.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:59:0)
[13:43:50.883] Timer tick, asking Buildbucket for the build status
[13:43:51.183] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:51.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:60:0) after 1m0s
[13:44:51.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:60:0)
[13:44:51.225] Timer tick, asking Buildbucket for the build status
[13:44:51.447] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:51.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:61:0) after 1m0s
[13:45:51.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:61:0)
[13:45:51.561] Timer tick, asking Buildbucket for the build status
[13:45:51.696] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:51.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:62:0) after 1m0s
[13:46:51.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:62:0)
[13:46:51.803] Timer tick, asking Buildbucket for the build status
[13:46:52.256] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:52.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:63:0) after 1m0s
[13:47:52.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:63:0)
[13:47:52.293] Timer tick, asking Buildbucket for the build status
[13:47:52.537] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:52.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:64:0) after 1m0s
[13:48:52.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:64:0)
[13:48:52.619] Timer tick, asking Buildbucket for the build status
[13:48:53.045] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:53.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:65:0) after 1m0s
[13:49:53.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:65:0)
[13:49:53.094] Timer tick, asking Buildbucket for the build status
[13:49:53.258] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:53.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:66:0) after 1m0s
[13:50:53.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:66:0)
[13:50:53.281] Timer tick, asking Buildbucket for the build status
[13:50:53.570] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:53.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:67:0) after 1m0s
[13:51:53.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:67:0)
[13:51:53.636] Timer tick, asking Buildbucket for the build status
[13:51:54.329] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:54.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:68:0) after 1m0s
[13:52:54.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:68:0)
[13:52:54.388] Timer tick, asking Buildbucket for the build status
[13:52:55.545] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:55.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:69:0) after 1m0s
[13:53:55.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:69:0)
[13:53:55.606] Timer tick, asking Buildbucket for the build status
[13:53:55.718] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:55.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:70:0) after 1m0s
[13:54:55.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:70:0)
[13:54:55.747] Timer tick, asking Buildbucket for the build status
[13:54:56.137] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:56.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:71:0) after 1m0s
[13:55:56.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:71:0)
[13:55:56.187] Timer tick, asking Buildbucket for the build status
[13:55:56.323] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:56.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:72:0) after 1m0s
[13:56:56.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:72:0)
[13:56:56.346] Timer tick, asking Buildbucket for the build status
[13:56:56.620] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:56.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:73:0) after 1m0s
[13:57:56.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:73:0)
[13:57:56.684] Timer tick, asking Buildbucket for the build status
[13:57:56.788] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:56.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:74:0) after 1m0s
[13:58:56.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:74:0)
[13:58:56.923] Timer tick, asking Buildbucket for the build status
[13:58:57.128] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:57.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:75:0) after 1m0s
[13:59:57.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:75:0)
[13:59:57.252] Timer tick, asking Buildbucket for the build status
[13:59:57.340] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:57.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:76:0) after 1m0s
[14:00:57.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:76:0)
[14:00:57.400] Timer tick, asking Buildbucket for the build status
[14:00:57.517] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:57.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:77:0) after 1m0s
[14:01:57.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:77:0)
[14:01:57.651] Timer tick, asking Buildbucket for the build status
[14:01:57.978] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:57.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:78:0) after 1m0s
[14:02:58.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:78:0)
[14:02:58.003] Timer tick, asking Buildbucket for the build status
[14:02:58.203] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:58.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:79:0) after 1m0s
[14:03:58.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:79:0)
[14:03:58.270] Timer tick, asking Buildbucket for the build status
[14:03:58.491] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:58.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:80:0) after 1m0s
[14:04:58.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:80:0)
[14:04:58.626] Timer tick, asking Buildbucket for the build status
[14:04:58.780] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:58.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:81:0) after 1m0s
[14:05:58.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:81:0)
[14:05:58.856] Timer tick, asking Buildbucket for the build status
[14:05:59.157] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:59.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:82:0) after 1m0s
[14:06:59.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:82:0)
[14:06:59.214] Timer tick, asking Buildbucket for the build status
[14:06:59.507] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:59.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:83:0) after 1m0s
[14:07:59.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:83:0)
[14:07:59.569] Timer tick, asking Buildbucket for the build status
[14:07:59.676] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:59.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:84:0) after 1m0s
[14:08:59.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:84:0)
[14:08:59.977] Timer tick, asking Buildbucket for the build status
[14:09:00.252] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:00.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:85:0) after 1m0s
[14:10:00.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:85:0)
[14:10:00.519] Timer tick, asking Buildbucket for the build status
[14:10:01.553] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:01.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:86:0) after 1m0s
[14:11:02.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:86:0)
[14:11:02.404] Timer tick, asking Buildbucket for the build status
[14:11:02.763] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:02.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:87:0) after 1m0s
[14:12:02.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:87:0)
[14:12:02.938] Timer tick, asking Buildbucket for the build status
[14:12:03.064] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:03.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:88:0) after 1m0s
[14:13:03.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:88:0)
[14:13:03.545] Timer tick, asking Buildbucket for the build status
[14:13:03.662] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:03.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:89:0) after 1m0s
[14:14:03.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:89:0)
[14:14:03.824] Timer tick, asking Buildbucket for the build status
[14:14:04.122] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:04.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:90:0) after 1m0s
[14:15:04.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:90:0)
[14:15:04.241] Timer tick, asking Buildbucket for the build status
[14:15:04.644] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:04.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:91:0) after 1m0s
[14:16:04.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:91:0)
[14:16:04.814] Timer tick, asking Buildbucket for the build status
[14:16:04.974] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:04.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:92:0) after 1m0s
[14:17:05.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:92:0)
[14:17:05.129] Timer tick, asking Buildbucket for the build status
[14:17:05.333] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:05.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:93:0) after 1m0s
[14:18:05.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:93:0)
[14:18:05.483] Timer tick, asking Buildbucket for the build status
[14:18:05.609] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:05.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:94:0) after 1m0s
[14:19:05.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:94:0)
[14:19:05.652] Timer tick, asking Buildbucket for the build status
[14:19:06.214] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:06.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:95:0) after 1m0s
[14:20:06.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:95:0)
[14:20:06.384] Timer tick, asking Buildbucket for the build status
[14:20:06.766] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:06.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:96:0) after 1m0s
[14:21:06.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:96:0)
[14:21:06.892] Timer tick, asking Buildbucket for the build status
[14:21:07.551] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:07.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:97:0) after 1m0s
[14:22:07.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:97:0)
[14:22:07.690] Timer tick, asking Buildbucket for the build status
[14:22:07.878] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:07.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:98:0) after 1m0s
[14:23:08.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:98:0)
[14:23:08.147] Timer tick, asking Buildbucket for the build status
[14:23:08.407] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:08.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:99:0) after 1m0s
[14:24:08.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:99:0)
[14:24:08.514] Timer tick, asking Buildbucket for the build status
[14:24:08.680] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:08.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:100:0) after 1m0s
[14:25:08.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:100:0)
[14:25:08.781] Timer tick, asking Buildbucket for the build status
[14:25:08.947] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:08.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:101:0) after 1m0s
[14:26:09.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:101:0)
[14:26:09.012] Timer tick, asking Buildbucket for the build status
[14:26:09.738] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:09.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:102:0) after 1m0s
[14:27:09.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:102:0)
[14:27:09.829] Timer tick, asking Buildbucket for the build status
[14:27:10.032] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:10.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:103:0) after 1m0s
[14:28:10.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:103:0)
[14:28:10.201] Timer tick, asking Buildbucket for the build status
[14:28:10.351] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:10.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:104:0) after 1m0s
[14:29:10.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:104:0)
[14:29:10.486] Timer tick, asking Buildbucket for the build status
[14:29:10.605] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:10.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:105:0) after 1m0s
[14:30:10.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:105:0)
[14:30:10.701] Timer tick, asking Buildbucket for the build status
[14:30:11.501] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:11.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:106:0) after 1m0s
[14:31:11.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:106:0)
[14:31:11.554] Timer tick, asking Buildbucket for the build status
[14:31:11.759] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:11.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:107:0) after 1m0s
[14:32:11.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:107:0)
[14:32:11.801] Timer tick, asking Buildbucket for the build status
[14:32:12.273] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:12.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:108:0) after 1m0s
[14:33:12.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:108:0)
[14:33:12.361] Timer tick, asking Buildbucket for the build status
[14:33:12.483] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:12.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:109:0) after 1m0s
[14:34:12.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:109:0)
[14:34:12.565] Timer tick, asking Buildbucket for the build status
[14:34:13.074] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:13.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:110:0) after 1m0s
[14:35:13.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:110:0)
[14:35:13.177] Timer tick, asking Buildbucket for the build status
[14:35:13.487] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:13.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:111:0) after 1m0s
[14:36:13.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:111:0)
[14:36:13.588] Timer tick, asking Buildbucket for the build status
[14:36:13.964] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:13.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:112:0) after 1m0s
[14:37:14.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:112:0)
[14:37:14.035] Timer tick, asking Buildbucket for the build status
[14:37:14.388] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:14.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:113:0) after 1m0s
[14:38:14.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:113:0)
[14:38:14.463] Timer tick, asking Buildbucket for the build status
[14:38:14.744] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:14.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:114:0) after 1m0s
[14:39:14.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:114:0)
[14:39:14.828] Timer tick, asking Buildbucket for the build status
[14:39:15.041] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:15.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:115:0) after 1m0s
[14:40:15.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:115:0)
[14:40:15.151] Timer tick, asking Buildbucket for the build status
[14:40:15.743] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:15.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:116:0) after 1m0s
[14:41:15.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:116:0)
[14:41:15.887] Timer tick, asking Buildbucket for the build status
[14:41:16.554] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:16.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:117:0) after 1m0s
[14:42:16.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:117:0)
[14:42:16.696] Timer tick, asking Buildbucket for the build status
[14:42:16.975] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:16.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:118:0) after 1m0s
[14:43:17.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:118:0)
[14:43:17.008] Timer tick, asking Buildbucket for the build status
[14:43:17.489] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:17.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:119:0) after 1m0s
[14:44:17.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:119:0)
[14:44:17.490] Timer tick, asking Buildbucket for the build status
[14:44:17.603] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:17.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:120:0) after 1m0s
[14:45:17.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:120:0)
[14:45:17.653] Timer tick, asking Buildbucket for the build status
[14:45:17.857] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:17.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:121:0) after 1m0s
[14:46:17.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:121:0)
[14:46:17.976] Timer tick, asking Buildbucket for the build status
[14:46:18.154] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:18.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:122:0) after 1m0s
[14:47:18.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:122:0)
[14:47:18.207] Timer tick, asking Buildbucket for the build status
[14:47:18.350] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:18.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:123:0) after 1m0s
[14:48:18.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:123:0)
[14:48:18.421] Timer tick, asking Buildbucket for the build status
[14:48:18.631] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:18.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:124:0) after 1m0s
[14:49:18.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:124:0)
[14:49:18.701] Timer tick, asking Buildbucket for the build status
[14:49:19.140] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:19.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:125:0) after 1m0s
[14:50:19.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:125:0)
[14:50:19.206] Timer tick, asking Buildbucket for the build status
[14:50:19.447] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:19.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:126:0) after 1m0s
[14:51:19.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:126:0)
[14:51:19.566] Timer tick, asking Buildbucket for the build status
[14:51:20.499] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:20.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:127:0) after 1m0s
[14:52:20.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:127:0)
[14:52:20.609] Timer tick, asking Buildbucket for the build status
[14:52:20.807] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:20.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:128:0) after 1m0s
[14:53:20.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:128:0)
[14:53:20.928] Timer tick, asking Buildbucket for the build status
[14:53:21.092] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:21.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:129:0) after 1m0s
[14:54:21.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:129:0)
[14:54:21.147] Timer tick, asking Buildbucket for the build status
[14:54:21.336] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:21.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:130:0) after 1m0s
[14:55:21.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:130:0)
[14:55:21.429] Timer tick, asking Buildbucket for the build status
[14:55:21.728] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:21.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:131:0) after 1m0s
[14:56:21.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:131:0)
[14:56:21.855] Timer tick, asking Buildbucket for the build status
[14:56:22.007] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:22.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:132:0) after 1m0s
[14:57:22.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:132:0)
[14:57:22.044] Timer tick, asking Buildbucket for the build status
[14:57:22.164] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:22.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:133:0) after 1m0s
[14:58:22.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:133:0)
[14:58:22.196] Timer tick, asking Buildbucket for the build status
[14:58:22.724] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:22.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:134:0) after 1m0s
[14:59:22.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:134:0)
[14:59:22.843] Timer tick, asking Buildbucket for the build status
[14:59:23.003] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:23.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:135:0) after 1m0s
[15:00:23.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:135:0)
[15:00:23.024] Timer tick, asking Buildbucket for the build status
[15:00:23.213] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:23.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:136:0) after 1m0s
[15:01:23.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:136:0)
[15:01:23.378] Timer tick, asking Buildbucket for the build status
[15:01:23.753] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:23.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:137:0) after 1m0s
[15:02:23.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:137:0)
[15:02:23.816] Timer tick, asking Buildbucket for the build status
[15:02:24.210] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:24.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:138:0) after 1m0s
[15:03:24.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:138:0)
[15:03:24.245] Timer tick, asking Buildbucket for the build status
[15:03:24.446] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:24.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:139:0) after 1m0s
[15:04:24.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:139:0)
[15:04:24.554] Timer tick, asking Buildbucket for the build status
[15:04:25.262] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:25.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:140:0) after 1m0s
[15:05:25.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:140:0)
[15:05:25.328] Timer tick, asking Buildbucket for the build status
[15:05:25.580] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:25.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:141:0) after 1m0s
[15:06:25.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:141:0)
[15:06:25.628] Timer tick, asking Buildbucket for the build status
[15:06:25.834] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:25.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:142:0) after 1m0s
[15:07:25.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:142:0)
[15:07:25.938] Timer tick, asking Buildbucket for the build status
[15:07:26.192] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:26.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:143:0) after 1m0s
[15:08:26.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:143:0)
[15:08:26.244] Timer tick, asking Buildbucket for the build status
[15:08:26.339] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:26.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:144:0) after 1m0s
[15:09:26.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:144:0)
[15:09:26.435] Timer tick, asking Buildbucket for the build status
[15:09:26.558] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:26.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:145:0) after 1m0s
[15:10:26.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:145:0)
[15:10:26.676] Timer tick, asking Buildbucket for the build status
[15:10:26.962] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:26.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:146:0) after 1m0s
[15:11:27.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:146:0)
[15:11:27.148] Timer tick, asking Buildbucket for the build status
[15:11:27.604] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:27.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:147:0) after 1m0s
[15:12:27.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:147:0)
[15:12:27.639] Timer tick, asking Buildbucket for the build status
[15:12:27.830] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:27.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:148:0) after 1m0s
[15:13:27.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:148:0)
[15:13:27.884] Timer tick, asking Buildbucket for the build status
[15:13:28.042] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:28.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:149:0) after 1m0s
[15:14:28.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:149:0)
[15:14:28.162] Timer tick, asking Buildbucket for the build status
[15:14:28.291] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:28.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:150:0) after 1m0s
[15:15:28.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:150:0)
[15:15:28.361] Timer tick, asking Buildbucket for the build status
[15:15:28.657] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:28.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:151:0) after 1m0s
[15:16:28.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:151:0)
[15:16:28.733] Timer tick, asking Buildbucket for the build status
[15:16:29.437] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:29.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:152:0) after 1m0s
[15:17:29.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:152:0)
[15:17:29.460] Timer tick, asking Buildbucket for the build status
[15:17:29.784] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:29.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:153:0) after 1m0s
[15:18:29.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:153:0)
[15:18:29.811] Timer tick, asking Buildbucket for the build status
[15:18:30.043] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:30.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:154:0) after 1m0s
[15:19:30.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:154:0)
[15:19:30.080] Timer tick, asking Buildbucket for the build status
[15:19:30.460] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:30.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:155:0) after 1m0s
[15:20:30.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:155:0)
[15:20:30.654] Timer tick, asking Buildbucket for the build status
[15:20:30.847] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:30.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:156:0) after 1m0s
[15:21:30.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:156:0)
[15:21:30.867] Timer tick, asking Buildbucket for the build status
[15:21:30.990] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:30.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:157:0) after 1m0s
[15:22:31.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:157:0)
[15:22:31.036] Timer tick, asking Buildbucket for the build status
[15:22:31.302] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:31.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:158:0) after 1m0s
[15:23:31.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:158:0)
[15:23:31.292] Timer tick, asking Buildbucket for the build status
[15:23:32.232] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:32.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:159:0) after 1m0s
[15:24:32.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:159:0)
[15:24:32.348] Timer tick, asking Buildbucket for the build status
[15:24:32.486] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:32.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:160:0) after 1m0s
[15:25:32.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:160:0)
[15:25:32.528] Timer tick, asking Buildbucket for the build status
[15:25:32.681] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:32.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:161:0) after 1m0s
[15:26:32.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:161:0)
[15:26:32.933] Timer tick, asking Buildbucket for the build status
[15:26:34.497] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:34.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:162:0) after 1m0s
[15:27:34.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:162:0)
[15:27:34.549] Timer tick, asking Buildbucket for the build status
[15:27:34.698] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:34.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:163:0) after 1m0s
[15:28:34.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:163:0)
[15:28:34.791] Timer tick, asking Buildbucket for the build status
[15:28:34.930] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:34.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:164:0) after 1m0s
[15:29:35.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:164:0)
[15:29:35.009] Timer tick, asking Buildbucket for the build status
[15:29:35.281] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:35.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:165:0) after 1m0s
[15:30:35.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:165:0)
[15:30:35.341] Timer tick, asking Buildbucket for the build status
[15:30:35.475] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:35.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:166:0) after 1m0s
[15:31:35.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:166:0)
[15:31:35.499] Timer tick, asking Buildbucket for the build status
[15:31:35.827] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:35.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:167:0) after 1m0s
[15:32:35.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:167:0)
[15:32:35.897] Timer tick, asking Buildbucket for the build status
[15:32:36.402] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:36.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:168:0) after 1m0s
[15:33:36.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:168:0)
[15:33:36.433] Timer tick, asking Buildbucket for the build status
[15:33:36.574] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:36.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:169:0) after 1m0s
[15:34:36.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:169:0)
[15:34:36.646] Timer tick, asking Buildbucket for the build status
[15:34:36.815] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:36.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:170:0) after 1m0s
[15:35:36.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:170:0)
[15:35:36.941] Timer tick, asking Buildbucket for the build status
[15:35:37.088] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:37.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:171:0) after 1m0s
[15:36:37.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:171:0)
[15:36:37.116] Timer tick, asking Buildbucket for the build status
[15:36:37.370] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:37.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:172:0) after 1m0s
[15:37:37.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:172:0)
[15:37:37.429] Timer tick, asking Buildbucket for the build status
[15:37:37.619] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:37.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:173:0) after 1m0s
[15:38:37.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:173:0)
[15:38:37.720] Timer tick, asking Buildbucket for the build status
[15:38:37.833] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:37.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:174:0) after 1m0s
[15:39:37.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:174:0)
[15:39:37.877] Timer tick, asking Buildbucket for the build status
[15:39:40.128] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:40.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:175:0) after 1m0s
[15:40:40.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:175:0)
[15:40:40.172] Timer tick, asking Buildbucket for the build status
[15:40:40.448] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:40.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:176:0) after 1m0s
[15:41:40.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:176:0)
[15:41:40.489] Timer tick, asking Buildbucket for the build status
[15:41:41.320] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:41.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:177:0) after 1m0s
[15:42:41.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:177:0)
[15:42:41.398] Timer tick, asking Buildbucket for the build status
[15:42:41.661] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:41.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:178:0) after 1m0s
[15:43:41.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:178:0)
[15:43:41.682] Timer tick, asking Buildbucket for the build status
[15:43:41.862] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:41.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:179:0) after 1m0s
[15:44:41.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:179:0)
[15:44:41.989] Timer tick, asking Buildbucket for the build status
[15:44:42.134] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:42.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:180:0) after 1m0s
[15:45:42.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:180:0)
[15:45:42.181] Timer tick, asking Buildbucket for the build status
[15:45:42.308] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:42.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:181:0) after 1m0s
[15:46:42.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:181:0)
[15:46:42.425] Timer tick, asking Buildbucket for the build status
[15:46:43.169] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:43.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:182:0) after 1m0s
[15:47:43.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:182:0)
[15:47:43.280] Timer tick, asking Buildbucket for the build status
[15:47:43.476] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:43.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:183:0) after 1m0s
[15:48:43.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:183:0)
[15:48:43.527] Timer tick, asking Buildbucket for the build status
[15:48:43.642] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:43.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:184:0) after 1m0s
[15:49:43.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:184:0)
[15:49:43.699] Timer tick, asking Buildbucket for the build status
[15:49:43.845] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:43.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:185:0) after 1m0s
[15:50:43.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:185:0)
[15:50:43.882] Timer tick, asking Buildbucket for the build status
[15:50:43.992] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:43.992] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:186:0) after 1m0s
[15:51:44.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:186:0)
[15:51:44.077] Timer tick, asking Buildbucket for the build status
[15:51:44.690] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:44.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:187:0) after 1m0s
[15:52:44.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:187:0)
[15:52:44.836] Timer tick, asking Buildbucket for the build status
[15:52:45.117] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:45.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:188:0) after 1m0s
[15:53:45.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:188:0)
[15:53:45.366] Timer tick, asking Buildbucket for the build status
[15:53:46.832] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:46.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:189:0) after 1m0s
[15:54:46.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:189:0)
[15:54:46.981] Timer tick, asking Buildbucket for the build status
[15:54:47.286] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:47.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:190:0) after 1m0s
[15:55:47.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:190:0)
[15:55:47.385] Timer tick, asking Buildbucket for the build status
[15:55:47.575] Build 8877786985182478528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:47.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9043217047532472464:191:0) after 1m0s
[15:56:03.570] Received PubSub notification, asking Buildbucket for the build status
[15:56:03.699] Build 8877786985182478528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:56:03.699] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1591890962629241",
    "created_by": "project:infra",
    "created_ts": "1591879587913042",
    "id": "8877786985182478528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4169.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"a01c2695-4eec-4edc-ba80-4395441b5f8d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4169.0\"}}",
    "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-qpmi\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5199-07c11c0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1591879647916737",
    "status": "COMPLETED",
    "status_changed_ts": "1591890963222949",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9043217047532472464",
      "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/8877786985182478528/+/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:4cbc3c6292736810",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1591890963252174",
    "url": "https://ci.chromium.org/b/8877786985182478528",
    "utcnow_ts": "1591890963682086"
  }
}
[15:56:03.699] Invocation finished in 3h9m37.409996213s with status SUCCEEDED