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

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

{
  "version": "91.0.4441.3"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[01:17:11.643] New invocation is queued and will start shortly
[01:17:11.643] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[01:17:12.767] Starting the invocation (attempt 1)
[01:17:12.825] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:17:12.825] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:17:12.825] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9018618036176660608",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"91.0.4441.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"27106de8-430e-4b9a-8ab4-cb04b9d78b48\",\"title\":\"publish_tarball/0\"}]},\"version\":\"91.0.4441.3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9018618036176660608",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[01:17:13.363] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1615339033014078",
    "id": "8853187974076107136",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4441.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"27106de8-430e-4b9a-8ab4-cb04b9d78b48\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4441.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1615339033178937",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9018618036176660608",
      "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/8853187974076107136/+/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": "1615339033179050",
    "url": "https://ci.chromium.org/b/8853187974076107136",
    "utcnow_ts": "1615339033355745"
  }
}
[01:17:13.363] Task URL: https://ci.chromium.org/b/8853187974076107136
[01:17:13.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:2:0) after 1m0s
[01:17:37.976] Received PubSub notification, asking Buildbucket for the build status
[01:17:38.128] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:13.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:2:0)
[01:18:13.416] Timer tick, asking Buildbucket for the build status
[01:18:13.485] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:13.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:4:0) after 1m0s
[01:19:13.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:4:0)
[01:19:13.565] Timer tick, asking Buildbucket for the build status
[01:19:13.862] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:13.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:5:0) after 1m0s
[01:20:13.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:5:0)
[01:20:13.929] Timer tick, asking Buildbucket for the build status
[01:20:14.142] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:14.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:6:0) after 1m0s
[01:21:14.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:6:0)
[01:21:14.203] Timer tick, asking Buildbucket for the build status
[01:21:14.286] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:14.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:7:0) after 1m0s
[01:22:14.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:7:0)
[01:22:14.293] Timer tick, asking Buildbucket for the build status
[01:22:14.358] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:14.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:8:0) after 1m0s
[01:23:14.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:8:0)
[01:23:14.421] Timer tick, asking Buildbucket for the build status
[01:23:14.500] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:14.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:9:0) after 1m0s
[01:24:14.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:9:0)
[01:24:14.540] Timer tick, asking Buildbucket for the build status
[01:24:14.664] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:14.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:10:0) after 1m0s
[01:25:14.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:10:0)
[01:25:14.741] Timer tick, asking Buildbucket for the build status
[01:25:14.862] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:14.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:11:0) after 1m0s
[01:26:14.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:11:0)
[01:26:14.920] Timer tick, asking Buildbucket for the build status
[01:26:14.986] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:14.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:12:0) after 1m0s
[01:27:15.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:12:0)
[01:27:15.013] Timer tick, asking Buildbucket for the build status
[01:27:15.155] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:15.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:13:0) after 1m0s
[01:28:15.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:13:0)
[01:28:15.164] Timer tick, asking Buildbucket for the build status
[01:28:15.318] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:15.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:14:0) after 1m0s
[01:29:15.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:14:0)
[01:29:15.472] Timer tick, asking Buildbucket for the build status
[01:29:15.548] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:15.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:15:0) after 1m0s
[01:30:15.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:15:0)
[01:30:15.771] Timer tick, asking Buildbucket for the build status
[01:30:15.869] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:15.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:16:0) after 1m0s
[01:31:16.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:16:0)
[01:31:16.082] Timer tick, asking Buildbucket for the build status
[01:31:16.322] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:16.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:17:0) after 1m0s
[01:32:16.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:17:0)
[01:32:16.350] Timer tick, asking Buildbucket for the build status
[01:32:16.408] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:16.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:18:0) after 1m0s
[01:33:16.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:18:0)
[01:33:16.455] Timer tick, asking Buildbucket for the build status
[01:33:16.705] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:16.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:19:0) after 1m0s
[01:34:16.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:19:0)
[01:34:16.747] Timer tick, asking Buildbucket for the build status
[01:34:16.846] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:16.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:20:0) after 1m0s
[01:35:16.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:20:0)
[01:35:16.917] Timer tick, asking Buildbucket for the build status
[01:35:17.109] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:17.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:21:0) after 1m0s
[01:36:17.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:21:0)
[01:36:17.161] Timer tick, asking Buildbucket for the build status
[01:36:17.425] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:17.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:22:0) after 1m0s
[01:37:17.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:22:0)
[01:37:17.449] Timer tick, asking Buildbucket for the build status
[01:37:17.590] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:17.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:23:0) after 1m0s
[01:38:17.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:23:0)
[01:38:17.628] Timer tick, asking Buildbucket for the build status
[01:38:18.031] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:18.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:24:0) after 1m0s
[01:39:18.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:24:0)
[01:39:18.046] Timer tick, asking Buildbucket for the build status
[01:39:18.127] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:18.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:25:0) after 1m0s
[01:40:18.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:25:0)
[01:40:18.180] Timer tick, asking Buildbucket for the build status
[01:40:18.241] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:18.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:26:0) after 1m0s
[01:41:18.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:26:0)
[01:41:18.266] Timer tick, asking Buildbucket for the build status
[01:41:18.328] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:18.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:27:0) after 1m0s
[01:42:18.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:27:0)
[01:42:18.394] Timer tick, asking Buildbucket for the build status
[01:42:18.491] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:18.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:28:0) after 1m0s
[01:43:18.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:28:0)
[01:43:18.557] Timer tick, asking Buildbucket for the build status
[01:43:18.777] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:18.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:29:0) after 1m0s
[01:44:18.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:29:0)
[01:44:18.770] Timer tick, asking Buildbucket for the build status
[01:44:18.958] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:18.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:30:0) after 1m0s
[01:45:18.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:30:0)
[01:45:18.946] Timer tick, asking Buildbucket for the build status
[01:45:19.129] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:19.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:31:0) after 1m0s
[01:46:19.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:31:0)
[01:46:19.119] Timer tick, asking Buildbucket for the build status
[01:46:19.182] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:19.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:32:0) after 1m0s
[01:47:19.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:32:0)
[01:47:19.150] Timer tick, asking Buildbucket for the build status
[01:47:19.242] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:19.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:33:0) after 1m0s
[01:48:19.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:33:0)
[01:48:19.333] Timer tick, asking Buildbucket for the build status
[01:48:19.418] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:19.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:34:0) after 1m0s
[01:49:19.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:34:0)
[01:49:19.448] Timer tick, asking Buildbucket for the build status
[01:49:19.549] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:19.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:35:0) after 1m0s
[01:50:19.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:35:0)
[01:50:19.631] Timer tick, asking Buildbucket for the build status
[01:50:19.703] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:19.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:36:0) after 1m0s
[01:51:19.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:36:0)
[01:51:19.713] Timer tick, asking Buildbucket for the build status
[01:51:20.025] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:20.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:37:0) after 1m0s
[01:52:20.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:37:0)
[01:52:20.089] Timer tick, asking Buildbucket for the build status
[01:52:20.188] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:20.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:38:0) after 1m0s
[01:53:20.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:38:0)
[01:53:20.251] Timer tick, asking Buildbucket for the build status
[01:53:20.349] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:20.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:39:0) after 1m0s
[01:54:20.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:39:0)
[01:54:20.389] Timer tick, asking Buildbucket for the build status
[01:54:20.601] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:20.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:40:0) after 1m0s
[01:55:20.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:40:0)
[01:55:20.724] Timer tick, asking Buildbucket for the build status
[01:55:20.878] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:20.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:41:0) after 1m0s
[01:56:20.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:41:0)
[01:56:20.909] Timer tick, asking Buildbucket for the build status
[01:56:20.980] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:20.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:42:0) after 1m0s
[01:57:21.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:42:0)
[01:57:21.106] Timer tick, asking Buildbucket for the build status
[01:57:21.231] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:21.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:43:0) after 1m0s
[01:58:21.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:43:0)
[01:58:21.329] Timer tick, asking Buildbucket for the build status
[01:58:21.616] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:21.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:44:0) after 1m0s
[01:59:21.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:44:0)
[01:59:21.686] Timer tick, asking Buildbucket for the build status
[01:59:21.749] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:21.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:45:0) after 1m0s
[02:00:21.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:45:0)
[02:00:21.794] Timer tick, asking Buildbucket for the build status
[02:00:21.854] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:21.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:46:0) after 1m0s
[02:01:21.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:46:0)
[02:01:21.910] Timer tick, asking Buildbucket for the build status
[02:01:21.972] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:21.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:47:0) after 1m0s
[02:02:21.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:47:0)
[02:02:21.996] Timer tick, asking Buildbucket for the build status
[02:02:22.229] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:22.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:48:0) after 1m0s
[02:03:22.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:48:0)
[02:03:22.247] Timer tick, asking Buildbucket for the build status
[02:03:22.330] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:22.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:49:0) after 1m0s
[02:04:22.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:49:0)
[02:04:22.388] Timer tick, asking Buildbucket for the build status
[02:04:22.481] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:22.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:50:0) after 1m0s
[02:05:22.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:50:0)
[02:05:22.570] Timer tick, asking Buildbucket for the build status
[02:05:22.726] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:22.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:51:0) after 1m0s
[02:06:22.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:51:0)
[02:06:22.749] Timer tick, asking Buildbucket for the build status
[02:06:22.815] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:22.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:52:0) after 1m0s
[02:07:22.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:52:0)
[02:07:22.822] Timer tick, asking Buildbucket for the build status
[02:07:22.899] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:22.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:53:0) after 1m0s
[02:08:22.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:53:0)
[02:08:22.954] Timer tick, asking Buildbucket for the build status
[02:08:23.028] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:23.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:54:0) after 1m0s
[02:09:23.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:54:0)
[02:09:23.135] Timer tick, asking Buildbucket for the build status
[02:09:23.333] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:23.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:55:0) after 1m0s
[02:10:23.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:55:0)
[02:10:23.378] Timer tick, asking Buildbucket for the build status
[02:10:23.495] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:23.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:56:0) after 1m0s
[02:11:23.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:56:0)
[02:11:23.670] Timer tick, asking Buildbucket for the build status
[02:11:23.840] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:23.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:57:0) after 1m0s
[02:12:23.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:57:0)
[02:12:23.888] Timer tick, asking Buildbucket for the build status
[02:12:24.128] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:24.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:58:0) after 1m0s
[02:13:24.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:58:0)
[02:13:24.154] Timer tick, asking Buildbucket for the build status
[02:13:24.260] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:24.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:59:0) after 1m0s
[02:14:24.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:59:0)
[02:14:24.314] Timer tick, asking Buildbucket for the build status
[02:14:24.435] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:24.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:60:0) after 1m0s
[02:15:24.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:60:0)
[02:15:24.442] Timer tick, asking Buildbucket for the build status
[02:15:24.538] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:24.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:61:0) after 1m0s
[02:16:24.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:61:0)
[02:16:24.586] Timer tick, asking Buildbucket for the build status
[02:16:24.647] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:24.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:62:0) after 1m0s
[02:17:24.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:62:0)
[02:17:24.737] Timer tick, asking Buildbucket for the build status
[02:17:24.893] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:24.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:63:0) after 1m0s
[02:18:24.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:63:0)
[02:18:24.935] Timer tick, asking Buildbucket for the build status
[02:18:25.106] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:25.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:64:0) after 1m0s
[02:19:25.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:64:0)
[02:19:25.135] Timer tick, asking Buildbucket for the build status
[02:19:25.199] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:25.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:65:0) after 1m0s
[02:20:25.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:65:0)
[02:20:25.304] Timer tick, asking Buildbucket for the build status
[02:20:25.369] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:25.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:66:0) after 1m0s
[02:21:25.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:66:0)
[02:21:25.412] Timer tick, asking Buildbucket for the build status
[02:21:25.489] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:25.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:67:0) after 1m0s
[02:22:25.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:67:0)
[02:22:25.665] Timer tick, asking Buildbucket for the build status
[02:22:25.740] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:25.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:68:0) after 1m0s
[02:23:25.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:68:0)
[02:23:25.809] Timer tick, asking Buildbucket for the build status
[02:23:25.873] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:25.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:69:0) after 1m0s
[02:24:25.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:69:0)
[02:24:25.943] Timer tick, asking Buildbucket for the build status
[02:24:26.031] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:26.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:70:0) after 1m0s
[02:25:26.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:70:0)
[02:25:26.027] Timer tick, asking Buildbucket for the build status
[02:25:26.322] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:26.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:71:0) after 1m0s
[02:26:26.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:71:0)
[02:26:26.350] Timer tick, asking Buildbucket for the build status
[02:26:26.603] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:26.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:72:0) after 1m0s
[02:27:26.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:72:0)
[02:27:26.726] Timer tick, asking Buildbucket for the build status
[02:27:27.133] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:27.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:73:0) after 1m0s
[02:28:27.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:73:0)
[02:28:27.160] Timer tick, asking Buildbucket for the build status
[02:28:27.223] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:27.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:74:0) after 1m0s
[02:29:27.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:74:0)
[02:29:27.250] Timer tick, asking Buildbucket for the build status
[02:29:27.331] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:27.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:75:0) after 1m0s
[02:30:27.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:75:0)
[02:30:27.349] Timer tick, asking Buildbucket for the build status
[02:30:27.458] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:27.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:76:0) after 1m0s
[02:31:27.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:76:0)
[02:31:27.481] Timer tick, asking Buildbucket for the build status
[02:31:27.701] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:27.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:77:0) after 1m0s
[02:32:27.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:77:0)
[02:32:27.719] Timer tick, asking Buildbucket for the build status
[02:32:27.825] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:27.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:78:0) after 1m0s
[02:33:27.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:78:0)
[02:33:27.876] Timer tick, asking Buildbucket for the build status
[02:33:27.936] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:27.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:79:0) after 1m0s
[02:34:27.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:79:0)
[02:34:27.957] Timer tick, asking Buildbucket for the build status
[02:34:28.028] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:28.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:80:0) after 1m0s
[02:35:28.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:80:0)
[02:35:28.059] Timer tick, asking Buildbucket for the build status
[02:35:28.128] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:28.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:81:0) after 1m0s
[02:36:28.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:81:0)
[02:36:28.155] Timer tick, asking Buildbucket for the build status
[02:36:28.227] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:28.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:82:0) after 1m0s
[02:37:28.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:82:0)
[02:37:28.268] Timer tick, asking Buildbucket for the build status
[02:37:28.405] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:28.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:83:0) after 1m0s
[02:38:28.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:83:0)
[02:38:28.522] Timer tick, asking Buildbucket for the build status
[02:38:28.928] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:28.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:84:0) after 1m0s
[02:39:32.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:84:0)
[02:39:32.301] Timer tick, asking Buildbucket for the build status
[02:39:32.556] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:32.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:85:0) after 1m0s
[02:40:32.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:85:0)
[02:40:32.750] Timer tick, asking Buildbucket for the build status
[02:40:32.833] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:32.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:86:0) after 1m0s
[02:41:32.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:86:0)
[02:41:32.852] Timer tick, asking Buildbucket for the build status
[02:41:32.963] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:32.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:87:0) after 1m0s
[02:42:32.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:87:0)
[02:42:32.953] Timer tick, asking Buildbucket for the build status
[02:42:33.046] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:33.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:88:0) after 1m0s
[02:43:33.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:88:0)
[02:43:33.144] Timer tick, asking Buildbucket for the build status
[02:43:33.217] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:33.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:89:0) after 1m0s
[02:44:33.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:89:0)
[02:44:33.338] Timer tick, asking Buildbucket for the build status
[02:44:33.402] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:33.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:90:0) after 1m0s
[02:45:33.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:90:0)
[02:45:33.456] Timer tick, asking Buildbucket for the build status
[02:45:33.571] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:33.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:91:0) after 1m0s
[02:46:33.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:91:0)
[02:46:33.616] Timer tick, asking Buildbucket for the build status
[02:46:33.689] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:33.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:92:0) after 1m0s
[02:47:33.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:92:0)
[02:47:33.714] Timer tick, asking Buildbucket for the build status
[02:47:33.811] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:33.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:93:0) after 1m0s
[02:48:33.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:93:0)
[02:48:33.893] Timer tick, asking Buildbucket for the build status
[02:48:33.962] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:33.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:94:0) after 1m0s
[02:49:34.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:94:0)
[02:49:34.067] Timer tick, asking Buildbucket for the build status
[02:49:34.131] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:34.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:95:0) after 1m0s
[02:50:34.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:95:0)
[02:50:34.217] Timer tick, asking Buildbucket for the build status
[02:50:34.295] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:34.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:96:0) after 1m0s
[02:51:34.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:96:0)
[02:51:34.400] Timer tick, asking Buildbucket for the build status
[02:51:34.477] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:34.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:97:0) after 1m0s
[02:52:34.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:97:0)
[02:52:34.581] Timer tick, asking Buildbucket for the build status
[02:52:34.645] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:34.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:98:0) after 1m0s
[02:53:34.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:98:0)
[02:53:34.676] Timer tick, asking Buildbucket for the build status
[02:53:34.962] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:34.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:99:0) after 1m0s
[02:54:35.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:99:0)
[02:54:35.018] Timer tick, asking Buildbucket for the build status
[02:54:35.112] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:35.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:100:0) after 1m0s
[02:55:35.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:100:0)
[02:55:35.129] Timer tick, asking Buildbucket for the build status
[02:55:35.190] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:35.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:101:0) after 1m0s
[02:56:35.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:101:0)
[02:56:35.249] Timer tick, asking Buildbucket for the build status
[02:56:35.331] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:35.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:102:0) after 1m0s
[02:57:35.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:102:0)
[02:57:35.428] Timer tick, asking Buildbucket for the build status
[02:57:35.497] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:35.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:103:0) after 1m0s
[02:58:35.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:103:0)
[02:58:35.588] Timer tick, asking Buildbucket for the build status
[02:58:35.659] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:35.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:104:0) after 1m0s
[02:59:35.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:104:0)
[02:59:35.898] Timer tick, asking Buildbucket for the build status
[02:59:36.026] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:36.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:105:0) after 1m0s
[03:00:36.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:105:0)
[03:00:36.040] Timer tick, asking Buildbucket for the build status
[03:00:36.123] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:36.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:106:0) after 1m0s
[03:01:36.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:106:0)
[03:01:36.308] Timer tick, asking Buildbucket for the build status
[03:01:36.604] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:36.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:107:0) after 1m0s
[03:02:36.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:107:0)
[03:02:36.714] Timer tick, asking Buildbucket for the build status
[03:02:36.840] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:36.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:108:0) after 1m0s
[03:03:36.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:108:0)
[03:03:36.957] Timer tick, asking Buildbucket for the build status
[03:03:37.016] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:37.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:109:0) after 1m0s
[03:04:37.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:109:0)
[03:04:37.066] Timer tick, asking Buildbucket for the build status
[03:04:37.243] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:37.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:110:0) after 1m0s
[03:05:37.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:110:0)
[03:05:37.416] Timer tick, asking Buildbucket for the build status
[03:05:37.490] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:37.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:111:0) after 1m0s
[03:06:37.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:111:0)
[03:06:37.696] Timer tick, asking Buildbucket for the build status
[03:06:37.786] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:37.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:112:0) after 1m0s
[03:07:37.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:112:0)
[03:07:37.761] Timer tick, asking Buildbucket for the build status
[03:07:37.840] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:37.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:113:0) after 1m0s
[03:08:37.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:113:0)
[03:08:37.883] Timer tick, asking Buildbucket for the build status
[03:08:38.037] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:38.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:114:0) after 1m0s
[03:09:38.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:114:0)
[03:09:38.093] Timer tick, asking Buildbucket for the build status
[03:09:38.236] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:38.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:115:0) after 1m0s
[03:10:38.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:115:0)
[03:10:38.305] Timer tick, asking Buildbucket for the build status
[03:10:38.372] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:38.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:116:0) after 1m0s
[03:11:38.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:116:0)
[03:11:38.485] Timer tick, asking Buildbucket for the build status
[03:11:38.546] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:38.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:117:0) after 1m0s
[03:12:38.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:117:0)
[03:12:38.571] Timer tick, asking Buildbucket for the build status
[03:12:38.651] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:38.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:118:0) after 1m0s
[03:13:38.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:118:0)
[03:13:38.711] Timer tick, asking Buildbucket for the build status
[03:13:38.791] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:38.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:119:0) after 1m0s
[03:14:38.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:119:0)
[03:14:38.840] Timer tick, asking Buildbucket for the build status
[03:14:38.933] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:38.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:120:0) after 1m0s
[03:15:38.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:120:0)
[03:15:38.978] Timer tick, asking Buildbucket for the build status
[03:15:39.039] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:39.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:121:0) after 1m0s
[03:16:39.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:121:0)
[03:16:39.120] Timer tick, asking Buildbucket for the build status
[03:16:39.334] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:39.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:122:0) after 1m0s
[03:17:39.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:122:0)
[03:17:39.345] Timer tick, asking Buildbucket for the build status
[03:17:39.474] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:39.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:123:0) after 1m0s
[03:18:39.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:123:0)
[03:18:39.497] Timer tick, asking Buildbucket for the build status
[03:18:39.640] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:39.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:124:0) after 1m0s
[03:19:39.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:124:0)
[03:19:39.666] Timer tick, asking Buildbucket for the build status
[03:19:39.742] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:39.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:125:0) after 1m0s
[03:20:39.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:125:0)
[03:20:39.751] Timer tick, asking Buildbucket for the build status
[03:20:39.833] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:39.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:126:0) after 1m0s
[03:21:39.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:126:0)
[03:21:39.944] Timer tick, asking Buildbucket for the build status
[03:21:40.017] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:40.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:127:0) after 1m0s
[03:22:40.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:127:0)
[03:22:40.191] Timer tick, asking Buildbucket for the build status
[03:22:40.530] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:40.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:128:0) after 1m0s
[03:23:40.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:128:0)
[03:23:40.527] Timer tick, asking Buildbucket for the build status
[03:23:40.611] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:40.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:129:0) after 1m0s
[03:24:40.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:129:0)
[03:24:40.708] Timer tick, asking Buildbucket for the build status
[03:24:40.906] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:40.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:130:0) after 1m0s
[03:25:40.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:130:0)
[03:25:40.908] Timer tick, asking Buildbucket for the build status
[03:25:41.003] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:41.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:131:0) after 1m0s
[03:26:41.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:131:0)
[03:26:41.048] Timer tick, asking Buildbucket for the build status
[03:26:41.207] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:41.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:132:0) after 1m0s
[03:27:41.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:132:0)
[03:27:41.329] Timer tick, asking Buildbucket for the build status
[03:27:41.472] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:41.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:133:0) after 1m0s
[03:28:41.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:133:0)
[03:28:41.495] Timer tick, asking Buildbucket for the build status
[03:28:41.652] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:41.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:134:0) after 1m0s
[03:29:41.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:134:0)
[03:29:41.655] Timer tick, asking Buildbucket for the build status
[03:29:41.802] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:41.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:135:0) after 1m0s
[03:30:41.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:135:0)
[03:30:41.777] Timer tick, asking Buildbucket for the build status
[03:30:41.997] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:41.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:136:0) after 1m0s
[03:31:42.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:136:0)
[03:31:42.070] Timer tick, asking Buildbucket for the build status
[03:31:42.142] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:42.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:137:0) after 1m0s
[03:32:42.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:137:0)
[03:32:42.179] Timer tick, asking Buildbucket for the build status
[03:32:42.482] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:42.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:138:0) after 1m0s
[03:33:42.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:138:0)
[03:33:42.473] Timer tick, asking Buildbucket for the build status
[03:33:42.534] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:42.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:139:0) after 1m0s
[03:34:42.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:139:0)
[03:34:42.568] Timer tick, asking Buildbucket for the build status
[03:34:42.726] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:42.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:140:0) after 1m0s
[03:35:42.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:140:0)
[03:35:42.759] Timer tick, asking Buildbucket for the build status
[03:35:43.036] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:43.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:141:0) after 1m0s
[03:36:43.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:141:0)
[03:36:43.119] Timer tick, asking Buildbucket for the build status
[03:36:43.332] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:43.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:142:0) after 1m0s
[03:37:43.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:142:0)
[03:37:43.357] Timer tick, asking Buildbucket for the build status
[03:37:43.471] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:43.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:143:0) after 1m0s
[03:38:43.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:143:0)
[03:38:43.528] Timer tick, asking Buildbucket for the build status
[03:38:43.783] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:43.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:144:0) after 1m0s
[03:39:43.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:144:0)
[03:39:43.805] Timer tick, asking Buildbucket for the build status
[03:39:43.872] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:43.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:145:0) after 1m0s
[03:40:43.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:145:0)
[03:40:43.917] Timer tick, asking Buildbucket for the build status
[03:40:44.094] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:44.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:146:0) after 1m0s
[03:41:44.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:146:0)
[03:41:44.153] Timer tick, asking Buildbucket for the build status
[03:41:44.306] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:44.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:147:0) after 1m0s
[03:42:44.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:147:0)
[03:42:44.303] Timer tick, asking Buildbucket for the build status
[03:42:44.432] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:44.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:148:0) after 1m0s
[03:43:44.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:148:0)
[03:43:44.510] Timer tick, asking Buildbucket for the build status
[03:43:44.570] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:44.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:149:0) after 1m0s
[03:44:44.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:149:0)
[03:44:44.600] Timer tick, asking Buildbucket for the build status
[03:44:44.665] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:44.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:150:0) after 1m0s
[03:45:44.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:150:0)
[03:45:44.669] Timer tick, asking Buildbucket for the build status
[03:45:44.742] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:44.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:151:0) after 1m0s
[03:46:44.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:151:0)
[03:46:44.776] Timer tick, asking Buildbucket for the build status
[03:46:44.904] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:44.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:152:0) after 1m0s
[03:47:44.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:152:0)
[03:47:44.989] Timer tick, asking Buildbucket for the build status
[03:47:45.411] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:45.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:153:0) after 1m0s
[03:48:45.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:153:0)
[03:48:45.580] Timer tick, asking Buildbucket for the build status
[03:48:45.650] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:45.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:154:0) after 1m0s
[03:49:46.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:154:0)
[03:49:46.139] Timer tick, asking Buildbucket for the build status
[03:49:46.202] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:46.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:155:0) after 1m0s
[03:50:46.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:155:0)
[03:50:46.417] Timer tick, asking Buildbucket for the build status
[03:50:46.510] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:46.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:156:0) after 1m0s
[03:51:46.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:156:0)
[03:51:46.598] Timer tick, asking Buildbucket for the build status
[03:51:46.671] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:46.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:157:0) after 1m0s
[03:52:46.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:157:0)
[03:52:46.691] Timer tick, asking Buildbucket for the build status
[03:52:46.769] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:46.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:158:0) after 1m0s
[03:53:46.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:158:0)
[03:53:46.778] Timer tick, asking Buildbucket for the build status
[03:53:46.849] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:46.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:159:0) after 1m0s
[03:54:46.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:159:0)
[03:54:46.854] Timer tick, asking Buildbucket for the build status
[03:54:46.913] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:46.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:160:0) after 1m0s
[03:55:46.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:160:0)
[03:55:46.934] Timer tick, asking Buildbucket for the build status
[03:55:47.037] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:47.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:161:0) after 1m0s
[03:56:47.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:161:0)
[03:56:47.056] Timer tick, asking Buildbucket for the build status
[03:56:47.129] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:47.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:162:0) after 1m0s
[03:57:47.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:162:0)
[03:57:47.205] Timer tick, asking Buildbucket for the build status
[03:57:47.568] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:47.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:163:0) after 1m0s
[03:58:47.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:163:0)
[03:58:47.586] Timer tick, asking Buildbucket for the build status
[03:58:47.652] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:47.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:164:0) after 1m0s
[03:59:47.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:164:0)
[03:59:47.706] Timer tick, asking Buildbucket for the build status
[03:59:47.771] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:47.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:165:0) after 1m0s
[04:00:47.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:165:0)
[04:00:47.807] Timer tick, asking Buildbucket for the build status
[04:00:47.902] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:47.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:166:0) after 1m0s
[04:01:47.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:166:0)
[04:01:47.934] Timer tick, asking Buildbucket for the build status
[04:01:48.115] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:48.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:167:0) after 1m0s
[04:02:48.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:167:0)
[04:02:48.139] Timer tick, asking Buildbucket for the build status
[04:02:48.230] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:48.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:168:0) after 1m0s
[04:03:48.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:168:0)
[04:03:48.276] Timer tick, asking Buildbucket for the build status
[04:03:48.542] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:48.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:169:0) after 1m0s
[04:04:48.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:169:0)
[04:04:48.574] Timer tick, asking Buildbucket for the build status
[04:04:48.735] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:48.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:170:0) after 1m0s
[04:05:48.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:170:0)
[04:05:48.854] Timer tick, asking Buildbucket for the build status
[04:05:49.112] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:49.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:171:0) after 1m0s
[04:06:49.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:171:0)
[04:06:49.228] Timer tick, asking Buildbucket for the build status
[04:06:49.287] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:49.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:172:0) after 1m0s
[04:07:49.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:172:0)
[04:07:49.304] Timer tick, asking Buildbucket for the build status
[04:07:49.389] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:49.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:173:0) after 1m0s
[04:08:49.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:173:0)
[04:08:49.486] Timer tick, asking Buildbucket for the build status
[04:08:49.551] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:49.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:174:0) after 1m0s
[04:09:49.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:174:0)
[04:09:49.614] Timer tick, asking Buildbucket for the build status
[04:09:49.859] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:49.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:175:0) after 1m0s
[04:10:49.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:175:0)
[04:10:49.831] Timer tick, asking Buildbucket for the build status
[04:10:49.895] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:49.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:176:0) after 1m0s
[04:11:49.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:176:0)
[04:11:49.948] Timer tick, asking Buildbucket for the build status
[04:11:50.031] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:50.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:177:0) after 1m0s
[04:12:50.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:177:0)
[04:12:50.118] Timer tick, asking Buildbucket for the build status
[04:12:50.246] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:50.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:178:0) after 1m0s
[04:13:50.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:178:0)
[04:13:50.295] Timer tick, asking Buildbucket for the build status
[04:13:50.391] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:50.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:179:0) after 1m0s
[04:14:50.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:179:0)
[04:14:50.446] Timer tick, asking Buildbucket for the build status
[04:14:50.753] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:50.753] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:180:0) after 1m0s
[04:15:50.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:180:0)
[04:15:50.772] Timer tick, asking Buildbucket for the build status
[04:15:50.843] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:50.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:181:0) after 1m0s
[04:16:50.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:181:0)
[04:16:50.890] Timer tick, asking Buildbucket for the build status
[04:16:51.045] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:51.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:182:0) after 1m0s
[04:17:51.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:182:0)
[04:17:51.062] Timer tick, asking Buildbucket for the build status
[04:17:51.170] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:51.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:183:0) after 1m0s
[04:18:51.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:183:0)
[04:18:51.237] Timer tick, asking Buildbucket for the build status
[04:18:51.315] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:51.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:184:0) after 1m0s
[04:19:51.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:184:0)
[04:19:51.377] Timer tick, asking Buildbucket for the build status
[04:19:51.550] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:51.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:185:0) after 1m0s
[04:20:51.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:185:0)
[04:20:51.543] Timer tick, asking Buildbucket for the build status
[04:20:51.614] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:51.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:186:0) after 1m0s
[04:21:51.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:186:0)
[04:21:51.638] Timer tick, asking Buildbucket for the build status
[04:21:51.746] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:51.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:187:0) after 1m0s
[04:22:51.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:187:0)
[04:22:51.925] Timer tick, asking Buildbucket for the build status
[04:22:52.145] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:52.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:188:0) after 1m0s
[04:23:52.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:188:0)
[04:23:52.163] Timer tick, asking Buildbucket for the build status
[04:23:52.333] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:52.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:189:0) after 1m0s
[04:24:52.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:189:0)
[04:24:52.341] Timer tick, asking Buildbucket for the build status
[04:24:52.502] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:52.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:190:0) after 1m0s
[04:25:52.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:190:0)
[04:25:52.548] Timer tick, asking Buildbucket for the build status
[04:25:52.620] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:52.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:191:0) after 1m0s
[04:26:52.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:191:0)
[04:26:52.726] Timer tick, asking Buildbucket for the build status
[04:26:52.803] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:52.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:192:0) after 1m0s
[04:27:52.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:192:0)
[04:27:52.958] Timer tick, asking Buildbucket for the build status
[04:27:53.144] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:53.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:193:0) after 1m0s
[04:28:53.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:193:0)
[04:28:53.167] Timer tick, asking Buildbucket for the build status
[04:28:53.234] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:53.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:194:0) after 1m0s
[04:29:53.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:194:0)
[04:29:53.348] Timer tick, asking Buildbucket for the build status
[04:29:53.617] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:53.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:195:0) after 1m0s
[04:30:53.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:195:0)
[04:30:53.690] Timer tick, asking Buildbucket for the build status
[04:30:53.770] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:53.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:196:0) after 1m0s
[04:31:53.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:196:0)
[04:31:53.836] Timer tick, asking Buildbucket for the build status
[04:31:53.996] Build 8853187974076107136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:53.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018618036176660608:197:0) after 1m0s
[04:31:57.235] Received PubSub notification, asking Buildbucket for the build status
[04:32:02.683] Build 8853187974076107136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:32:02.683] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1615350717044002",
    "created_by": "project:infra",
    "created_ts": "1615339033014078",
    "id": "8853187974076107136",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4441.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"27106de8-430e-4b9a-8ab4-cb04b9d78b48\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4441.3\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-zhn2\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5746-91ff3bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1615339057182769",
    "status": "COMPLETED",
    "status_changed_ts": "1615339057783780",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9018618036176660608",
      "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/8853187974076107136/+/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:5232872dd59a2210",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1615350717044905",
    "url": "https://ci.chromium.org/b/8853187974076107136",
    "utcnow_ts": "1615350717496278"
  }
}
[04:32:02.683] Invocation finished in 3h14m51.054236151s with status SUCCEEDED