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

• parent_buildername:publish_tarball
• user_agent:recipe

[08:21:30.803] New invocation is queued and will start shortly
[08:21:30.803] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:21:31.909] Starting the invocation (attempt 1)
[08:21:31.955] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:21:31.955] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:21:31.956] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9042961924274753888",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4172.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"a5bf6261-89ca-4bfe-83d3-3be749bfe4c2\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4172.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:9042961924274753888",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:21:32.835] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1592122892077883",
    "id": "8877531862275485952",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4172.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"a5bf6261-89ca-4bfe-83d3-3be749bfe4c2\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4172.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1592122892618898",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042961924274753888",
      "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/8877531862275485952/+/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": "1592122892619028",
    "url": "https://ci.chromium.org/b/8877531862275485952",
    "utcnow_ts": "1592122892827836"
  }
}
[08:21:32.835] Task URL: https://ci.chromium.org/b/8877531862275485952
[08:21:32.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:2:0) after 1m0s
[08:21:47.245] Received PubSub notification, asking Buildbucket for the build status
[08:21:47.491] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:33.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:2:0)
[08:22:33.176] Timer tick, asking Buildbucket for the build status
[08:22:33.395] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:33.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:4:0) after 1m0s
[08:23:33.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:4:0)
[08:23:33.546] Timer tick, asking Buildbucket for the build status
[08:23:33.792] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:33.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:5:0) after 1m0s
[08:24:33.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:5:0)
[08:24:33.960] Timer tick, asking Buildbucket for the build status
[08:24:34.160] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:34.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:6:0) after 1m0s
[08:25:34.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:6:0)
[08:25:34.226] Timer tick, asking Buildbucket for the build status
[08:25:34.520] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:34.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:7:0) after 1m0s
[08:26:34.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:7:0)
[08:26:34.609] Timer tick, asking Buildbucket for the build status
[08:26:34.818] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:34.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:8:0) after 1m0s
[08:27:34.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:8:0)
[08:27:34.914] Timer tick, asking Buildbucket for the build status
[08:27:36.550] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:36.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:9:0) after 1m0s
[08:28:36.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:9:0)
[08:28:36.570] Timer tick, asking Buildbucket for the build status
[08:28:37.228] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:37.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:10:0) after 1m0s
[08:29:37.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:10:0)
[08:29:37.225] Timer tick, asking Buildbucket for the build status
[08:29:37.547] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:37.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:11:0) after 1m0s
[08:30:37.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:11:0)
[08:30:37.588] Timer tick, asking Buildbucket for the build status
[08:30:38.191] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:38.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:12:0) after 1m0s
[08:31:38.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:12:0)
[08:31:38.210] Timer tick, asking Buildbucket for the build status
[08:31:38.484] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:38.484] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:13:0) after 1m0s
[08:32:38.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:13:0)
[08:32:38.505] Timer tick, asking Buildbucket for the build status
[08:32:38.961] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:38.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:14:0) after 1m0s
[08:33:38.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:14:0)
[08:33:38.996] Timer tick, asking Buildbucket for the build status
[08:33:39.175] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:39.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:15:0) after 1m0s
[08:34:39.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:15:0)
[08:34:39.248] Timer tick, asking Buildbucket for the build status
[08:34:39.444] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:39.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:16:0) after 1m0s
[08:35:39.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:16:0)
[08:35:39.515] Timer tick, asking Buildbucket for the build status
[08:35:39.895] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:39.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:17:0) after 1m0s
[08:36:39.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:17:0)
[08:36:39.972] Timer tick, asking Buildbucket for the build status
[08:36:40.129] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:40.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:18:0) after 1m0s
[08:37:40.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:18:0)
[08:37:40.246] Timer tick, asking Buildbucket for the build status
[08:37:40.626] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:40.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:19:0) after 1m0s
[08:38:40.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:19:0)
[08:38:40.645] Timer tick, asking Buildbucket for the build status
[08:38:41.028] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:41.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:20:0) after 1m0s
[08:39:41.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:20:0)
[08:39:41.046] Timer tick, asking Buildbucket for the build status
[08:39:41.197] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:41.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:21:0) after 1m0s
[08:40:41.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:21:0)
[08:40:41.217] Timer tick, asking Buildbucket for the build status
[08:40:41.552] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:41.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:22:0) after 1m0s
[08:41:41.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:22:0)
[08:41:41.597] Timer tick, asking Buildbucket for the build status
[08:41:41.946] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:41.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:23:0) after 1m0s
[08:42:42.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:23:0)
[08:42:42.013] Timer tick, asking Buildbucket for the build status
[08:42:42.738] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:42.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:24:0) after 1m0s
[08:43:42.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:24:0)
[08:43:42.771] Timer tick, asking Buildbucket for the build status
[08:43:42.940] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:42.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:25:0) after 1m0s
[08:44:42.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:25:0)
[08:44:42.960] Timer tick, asking Buildbucket for the build status
[08:44:44.991] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:44.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:26:0) after 1m0s
[08:45:45.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:26:0)
[08:45:45.008] Timer tick, asking Buildbucket for the build status
[08:45:45.213] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:45.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:27:0) after 1m0s
[08:46:45.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:27:0)
[08:46:45.192] Timer tick, asking Buildbucket for the build status
[08:46:45.303] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:45.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:28:0) after 1m0s
[08:47:45.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:28:0)
[08:47:45.330] Timer tick, asking Buildbucket for the build status
[08:47:45.746] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:45.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:29:0) after 1m0s
[08:48:46.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:29:0)
[08:48:46.129] Timer tick, asking Buildbucket for the build status
[08:48:46.317] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:46.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:30:0) after 1m0s
[08:49:46.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:30:0)
[08:49:46.485] Timer tick, asking Buildbucket for the build status
[08:49:46.669] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:46.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:31:0) after 1m0s
[08:50:46.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:31:0)
[08:50:46.847] Timer tick, asking Buildbucket for the build status
[08:50:47.168] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:47.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:32:0) after 1m0s
[08:51:47.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:32:0)
[08:51:47.185] Timer tick, asking Buildbucket for the build status
[08:51:47.478] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:47.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:33:0) after 1m0s
[08:52:47.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:33:0)
[08:52:47.552] Timer tick, asking Buildbucket for the build status
[08:52:47.834] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:47.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:34:0) after 1m0s
[08:53:48.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:34:0)
[08:53:48.109] Timer tick, asking Buildbucket for the build status
[08:53:48.879] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:48.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:35:0) after 1m0s
[08:54:49.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:35:0)
[08:54:49.146] Timer tick, asking Buildbucket for the build status
[08:54:49.532] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:49.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:36:0) after 1m0s
[08:55:49.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:36:0)
[08:55:49.636] Timer tick, asking Buildbucket for the build status
[08:55:49.768] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:49.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:37:0) after 1m0s
[08:56:49.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:37:0)
[08:56:49.835] Timer tick, asking Buildbucket for the build status
[08:56:49.933] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:49.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:38:0) after 1m0s
[08:57:50.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:38:0)
[08:57:50.148] Timer tick, asking Buildbucket for the build status
[08:57:50.896] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:50.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:39:0) after 1m0s
[08:58:50.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:39:0)
[08:58:50.928] Timer tick, asking Buildbucket for the build status
[08:58:51.113] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:51.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:40:0) after 1m0s
[08:59:51.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:40:0)
[08:59:51.181] Timer tick, asking Buildbucket for the build status
[08:59:51.344] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:51.344] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:41:0) after 1m0s
[09:00:51.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:41:0)
[09:00:51.573] Timer tick, asking Buildbucket for the build status
[09:00:51.748] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:51.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:42:0) after 1m0s
[09:01:52.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:42:0)
[09:01:52.028] Timer tick, asking Buildbucket for the build status
[09:01:52.254] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:52.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:43:0) after 1m0s
[09:02:52.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:43:0)
[09:02:52.422] Timer tick, asking Buildbucket for the build status
[09:02:52.621] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:52.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:44:0) after 1m0s
[09:03:53.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:44:0)
[09:03:53.514] Timer tick, asking Buildbucket for the build status
[09:03:53.860] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:53.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:45:0) after 1m0s
[09:04:54.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:45:0)
[09:04:54.125] Timer tick, asking Buildbucket for the build status
[09:04:54.301] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:54.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:46:0) after 1m0s
[09:05:54.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:46:0)
[09:05:54.427] Timer tick, asking Buildbucket for the build status
[09:05:55.023] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:55.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:47:0) after 1m0s
[09:06:55.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:47:0)
[09:06:55.123] Timer tick, asking Buildbucket for the build status
[09:06:55.303] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:55.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:48:0) after 1m0s
[09:07:55.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:48:0)
[09:07:55.425] Timer tick, asking Buildbucket for the build status
[09:07:55.574] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:55.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:49:0) after 1m0s
[09:08:55.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:49:0)
[09:08:55.555] Timer tick, asking Buildbucket for the build status
[09:08:55.949] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:55.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:50:0) after 1m0s
[09:09:56.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:50:0)
[09:09:56.169] Timer tick, asking Buildbucket for the build status
[09:09:56.696] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:56.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:51:0) after 1m0s
[09:10:56.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:51:0)
[09:10:56.971] Timer tick, asking Buildbucket for the build status
[09:10:57.223] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:57.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:52:0) after 1m0s
[09:11:57.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:52:0)
[09:11:57.263] Timer tick, asking Buildbucket for the build status
[09:11:57.684] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:57.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:53:0) after 1m0s
[09:12:57.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:53:0)
[09:12:57.708] Timer tick, asking Buildbucket for the build status
[09:12:57.851] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:57.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:54:0) after 1m0s
[09:13:57.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:54:0)
[09:13:57.869] Timer tick, asking Buildbucket for the build status
[09:13:57.971] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:57.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:55:0) after 1m0s
[09:14:58.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:55:0)
[09:14:58.018] Timer tick, asking Buildbucket for the build status
[09:14:58.171] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:58.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:56:0) after 1m0s
[09:15:58.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:56:0)
[09:15:58.262] Timer tick, asking Buildbucket for the build status
[09:15:58.409] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:58.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:57:0) after 1m0s
[09:16:58.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:57:0)
[09:16:58.431] Timer tick, asking Buildbucket for the build status
[09:16:58.874] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:58.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:58:0) after 1m0s
[09:17:58.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:58:0)
[09:17:58.968] Timer tick, asking Buildbucket for the build status
[09:17:59.302] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:59.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:59:0) after 1m0s
[09:18:59.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:59:0)
[09:18:59.374] Timer tick, asking Buildbucket for the build status
[09:18:59.559] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:59.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:60:0) after 1m0s
[09:19:59.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:60:0)
[09:19:59.660] Timer tick, asking Buildbucket for the build status
[09:19:59.896] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:59.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:61:0) after 1m0s
[09:21:00.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:61:0)
[09:21:00.016] Timer tick, asking Buildbucket for the build status
[09:21:02.347] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:02.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:62:0) after 1m0s
[09:22:02.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:62:0)
[09:22:02.656] Timer tick, asking Buildbucket for the build status
[09:22:02.803] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:02.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:63:0) after 1m0s
[09:23:02.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:63:0)
[09:23:02.960] Timer tick, asking Buildbucket for the build status
[09:23:03.419] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:03.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:64:0) after 1m0s
[09:24:03.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:64:0)
[09:24:03.545] Timer tick, asking Buildbucket for the build status
[09:24:03.675] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:03.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:65:0) after 1m0s
[09:25:03.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:65:0)
[09:25:03.701] Timer tick, asking Buildbucket for the build status
[09:25:03.817] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:03.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:66:0) after 1m0s
[09:26:03.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:66:0)
[09:26:03.845] Timer tick, asking Buildbucket for the build status
[09:26:03.943] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:03.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:67:0) after 1m0s
[09:27:03.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:67:0)
[09:27:03.995] Timer tick, asking Buildbucket for the build status
[09:27:04.200] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:04.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:68:0) after 1m0s
[09:28:04.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:68:0)
[09:28:04.242] Timer tick, asking Buildbucket for the build status
[09:28:04.611] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:04.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:69:0) after 1m0s
[09:29:04.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:69:0)
[09:29:04.656] Timer tick, asking Buildbucket for the build status
[09:29:04.769] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:04.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:70:0) after 1m0s
[09:30:04.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:70:0)
[09:30:04.837] Timer tick, asking Buildbucket for the build status
[09:30:06.096] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:06.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:71:0) after 1m0s
[09:31:06.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:71:0)
[09:31:06.188] Timer tick, asking Buildbucket for the build status
[09:31:06.332] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:06.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:72:0) after 1m0s
[09:32:06.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:72:0)
[09:32:06.428] Timer tick, asking Buildbucket for the build status
[09:32:06.564] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:06.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:73:0) after 1m0s
[09:33:06.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:73:0)
[09:33:06.637] Timer tick, asking Buildbucket for the build status
[09:33:08.465] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:08.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:74:0) after 1m0s
[09:34:08.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:74:0)
[09:34:08.579] Timer tick, asking Buildbucket for the build status
[09:34:08.695] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:08.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:75:0) after 1m0s
[09:35:08.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:75:0)
[09:35:08.771] Timer tick, asking Buildbucket for the build status
[09:35:09.127] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:09.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:76:0) after 1m0s
[09:36:09.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:76:0)
[09:36:09.408] Timer tick, asking Buildbucket for the build status
[09:36:09.525] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:09.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:77:0) after 1m0s
[09:37:09.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:77:0)
[09:37:09.560] Timer tick, asking Buildbucket for the build status
[09:37:09.865] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:09.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:78:0) after 1m0s
[09:38:09.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:78:0)
[09:38:09.907] Timer tick, asking Buildbucket for the build status
[09:38:10.134] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:10.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:79:0) after 1m0s
[09:39:10.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:79:0)
[09:39:10.234] Timer tick, asking Buildbucket for the build status
[09:39:10.471] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:10.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:80:0) after 1m0s
[09:40:10.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:80:0)
[09:40:10.480] Timer tick, asking Buildbucket for the build status
[09:40:10.865] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:10.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:81:0) after 1m0s
[09:41:10.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:81:0)
[09:41:10.954] Timer tick, asking Buildbucket for the build status
[09:41:11.066] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:11.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:82:0) after 1m0s
[09:42:11.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:82:0)
[09:42:11.096] Timer tick, asking Buildbucket for the build status
[09:42:11.313] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:11.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:83:0) after 1m0s
[09:43:11.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:83:0)
[09:43:11.308] Timer tick, asking Buildbucket for the build status
[09:43:11.945] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:11.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:84:0) after 1m0s
[09:44:12.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:84:0)
[09:44:12.064] Timer tick, asking Buildbucket for the build status
[09:44:12.281] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:12.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:85:0) after 1m0s
[09:45:12.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:85:0)
[09:45:12.367] Timer tick, asking Buildbucket for the build status
[09:45:12.484] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:12.484] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:86:0) after 1m0s
[09:46:12.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:86:0)
[09:46:12.508] Timer tick, asking Buildbucket for the build status
[09:46:12.849] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:12.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:87:0) after 1m0s
[09:47:12.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:87:0)
[09:47:12.897] Timer tick, asking Buildbucket for the build status
[09:47:13.015] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:13.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:88:0) after 1m0s
[09:48:13.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:88:0)
[09:48:13.144] Timer tick, asking Buildbucket for the build status
[09:48:13.359] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:13.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:89:0) after 1m0s
[09:49:13.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:89:0)
[09:49:13.386] Timer tick, asking Buildbucket for the build status
[09:49:13.559] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:13.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:90:0) after 1m0s
[09:50:13.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:90:0)
[09:50:13.609] Timer tick, asking Buildbucket for the build status
[09:50:13.894] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:13.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:91:0) after 1m0s
[09:51:13.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:91:0)
[09:51:13.949] Timer tick, asking Buildbucket for the build status
[09:51:14.175] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:14.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:92:0) after 1m0s
[09:52:14.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:92:0)
[09:52:14.256] Timer tick, asking Buildbucket for the build status
[09:52:14.405] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:14.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:93:0) after 1m0s
[09:53:14.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:93:0)
[09:53:14.714] Timer tick, asking Buildbucket for the build status
[09:53:14.979] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:14.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:94:0) after 1m0s
[09:54:15.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:94:0)
[09:54:15.147] Timer tick, asking Buildbucket for the build status
[09:54:16.059] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:16.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:95:0) after 1m0s
[09:55:16.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:95:0)
[09:55:16.106] Timer tick, asking Buildbucket for the build status
[09:55:16.613] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:16.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:96:0) after 1m0s
[09:56:16.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:96:0)
[09:56:16.643] Timer tick, asking Buildbucket for the build status
[09:56:17.124] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:17.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:97:0) after 1m0s
[09:57:17.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:97:0)
[09:57:17.194] Timer tick, asking Buildbucket for the build status
[09:57:17.542] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:17.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:98:0) after 1m0s
[09:58:17.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:98:0)
[09:58:17.659] Timer tick, asking Buildbucket for the build status
[09:58:18.112] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:18.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:99:0) after 1m0s
[09:59:18.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:99:0)
[09:59:18.217] Timer tick, asking Buildbucket for the build status
[09:59:18.406] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:18.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:100:0) after 1m0s
[10:00:18.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:100:0)
[10:00:18.453] Timer tick, asking Buildbucket for the build status
[10:00:18.592] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:18.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:101:0) after 1m0s
[10:01:18.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:101:0)
[10:01:18.744] Timer tick, asking Buildbucket for the build status
[10:01:18.876] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:18.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:102:0) after 1m0s
[10:02:18.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:102:0)
[10:02:18.927] Timer tick, asking Buildbucket for the build status
[10:02:19.230] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:19.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:103:0) after 1m0s
[10:03:19.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:103:0)
[10:03:19.380] Timer tick, asking Buildbucket for the build status
[10:03:19.540] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:19.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:104:0) after 1m0s
[10:04:19.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:104:0)
[10:04:19.686] Timer tick, asking Buildbucket for the build status
[10:04:20.012] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:20.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:105:0) after 1m0s
[10:05:20.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:105:0)
[10:05:20.092] Timer tick, asking Buildbucket for the build status
[10:05:20.317] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:20.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:106:0) after 1m0s
[10:06:20.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:106:0)
[10:06:20.388] Timer tick, asking Buildbucket for the build status
[10:06:20.535] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:20.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:107:0) after 1m0s
[10:07:20.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:107:0)
[10:07:20.602] Timer tick, asking Buildbucket for the build status
[10:07:20.754] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:20.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:108:0) after 1m0s
[10:08:20.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:108:0)
[10:08:20.807] Timer tick, asking Buildbucket for the build status
[10:08:20.976] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:20.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:109:0) after 1m0s
[10:09:21.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:109:0)
[10:09:21.001] Timer tick, asking Buildbucket for the build status
[10:09:21.241] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:21.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:110:0) after 1m0s
[10:10:21.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:110:0)
[10:10:21.317] Timer tick, asking Buildbucket for the build status
[10:10:21.660] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:21.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:111:0) after 1m0s
[10:11:21.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:111:0)
[10:11:21.681] Timer tick, asking Buildbucket for the build status
[10:11:21.888] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:21.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:112:0) after 1m0s
[10:12:21.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:112:0)
[10:12:21.972] Timer tick, asking Buildbucket for the build status
[10:12:22.182] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:22.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:113:0) after 1m0s
[10:13:22.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:113:0)
[10:13:22.213] Timer tick, asking Buildbucket for the build status
[10:13:22.478] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:22.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:114:0) after 1m0s
[10:14:22.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:114:0)
[10:14:22.491] Timer tick, asking Buildbucket for the build status
[10:14:22.751] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:22.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:115:0) after 1m0s
[10:15:22.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:115:0)
[10:15:22.772] Timer tick, asking Buildbucket for the build status
[10:15:23.172] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:23.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:116:0) after 1m0s
[10:16:23.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:116:0)
[10:16:23.382] Timer tick, asking Buildbucket for the build status
[10:16:23.614] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:23.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:117:0) after 1m0s
[10:17:23.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:117:0)
[10:17:23.672] Timer tick, asking Buildbucket for the build status
[10:17:24.123] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:24.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:118:0) after 1m0s
[10:18:24.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:118:0)
[10:18:24.150] Timer tick, asking Buildbucket for the build status
[10:18:24.310] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:24.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:119:0) after 1m0s
[10:19:24.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:119:0)
[10:19:24.435] Timer tick, asking Buildbucket for the build status
[10:19:24.638] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:24.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:120:0) after 1m0s
[10:20:24.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:120:0)
[10:20:24.720] Timer tick, asking Buildbucket for the build status
[10:20:24.925] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:24.925] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:121:0) after 1m0s
[10:21:24.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:121:0)
[10:21:24.996] Timer tick, asking Buildbucket for the build status
[10:21:25.327] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:25.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:122:0) after 1m0s
[10:22:25.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:122:0)
[10:22:25.367] Timer tick, asking Buildbucket for the build status
[10:22:25.617] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:25.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:123:0) after 1m0s
[10:23:25.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:123:0)
[10:23:25.739] Timer tick, asking Buildbucket for the build status
[10:23:25.838] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:25.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:124:0) after 1m0s
[10:24:25.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:124:0)
[10:24:25.935] Timer tick, asking Buildbucket for the build status
[10:24:26.097] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:26.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:125:0) after 1m0s
[10:25:26.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:125:0)
[10:25:26.299] Timer tick, asking Buildbucket for the build status
[10:25:26.568] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:26.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:126:0) after 1m0s
[10:26:26.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:126:0)
[10:26:26.632] Timer tick, asking Buildbucket for the build status
[10:26:26.783] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:26.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:127:0) after 1m0s
[10:27:26.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:127:0)
[10:27:26.905] Timer tick, asking Buildbucket for the build status
[10:27:27.419] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:27.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:128:0) after 1m0s
[10:28:27.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:128:0)
[10:28:27.446] Timer tick, asking Buildbucket for the build status
[10:28:27.546] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:27.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:129:0) after 1m0s
[10:29:27.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:129:0)
[10:29:27.596] Timer tick, asking Buildbucket for the build status
[10:29:27.846] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:27.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:130:0) after 1m0s
[10:30:27.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:130:0)
[10:30:27.929] Timer tick, asking Buildbucket for the build status
[10:30:28.072] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:28.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:131:0) after 1m0s
[10:31:28.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:131:0)
[10:31:28.104] Timer tick, asking Buildbucket for the build status
[10:31:28.242] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:28.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:132:0) after 1m0s
[10:32:28.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:132:0)
[10:32:28.351] Timer tick, asking Buildbucket for the build status
[10:32:28.866] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:28.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:133:0) after 1m0s
[10:33:28.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:133:0)
[10:33:28.890] Timer tick, asking Buildbucket for the build status
[10:33:29.109] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:29.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:134:0) after 1m0s
[10:34:29.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:134:0)
[10:34:29.142] Timer tick, asking Buildbucket for the build status
[10:34:29.315] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:29.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:135:0) after 1m0s
[10:35:29.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:135:0)
[10:35:29.500] Timer tick, asking Buildbucket for the build status
[10:35:29.768] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:29.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:136:0) after 1m0s
[10:36:29.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:136:0)
[10:36:29.835] Timer tick, asking Buildbucket for the build status
[10:36:30.242] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:30.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:137:0) after 1m0s
[10:37:30.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:137:0)
[10:37:30.275] Timer tick, asking Buildbucket for the build status
[10:37:31.363] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:31.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:138:0) after 1m0s
[10:38:31.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:138:0)
[10:38:31.423] Timer tick, asking Buildbucket for the build status
[10:38:31.693] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:31.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:139:0) after 1m0s
[10:39:31.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:139:0)
[10:39:31.716] Timer tick, asking Buildbucket for the build status
[10:39:31.878] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:31.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:140:0) after 1m0s
[10:40:31.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:140:0)
[10:40:31.903] Timer tick, asking Buildbucket for the build status
[10:40:32.022] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:32.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:141:0) after 1m0s
[10:41:32.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:141:0)
[10:41:32.051] Timer tick, asking Buildbucket for the build status
[10:41:32.375] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:32.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:142:0) after 1m0s
[10:42:32.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:142:0)
[10:42:32.365] Timer tick, asking Buildbucket for the build status
[10:42:32.761] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:32.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:143:0) after 1m0s
[10:43:32.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:143:0)
[10:43:32.831] Timer tick, asking Buildbucket for the build status
[10:43:33.250] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:33.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:144:0) after 1m0s
[10:44:33.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:144:0)
[10:44:33.256] Timer tick, asking Buildbucket for the build status
[10:44:33.487] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:33.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:145:0) after 1m0s
[10:45:33.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:145:0)
[10:45:33.514] Timer tick, asking Buildbucket for the build status
[10:45:34.083] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:34.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:146:0) after 1m0s
[10:46:34.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:146:0)
[10:46:34.170] Timer tick, asking Buildbucket for the build status
[10:46:36.229] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:36.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:147:0) after 1m0s
[10:47:36.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:147:0)
[10:47:36.419] Timer tick, asking Buildbucket for the build status
[10:47:37.203] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:37.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:148:0) after 1m0s
[10:48:37.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:148:0)
[10:48:37.242] Timer tick, asking Buildbucket for the build status
[10:48:37.428] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:37.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:149:0) after 1m0s
[10:49:37.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:149:0)
[10:49:37.466] Timer tick, asking Buildbucket for the build status
[10:49:37.881] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:37.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:150:0) after 1m0s
[10:50:37.902] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:150:0)
[10:50:37.902] Timer tick, asking Buildbucket for the build status
[10:50:38.009] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:38.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:151:0) after 1m0s
[10:51:38.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:151:0)
[10:51:38.043] Timer tick, asking Buildbucket for the build status
[10:51:38.194] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:38.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:152:0) after 1m0s
[10:52:38.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:152:0)
[10:52:38.316] Timer tick, asking Buildbucket for the build status
[10:52:38.664] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:38.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:153:0) after 1m0s
[10:53:38.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:153:0)
[10:53:38.704] Timer tick, asking Buildbucket for the build status
[10:53:38.843] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:38.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:154:0) after 1m0s
[10:54:38.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:154:0)
[10:54:38.849] Timer tick, asking Buildbucket for the build status
[10:54:39.216] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:39.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:155:0) after 1m0s
[10:55:39.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:155:0)
[10:55:39.298] Timer tick, asking Buildbucket for the build status
[10:55:39.442] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:39.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:156:0) after 1m0s
[10:56:39.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:156:0)
[10:56:39.510] Timer tick, asking Buildbucket for the build status
[10:56:39.702] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:39.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:157:0) after 1m0s
[10:57:39.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:157:0)
[10:57:39.740] Timer tick, asking Buildbucket for the build status
[10:57:40.462] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:40.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:158:0) after 1m0s
[10:58:40.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:158:0)
[10:58:40.542] Timer tick, asking Buildbucket for the build status
[10:58:40.743] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:40.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:159:0) after 1m0s
[10:59:40.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:159:0)
[10:59:40.767] Timer tick, asking Buildbucket for the build status
[10:59:40.865] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:40.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:160:0) after 1m0s
[11:00:41.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:160:0)
[11:00:41.024] Timer tick, asking Buildbucket for the build status
[11:00:41.360] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:41.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:161:0) after 1m0s
[11:01:41.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:161:0)
[11:01:41.485] Timer tick, asking Buildbucket for the build status
[11:01:41.885] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:41.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:162:0) after 1m0s
[11:02:41.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:162:0)
[11:02:41.920] Timer tick, asking Buildbucket for the build status
[11:02:43.889] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:43.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:163:0) after 1m0s
[11:03:44.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:163:0)
[11:03:44.016] Timer tick, asking Buildbucket for the build status
[11:03:44.210] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:44.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:164:0) after 1m0s
[11:04:44.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:164:0)
[11:04:44.243] Timer tick, asking Buildbucket for the build status
[11:04:44.402] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:44.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:165:0) after 1m0s
[11:05:44.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:165:0)
[11:05:44.467] Timer tick, asking Buildbucket for the build status
[11:05:44.722] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:44.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:166:0) after 1m0s
[11:06:44.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:166:0)
[11:06:44.772] Timer tick, asking Buildbucket for the build status
[11:06:45.068] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:45.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:167:0) after 1m0s
[11:07:45.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:167:0)
[11:07:45.091] Timer tick, asking Buildbucket for the build status
[11:07:45.599] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:45.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:168:0) after 1m0s
[11:08:45.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:168:0)
[11:08:45.611] Timer tick, asking Buildbucket for the build status
[11:08:45.896] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:45.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:169:0) after 1m0s
[11:09:45.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:169:0)
[11:09:45.920] Timer tick, asking Buildbucket for the build status
[11:09:46.102] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:46.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:170:0) after 1m0s
[11:10:46.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:170:0)
[11:10:46.136] Timer tick, asking Buildbucket for the build status
[11:10:46.677] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:46.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:171:0) after 1m0s
[11:11:46.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:171:0)
[11:11:46.767] Timer tick, asking Buildbucket for the build status
[11:11:47.184] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:47.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:172:0) after 1m0s
[11:12:47.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:172:0)
[11:12:47.208] Timer tick, asking Buildbucket for the build status
[11:12:47.374] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:47.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:173:0) after 1m0s
[11:13:47.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:173:0)
[11:13:47.404] Timer tick, asking Buildbucket for the build status
[11:13:47.562] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:47.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:174:0) after 1m0s
[11:14:47.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:174:0)
[11:14:47.643] Timer tick, asking Buildbucket for the build status
[11:14:47.749] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:47.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:175:0) after 1m0s
[11:15:47.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:175:0)
[11:15:47.827] Timer tick, asking Buildbucket for the build status
[11:15:47.966] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:47.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:176:0) after 1m0s
[11:16:47.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:176:0)
[11:16:47.991] Timer tick, asking Buildbucket for the build status
[11:16:48.147] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:48.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:177:0) after 1m0s
[11:17:48.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:177:0)
[11:17:48.185] Timer tick, asking Buildbucket for the build status
[11:17:48.462] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:48.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:178:0) after 1m0s
[11:18:48.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:178:0)
[11:18:48.494] Timer tick, asking Buildbucket for the build status
[11:18:48.597] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:48.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:179:0) after 1m0s
[11:19:48.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:179:0)
[11:19:48.676] Timer tick, asking Buildbucket for the build status
[11:19:48.799] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:48.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:180:0) after 1m0s
[11:20:48.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:180:0)
[11:20:48.856] Timer tick, asking Buildbucket for the build status
[11:20:49.192] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:49.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:181:0) after 1m0s
[11:21:49.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:181:0)
[11:21:49.215] Timer tick, asking Buildbucket for the build status
[11:21:49.356] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:49.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:182:0) after 1m0s
[11:22:49.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:182:0)
[11:22:49.382] Timer tick, asking Buildbucket for the build status
[11:22:49.776] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:49.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:183:0) after 1m0s
[11:23:49.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:183:0)
[11:23:49.849] Timer tick, asking Buildbucket for the build status
[11:23:50.213] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:50.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:184:0) after 1m0s
[11:24:50.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:184:0)
[11:24:50.241] Timer tick, asking Buildbucket for the build status
[11:24:50.382] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:50.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:185:0) after 1m0s
[11:25:50.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:185:0)
[11:25:50.409] Timer tick, asking Buildbucket for the build status
[11:25:50.715] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:50.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:186:0) after 1m0s
[11:26:50.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:186:0)
[11:26:50.745] Timer tick, asking Buildbucket for the build status
[11:26:50.985] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:50.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:187:0) after 1m0s
[11:27:51.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:187:0)
[11:27:51.081] Timer tick, asking Buildbucket for the build status
[11:27:51.624] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:51.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:188:0) after 1m0s
[11:28:51.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:188:0)
[11:28:51.651] Timer tick, asking Buildbucket for the build status
[11:28:51.982] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:51.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:189:0) after 1m0s
[11:29:52.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:189:0)
[11:29:52.013] Timer tick, asking Buildbucket for the build status
[11:29:52.174] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:52.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:190:0) after 1m0s
[11:30:52.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:190:0)
[11:30:52.199] Timer tick, asking Buildbucket for the build status
[11:30:52.299] Build 8877531862275485952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:52.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042961924274753888:191:0) after 1m0s
[11:31:29.985] Received PubSub notification, asking Buildbucket for the build status
[11:31:30.086] Build 8877531862275485952: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:31:30.086] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1592134288515049",
    "created_by": "project:infra",
    "created_ts": "1592122892077883",
    "id": "8877531862275485952",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4172.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"a5bf6261-89ca-4bfe-83d3-3be749bfe4c2\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4172.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-7d37\"], \"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": "1592122905416667",
    "status": "COMPLETED",
    "status_changed_ts": "1592134289404152",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042961924274753888",
      "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/8877531862275485952/+/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:4ccabcf1b8966910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1592134289525206",
    "url": "https://ci.chromium.org/b/8877531862275485952",
    "utcnow_ts": "1592134290079308"
  }
}
[11:31:30.086] Invocation finished in 3h9m59.298863531s with status SUCCEEDED