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

• parent_buildername:publish_tarball
• user_agent:recipe

[10:53:34.835] New invocation is queued and will start shortly
[10:53:34.835] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:53:35.950] Starting the invocation (attempt 1)
[10:53:35.988] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:53:35.988] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:53:35.989] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9034526839163630224",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"87.0.4264.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"b0c99436-c6a9-4f12-a644-7f958f8fc56b\",\"title\":\"publish_tarball/0\"}]},\"version\":\"87.0.4264.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:9034526839163630224",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[10:53:36.575] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1600167216131622",
    "id": "8869096777136746576",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4264.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b0c99436-c6a9-4f12-a644-7f958f8fc56b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4264.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1600167216403455",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9034526839163630224",
      "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/8869096777136746576/+/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": "1600167216403580",
    "url": "https://ci.chromium.org/b/8869096777136746576",
    "utcnow_ts": "1600167216557668"
  }
}
[10:53:36.575] Task URL: https://ci.chromium.org/b/8869096777136746576
[10:53:36.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:2:0) after 1m0s
[10:54:27.759] Received PubSub notification, asking Buildbucket for the build status
[10:54:28.135] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:36.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:2:0)
[10:54:36.609] Timer tick, asking Buildbucket for the build status
[10:54:36.728] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:36.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:4:0) after 1m0s
[10:55:36.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:4:0)
[10:55:36.763] Timer tick, asking Buildbucket for the build status
[10:55:37.111] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:37.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:5:0) after 1m0s
[10:56:37.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:5:0)
[10:56:37.242] Timer tick, asking Buildbucket for the build status
[10:56:37.392] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:37.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:6:0) after 1m0s
[10:57:37.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:6:0)
[10:57:37.507] Timer tick, asking Buildbucket for the build status
[10:57:37.578] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:37.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:7:0) after 1m0s
[10:58:37.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:7:0)
[10:58:37.624] Timer tick, asking Buildbucket for the build status
[10:58:38.038] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:38.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:8:0) after 1m0s
[10:59:38.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:8:0)
[10:59:38.105] Timer tick, asking Buildbucket for the build status
[10:59:38.626] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:38.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:9:0) after 1m0s
[11:00:38.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:9:0)
[11:00:38.651] Timer tick, asking Buildbucket for the build status
[11:00:38.765] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:38.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:10:0) after 1m0s
[11:01:38.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:10:0)
[11:01:38.767] Timer tick, asking Buildbucket for the build status
[11:01:38.862] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:38.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:11:0) after 1m0s
[11:02:38.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:11:0)
[11:02:38.987] Timer tick, asking Buildbucket for the build status
[11:02:39.200] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:39.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:12:0) after 1m0s
[11:03:39.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:12:0)
[11:03:39.254] Timer tick, asking Buildbucket for the build status
[11:03:39.410] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:39.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:13:0) after 1m0s
[11:04:39.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:13:0)
[11:04:39.430] Timer tick, asking Buildbucket for the build status
[11:04:39.497] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:39.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:14:0) after 1m0s
[11:05:39.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:14:0)
[11:05:39.609] Timer tick, asking Buildbucket for the build status
[11:05:39.670] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:39.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:15:0) after 1m0s
[11:06:39.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:15:0)
[11:06:39.658] Timer tick, asking Buildbucket for the build status
[11:06:40.408] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:40.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:16:0) after 1m0s
[11:07:40.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:16:0)
[11:07:40.631] Timer tick, asking Buildbucket for the build status
[11:07:40.733] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:40.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:17:0) after 1m0s
[11:08:40.785] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:17:0)
[11:08:40.785] Timer tick, asking Buildbucket for the build status
[11:08:40.918] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:40.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:18:0) after 1m0s
[11:09:40.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:18:0)
[11:09:40.996] Timer tick, asking Buildbucket for the build status
[11:09:41.142] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:41.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:19:0) after 1m0s
[11:10:41.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:19:0)
[11:10:41.174] Timer tick, asking Buildbucket for the build status
[11:10:41.235] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:41.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:20:0) after 1m0s
[11:11:41.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:20:0)
[11:11:41.242] Timer tick, asking Buildbucket for the build status
[11:11:41.684] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:41.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:21:0) after 1m0s
[11:12:41.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:21:0)
[11:12:41.747] Timer tick, asking Buildbucket for the build status
[11:12:41.827] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:41.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:22:0) after 1m0s
[11:13:41.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:22:0)
[11:13:41.852] Timer tick, asking Buildbucket for the build status
[11:13:41.946] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:41.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:23:0) after 1m0s
[11:14:42.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:23:0)
[11:14:42.009] Timer tick, asking Buildbucket for the build status
[11:14:44.032] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:44.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:24:0) after 1m0s
[11:15:44.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:24:0)
[11:15:44.058] Timer tick, asking Buildbucket for the build status
[11:15:44.496] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:44.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:25:0) after 1m0s
[11:16:44.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:25:0)
[11:16:44.478] Timer tick, asking Buildbucket for the build status
[11:16:44.569] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:44.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:26:0) after 1m0s
[11:17:44.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:26:0)
[11:17:44.602] Timer tick, asking Buildbucket for the build status
[11:17:44.734] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:44.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:27:0) after 1m0s
[11:18:44.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:27:0)
[11:18:44.925] Timer tick, asking Buildbucket for the build status
[11:18:45.658] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:45.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:28:0) after 1m0s
[11:19:46.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:28:0)
[11:19:46.147] Timer tick, asking Buildbucket for the build status
[11:19:46.338] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:46.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:29:0) after 1m0s
[11:20:46.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:29:0)
[11:20:46.595] Timer tick, asking Buildbucket for the build status
[11:20:46.754] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:46.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:30:0) after 1m0s
[11:21:47.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:30:0)
[11:21:47.046] Timer tick, asking Buildbucket for the build status
[11:21:47.491] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:47.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:31:0) after 1m0s
[11:22:47.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:31:0)
[11:22:47.640] Timer tick, asking Buildbucket for the build status
[11:22:47.704] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:47.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:32:0) after 1m0s
[11:23:47.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:32:0)
[11:23:47.833] Timer tick, asking Buildbucket for the build status
[11:23:47.968] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:47.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:33:0) after 1m0s
[11:24:48.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:33:0)
[11:24:48.061] Timer tick, asking Buildbucket for the build status
[11:24:48.129] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:48.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:34:0) after 1m0s
[11:25:48.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:34:0)
[11:25:48.152] Timer tick, asking Buildbucket for the build status
[11:25:48.363] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:48.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:35:0) after 1m0s
[11:26:48.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:35:0)
[11:26:48.413] Timer tick, asking Buildbucket for the build status
[11:26:48.599] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:48.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:36:0) after 1m0s
[11:27:48.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:36:0)
[11:27:48.666] Timer tick, asking Buildbucket for the build status
[11:27:49.091] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:49.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:37:0) after 1m0s
[11:28:49.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:37:0)
[11:28:49.137] Timer tick, asking Buildbucket for the build status
[11:28:49.313] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:49.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:38:0) after 1m0s
[11:29:49.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:38:0)
[11:29:49.329] Timer tick, asking Buildbucket for the build status
[11:29:49.393] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:49.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:39:0) after 1m0s
[11:30:49.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:39:0)
[11:30:49.435] Timer tick, asking Buildbucket for the build status
[11:30:49.495] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:49.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:40:0) after 1m0s
[11:31:49.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:40:0)
[11:31:49.535] Timer tick, asking Buildbucket for the build status
[11:31:49.712] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:49.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:41:0) after 1m0s
[11:32:49.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:41:0)
[11:32:49.780] Timer tick, asking Buildbucket for the build status
[11:32:49.902] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:49.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:42:0) after 1m0s
[11:33:49.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:42:0)
[11:33:49.933] Timer tick, asking Buildbucket for the build status
[11:33:50.054] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:50.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:43:0) after 1m0s
[11:34:50.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:43:0)
[11:34:50.052] Timer tick, asking Buildbucket for the build status
[11:34:50.131] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:50.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:44:0) after 1m0s
[11:35:50.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:44:0)
[11:35:50.196] Timer tick, asking Buildbucket for the build status
[11:35:50.270] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:50.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:45:0) after 1m0s
[11:36:50.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:45:0)
[11:36:50.338] Timer tick, asking Buildbucket for the build status
[11:36:50.399] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:50.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:46:0) after 1m0s
[11:37:50.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:46:0)
[11:37:50.571] Timer tick, asking Buildbucket for the build status
[11:37:50.633] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:50.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:47:0) after 1m0s
[11:38:50.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:47:0)
[11:38:50.734] Timer tick, asking Buildbucket for the build status
[11:38:50.909] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:50.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:48:0) after 1m0s
[11:39:50.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:48:0)
[11:39:50.955] Timer tick, asking Buildbucket for the build status
[11:39:51.039] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:51.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:49:0) after 1m0s
[11:40:51.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:49:0)
[11:40:51.109] Timer tick, asking Buildbucket for the build status
[11:40:51.328] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:51.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:50:0) after 1m0s
[11:41:51.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:50:0)
[11:41:51.365] Timer tick, asking Buildbucket for the build status
[11:41:51.450] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:51.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:51:0) after 1m0s
[11:42:51.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:51:0)
[11:42:51.473] Timer tick, asking Buildbucket for the build status
[11:42:51.675] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:51.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:52:0) after 1m0s
[11:43:51.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:52:0)
[11:43:51.729] Timer tick, asking Buildbucket for the build status
[11:43:51.866] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:51.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:53:0) after 1m0s
[11:44:51.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:53:0)
[11:44:51.974] Timer tick, asking Buildbucket for the build status
[11:44:52.188] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:52.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:54:0) after 1m0s
[11:45:52.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:54:0)
[11:45:52.345] Timer tick, asking Buildbucket for the build status
[11:45:52.618] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:52.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:55:0) after 1m0s
[11:46:52.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:55:0)
[11:46:52.685] Timer tick, asking Buildbucket for the build status
[11:46:52.781] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:52.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:56:0) after 1m0s
[11:47:52.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:56:0)
[11:47:52.808] Timer tick, asking Buildbucket for the build status
[11:47:52.867] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:52.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:57:0) after 1m0s
[11:48:52.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:57:0)
[11:48:52.882] Timer tick, asking Buildbucket for the build status
[11:48:53.147] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:53.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:58:0) after 1m0s
[11:49:53.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:58:0)
[11:49:53.203] Timer tick, asking Buildbucket for the build status
[11:49:53.269] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:53.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:59:0) after 1m0s
[11:50:53.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:59:0)
[11:50:53.358] Timer tick, asking Buildbucket for the build status
[11:50:53.432] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:53.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:60:0) after 1m0s
[11:51:53.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:60:0)
[11:51:53.556] Timer tick, asking Buildbucket for the build status
[11:51:53.878] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:53.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:61:0) after 1m0s
[11:52:53.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:61:0)
[11:52:53.967] Timer tick, asking Buildbucket for the build status
[11:52:54.036] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:54.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:62:0) after 1m0s
[11:53:54.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:62:0)
[11:53:54.046] Timer tick, asking Buildbucket for the build status
[11:53:54.145] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:54.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:63:0) after 1m0s
[11:54:54.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:63:0)
[11:54:54.155] Timer tick, asking Buildbucket for the build status
[11:54:54.236] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:54.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:64:0) after 1m0s
[11:55:54.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:64:0)
[11:55:54.472] Timer tick, asking Buildbucket for the build status
[11:55:54.537] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:54.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:65:0) after 1m0s
[11:56:54.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:65:0)
[11:56:54.578] Timer tick, asking Buildbucket for the build status
[11:56:55.796] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:55.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:66:0) after 1m0s
[11:57:55.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:66:0)
[11:57:55.926] Timer tick, asking Buildbucket for the build status
[11:57:56.015] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:56.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:67:0) after 1m0s
[11:58:56.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:67:0)
[11:58:56.133] Timer tick, asking Buildbucket for the build status
[11:58:56.579] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:56.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:68:0) after 1m0s
[11:59:56.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:68:0)
[11:59:56.624] Timer tick, asking Buildbucket for the build status
[11:59:56.700] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:56.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:69:0) after 1m0s
[12:00:56.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:69:0)
[12:00:56.803] Timer tick, asking Buildbucket for the build status
[12:00:56.914] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:56.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:70:0) after 1m0s
[12:01:56.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:70:0)
[12:01:56.988] Timer tick, asking Buildbucket for the build status
[12:01:57.094] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:57.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:71:0) after 1m0s
[12:02:57.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:71:0)
[12:02:57.169] Timer tick, asking Buildbucket for the build status
[12:02:57.330] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:57.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:72:0) after 1m0s
[12:03:57.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:72:0)
[12:03:57.330] Timer tick, asking Buildbucket for the build status
[12:03:57.427] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:57.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:73:0) after 1m0s
[12:04:57.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:73:0)
[12:04:57.504] Timer tick, asking Buildbucket for the build status
[12:04:57.566] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:57.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:74:0) after 1m0s
[12:05:57.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:74:0)
[12:05:57.584] Timer tick, asking Buildbucket for the build status
[12:05:57.641] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:57.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:75:0) after 1m0s
[12:06:57.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:75:0)
[12:06:57.678] Timer tick, asking Buildbucket for the build status
[12:06:58.054] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:58.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:76:0) after 1m0s
[12:07:58.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:76:0)
[12:07:58.112] Timer tick, asking Buildbucket for the build status
[12:07:58.172] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:58.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:77:0) after 1m0s
[12:08:58.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:77:0)
[12:08:58.281] Timer tick, asking Buildbucket for the build status
[12:08:58.521] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:58.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:78:0) after 1m0s
[12:09:58.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:78:0)
[12:09:58.611] Timer tick, asking Buildbucket for the build status
[12:09:58.679] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:58.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:79:0) after 1m0s
[12:10:58.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:79:0)
[12:10:58.725] Timer tick, asking Buildbucket for the build status
[12:10:58.907] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:58.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:80:0) after 1m0s
[12:11:58.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:80:0)
[12:11:58.941] Timer tick, asking Buildbucket for the build status
[12:11:59.052] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:59.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:81:0) after 1m0s
[12:12:59.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:81:0)
[12:12:59.086] Timer tick, asking Buildbucket for the build status
[12:12:59.144] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:59.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:82:0) after 1m0s
[12:13:59.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:82:0)
[12:13:59.183] Timer tick, asking Buildbucket for the build status
[12:13:59.293] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:59.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:83:0) after 1m0s
[12:14:59.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:83:0)
[12:14:59.375] Timer tick, asking Buildbucket for the build status
[12:14:59.698] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:59.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:84:0) after 1m0s
[12:15:59.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:84:0)
[12:15:59.752] Timer tick, asking Buildbucket for the build status
[12:15:59.827] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:59.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:85:0) after 1m0s
[12:16:59.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:85:0)
[12:16:59.842] Timer tick, asking Buildbucket for the build status
[12:16:59.972] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:59.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:86:0) after 1m0s
[12:18:00.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:86:0)
[12:18:00.028] Timer tick, asking Buildbucket for the build status
[12:18:00.177] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:00.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:87:0) after 1m0s
[12:19:00.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:87:0)
[12:19:00.323] Timer tick, asking Buildbucket for the build status
[12:19:00.508] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:00.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:88:0) after 1m0s
[12:20:00.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:88:0)
[12:20:00.601] Timer tick, asking Buildbucket for the build status
[12:20:00.684] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:00.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:89:0) after 1m0s
[12:21:00.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:89:0)
[12:21:00.731] Timer tick, asking Buildbucket for the build status
[12:21:00.796] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:00.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:90:0) after 1m0s
[12:22:00.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:90:0)
[12:22:00.817] Timer tick, asking Buildbucket for the build status
[12:22:00.894] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:00.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:91:0) after 1m0s
[12:23:01.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:91:0)
[12:23:01.758] Timer tick, asking Buildbucket for the build status
[12:23:01.828] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:01.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:92:0) after 1m0s
[12:24:01.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:92:0)
[12:24:01.904] Timer tick, asking Buildbucket for the build status
[12:24:02.006] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:02.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:93:0) after 1m0s
[12:25:02.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:93:0)
[12:25:02.286] Timer tick, asking Buildbucket for the build status
[12:25:02.405] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:02.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:94:0) after 1m0s
[12:26:02.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:94:0)
[12:26:02.443] Timer tick, asking Buildbucket for the build status
[12:26:02.540] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:02.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:95:0) after 1m0s
[12:27:02.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:95:0)
[12:27:02.548] Timer tick, asking Buildbucket for the build status
[12:27:02.775] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:02.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:96:0) after 1m0s
[12:28:02.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:96:0)
[12:28:02.877] Timer tick, asking Buildbucket for the build status
[12:28:02.977] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:02.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:97:0) after 1m0s
[12:29:03.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:97:0)
[12:29:03.129] Timer tick, asking Buildbucket for the build status
[12:29:03.355] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:03.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:98:0) after 1m0s
[12:30:03.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:98:0)
[12:30:03.517] Timer tick, asking Buildbucket for the build status
[12:30:03.883] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:03.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:99:0) after 1m0s
[12:31:04.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:99:0)
[12:31:04.070] Timer tick, asking Buildbucket for the build status
[12:31:04.209] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:04.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:100:0) after 1m0s
[12:32:04.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:100:0)
[12:32:04.279] Timer tick, asking Buildbucket for the build status
[12:32:06.559] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:06.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:101:0) after 1m0s
[12:33:06.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:101:0)
[12:33:06.615] Timer tick, asking Buildbucket for the build status
[12:33:06.678] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:06.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:102:0) after 1m0s
[12:34:06.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:102:0)
[12:34:06.755] Timer tick, asking Buildbucket for the build status
[12:34:06.910] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:06.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:103:0) after 1m0s
[12:35:07.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:103:0)
[12:35:07.070] Timer tick, asking Buildbucket for the build status
[12:35:07.286] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:07.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:104:0) after 1m0s
[12:36:07.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:104:0)
[12:36:07.557] Timer tick, asking Buildbucket for the build status
[12:36:07.759] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:07.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:105:0) after 1m0s
[12:37:07.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:105:0)
[12:37:07.886] Timer tick, asking Buildbucket for the build status
[12:37:08.000] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:08.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:106:0) after 1m0s
[12:38:08.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:106:0)
[12:38:08.034] Timer tick, asking Buildbucket for the build status
[12:38:08.395] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:08.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:107:0) after 1m0s
[12:39:08.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:107:0)
[12:39:08.600] Timer tick, asking Buildbucket for the build status
[12:39:08.732] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:08.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:108:0) after 1m0s
[12:40:08.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:108:0)
[12:40:08.815] Timer tick, asking Buildbucket for the build status
[12:40:08.900] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:08.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:109:0) after 1m0s
[12:41:10.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:109:0)
[12:41:10.876] Timer tick, asking Buildbucket for the build status
[12:41:10.947] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:10.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:110:0) after 1m0s
[12:42:11.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:110:0)
[12:42:11.353] Timer tick, asking Buildbucket for the build status
[12:42:11.512] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:11.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:111:0) after 1m0s
[12:43:11.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:111:0)
[12:43:11.832] Timer tick, asking Buildbucket for the build status
[12:43:11.968] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:11.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:112:0) after 1m0s
[12:44:14.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:112:0)
[12:44:14.036] Timer tick, asking Buildbucket for the build status
[12:44:14.223] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:14.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:113:0) after 1m0s
[12:45:15.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:113:0)
[12:45:15.514] Timer tick, asking Buildbucket for the build status
[12:45:15.593] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:15.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:114:0) after 1m0s
[12:46:15.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:114:0)
[12:46:15.800] Timer tick, asking Buildbucket for the build status
[12:46:15.869] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:15.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:115:0) after 1m0s
[12:47:16.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:115:0)
[12:47:16.233] Timer tick, asking Buildbucket for the build status
[12:47:16.883] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:16.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:116:0) after 1m0s
[12:48:17.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:116:0)
[12:48:17.904] Timer tick, asking Buildbucket for the build status
[12:48:17.967] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:17.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:117:0) after 1m0s
[12:49:18.276] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:117:0)
[12:49:18.276] Timer tick, asking Buildbucket for the build status
[12:49:18.413] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:18.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:118:0) after 1m0s
[12:50:18.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:118:0)
[12:50:18.560] Timer tick, asking Buildbucket for the build status
[12:50:18.921] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:18.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:119:0) after 1m0s
[12:51:18.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:119:0)
[12:51:18.983] Timer tick, asking Buildbucket for the build status
[12:51:19.067] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:19.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:120:0) after 1m0s
[12:52:19.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:120:0)
[12:52:19.524] Timer tick, asking Buildbucket for the build status
[12:52:19.705] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:19.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:121:0) after 1m0s
[12:53:19.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:121:0)
[12:53:19.812] Timer tick, asking Buildbucket for the build status
[12:53:19.916] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:19.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:122:0) after 1m0s
[12:54:20.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:122:0)
[12:54:20.081] Timer tick, asking Buildbucket for the build status
[12:54:20.416] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:20.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:123:0) after 1m0s
[12:55:20.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:123:0)
[12:55:20.582] Timer tick, asking Buildbucket for the build status
[12:55:20.653] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:20.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:124:0) after 1m0s
[12:56:20.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:124:0)
[12:56:20.727] Timer tick, asking Buildbucket for the build status
[12:56:20.926] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:20.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:125:0) after 1m0s
[12:57:20.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:125:0)
[12:57:20.951] Timer tick, asking Buildbucket for the build status
[12:57:21.043] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:21.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:126:0) after 1m0s
[12:58:21.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:126:0)
[12:58:21.082] Timer tick, asking Buildbucket for the build status
[12:58:21.143] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:21.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:127:0) after 1m0s
[12:59:21.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:127:0)
[12:59:21.216] Timer tick, asking Buildbucket for the build status
[12:59:21.342] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:21.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:128:0) after 1m0s
[13:00:21.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:128:0)
[13:00:21.664] Timer tick, asking Buildbucket for the build status
[13:00:21.794] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:21.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:129:0) after 1m0s
[13:01:21.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:129:0)
[13:01:21.892] Timer tick, asking Buildbucket for the build status
[13:01:21.972] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:21.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:130:0) after 1m0s
[13:02:22.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:130:0)
[13:02:22.030] Timer tick, asking Buildbucket for the build status
[13:02:22.134] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:22.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:131:0) after 1m0s
[13:03:22.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:131:0)
[13:03:22.178] Timer tick, asking Buildbucket for the build status
[13:03:22.290] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:22.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:132:0) after 1m0s
[13:04:22.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:132:0)
[13:04:22.329] Timer tick, asking Buildbucket for the build status
[13:04:22.916] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:22.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:133:0) after 1m0s
[13:05:22.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:133:0)
[13:05:22.996] Timer tick, asking Buildbucket for the build status
[13:05:23.092] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:23.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:134:0) after 1m0s
[13:06:23.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:134:0)
[13:06:23.199] Timer tick, asking Buildbucket for the build status
[13:06:23.514] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:23.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:135:0) after 1m0s
[13:07:23.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:135:0)
[13:07:23.552] Timer tick, asking Buildbucket for the build status
[13:07:23.618] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:23.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:136:0) after 1m0s
[13:08:23.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:136:0)
[13:08:23.949] Timer tick, asking Buildbucket for the build status
[13:08:24.409] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:24.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:137:0) after 1m0s
[13:09:24.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:137:0)
[13:09:24.526] Timer tick, asking Buildbucket for the build status
[13:09:24.649] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:24.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:138:0) after 1m0s
[13:10:24.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:138:0)
[13:10:24.726] Timer tick, asking Buildbucket for the build status
[13:10:24.825] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:24.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:139:0) after 1m0s
[13:11:24.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:139:0)
[13:11:24.991] Timer tick, asking Buildbucket for the build status
[13:11:25.341] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:25.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:140:0) after 1m0s
[13:12:25.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:140:0)
[13:12:25.451] Timer tick, asking Buildbucket for the build status
[13:12:25.569] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:25.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:141:0) after 1m0s
[13:13:25.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:141:0)
[13:13:25.617] Timer tick, asking Buildbucket for the build status
[13:13:25.790] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:25.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:142:0) after 1m0s
[13:14:25.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:142:0)
[13:14:25.853] Timer tick, asking Buildbucket for the build status
[13:14:26.000] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:26.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:143:0) after 1m0s
[13:15:26.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:143:0)
[13:15:26.002] Timer tick, asking Buildbucket for the build status
[13:15:26.084] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:26.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:144:0) after 1m0s
[13:16:26.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:144:0)
[13:16:26.119] Timer tick, asking Buildbucket for the build status
[13:16:26.209] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:26.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:145:0) after 1m0s
[13:17:26.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:145:0)
[13:17:26.277] Timer tick, asking Buildbucket for the build status
[13:17:26.601] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:26.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:146:0) after 1m0s
[13:18:26.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:146:0)
[13:18:26.692] Timer tick, asking Buildbucket for the build status
[13:18:26.755] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:26.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:147:0) after 1m0s
[13:19:26.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:147:0)
[13:19:26.924] Timer tick, asking Buildbucket for the build status
[13:19:27.032] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:27.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:148:0) after 1m0s
[13:20:27.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:148:0)
[13:20:27.116] Timer tick, asking Buildbucket for the build status
[13:20:27.256] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:27.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:149:0) after 1m0s
[13:21:27.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:149:0)
[13:21:27.303] Timer tick, asking Buildbucket for the build status
[13:21:27.591] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:27.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:150:0) after 1m0s
[13:22:27.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:150:0)
[13:22:27.738] Timer tick, asking Buildbucket for the build status
[13:22:27.876] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:27.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:151:0) after 1m0s
[13:23:27.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:151:0)
[13:23:27.925] Timer tick, asking Buildbucket for the build status
[13:23:28.013] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:28.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:152:0) after 1m0s
[13:24:28.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:152:0)
[13:24:28.090] Timer tick, asking Buildbucket for the build status
[13:24:28.284] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:28.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:153:0) after 1m0s
[13:25:28.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:153:0)
[13:25:28.663] Timer tick, asking Buildbucket for the build status
[13:25:28.769] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:28.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:154:0) after 1m0s
[13:26:28.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:154:0)
[13:26:28.955] Timer tick, asking Buildbucket for the build status
[13:26:29.051] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:29.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:155:0) after 1m0s
[13:27:29.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:155:0)
[13:27:29.371] Timer tick, asking Buildbucket for the build status
[13:27:29.664] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:29.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:156:0) after 1m0s
[13:28:29.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:156:0)
[13:28:29.837] Timer tick, asking Buildbucket for the build status
[13:28:29.982] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:29.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:157:0) after 1m0s
[13:29:30.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:157:0)
[13:29:30.857] Timer tick, asking Buildbucket for the build status
[13:29:31.045] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:31.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:158:0) after 1m0s
[13:30:31.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:158:0)
[13:30:31.350] Timer tick, asking Buildbucket for the build status
[13:30:31.450] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:31.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:159:0) after 1m0s
[13:31:31.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:159:0)
[13:31:31.601] Timer tick, asking Buildbucket for the build status
[13:31:31.689] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:31.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:160:0) after 1m0s
[13:32:31.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:160:0)
[13:32:31.694] Timer tick, asking Buildbucket for the build status
[13:32:31.778] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:31.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:161:0) after 1m0s
[13:33:31.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:161:0)
[13:33:31.787] Timer tick, asking Buildbucket for the build status
[13:33:31.879] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:31.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:162:0) after 1m0s
[13:34:31.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:162:0)
[13:34:31.936] Timer tick, asking Buildbucket for the build status
[13:34:32.309] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:32.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:163:0) after 1m0s
[13:35:32.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:163:0)
[13:35:32.335] Timer tick, asking Buildbucket for the build status
[13:35:32.520] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:32.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:164:0) after 1m0s
[13:36:32.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:164:0)
[13:36:32.647] Timer tick, asking Buildbucket for the build status
[13:36:32.779] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:32.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:165:0) after 1m0s
[13:37:32.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:165:0)
[13:37:32.806] Timer tick, asking Buildbucket for the build status
[13:37:32.898] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:32.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:166:0) after 1m0s
[13:38:32.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:166:0)
[13:38:32.973] Timer tick, asking Buildbucket for the build status
[13:38:33.120] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:33.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:167:0) after 1m0s
[13:39:33.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:167:0)
[13:39:33.174] Timer tick, asking Buildbucket for the build status
[13:39:33.822] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:33.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:168:0) after 1m0s
[13:40:33.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:168:0)
[13:40:33.810] Timer tick, asking Buildbucket for the build status
[13:40:33.916] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:33.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:169:0) after 1m0s
[13:41:34.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:169:0)
[13:41:34.050] Timer tick, asking Buildbucket for the build status
[13:41:34.323] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:34.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:170:0) after 1m0s
[13:42:34.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:170:0)
[13:42:34.389] Timer tick, asking Buildbucket for the build status
[13:42:34.457] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:34.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:171:0) after 1m0s
[13:43:34.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:171:0)
[13:43:34.703] Timer tick, asking Buildbucket for the build status
[13:43:34.910] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:34.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:172:0) after 1m0s
[13:44:35.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:172:0)
[13:44:35.096] Timer tick, asking Buildbucket for the build status
[13:44:35.285] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:35.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:173:0) after 1m0s
[13:45:35.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:173:0)
[13:45:35.317] Timer tick, asking Buildbucket for the build status
[13:45:35.383] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:35.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:174:0) after 1m0s
[13:46:35.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:174:0)
[13:46:35.461] Timer tick, asking Buildbucket for the build status
[13:46:35.535] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:35.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:175:0) after 1m0s
[13:47:35.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:175:0)
[13:47:35.673] Timer tick, asking Buildbucket for the build status
[13:47:35.818] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:35.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:176:0) after 1m0s
[13:48:35.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:176:0)
[13:48:35.846] Timer tick, asking Buildbucket for the build status
[13:48:35.915] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:35.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:177:0) after 1m0s
[13:49:35.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:177:0)
[13:49:35.932] Timer tick, asking Buildbucket for the build status
[13:49:36.073] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:36.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:178:0) after 1m0s
[13:50:36.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:178:0)
[13:50:36.118] Timer tick, asking Buildbucket for the build status
[13:50:36.240] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:36.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:179:0) after 1m0s
[13:51:36.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:179:0)
[13:51:36.314] Timer tick, asking Buildbucket for the build status
[13:51:36.498] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:36.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:180:0) after 1m0s
[13:52:36.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:180:0)
[13:52:36.552] Timer tick, asking Buildbucket for the build status
[13:52:36.738] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:36.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:181:0) after 1m0s
[13:53:36.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:181:0)
[13:53:36.790] Timer tick, asking Buildbucket for the build status
[13:53:36.924] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:36.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:182:0) after 1m0s
[13:54:36.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:182:0)
[13:54:36.916] Timer tick, asking Buildbucket for the build status
[13:54:37.032] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:37.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:183:0) after 1m0s
[13:55:37.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:183:0)
[13:55:37.068] Timer tick, asking Buildbucket for the build status
[13:55:37.138] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:37.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:184:0) after 1m0s
[13:56:37.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:184:0)
[13:56:37.182] Timer tick, asking Buildbucket for the build status
[13:56:37.252] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:37.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:185:0) after 1m0s
[13:57:37.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:185:0)
[13:57:37.287] Timer tick, asking Buildbucket for the build status
[13:57:37.353] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:37.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:186:0) after 1m0s
[13:58:37.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:186:0)
[13:58:37.477] Timer tick, asking Buildbucket for the build status
[13:58:37.613] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:37.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:187:0) after 1m0s
[13:59:37.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:187:0)
[13:59:37.651] Timer tick, asking Buildbucket for the build status
[13:59:37.947] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:37.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:188:0) after 1m0s
[14:00:38.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:188:0)
[14:00:38.022] Timer tick, asking Buildbucket for the build status
[14:00:38.139] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:38.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:189:0) after 1m0s
[14:01:38.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:189:0)
[14:01:38.183] Timer tick, asking Buildbucket for the build status
[14:01:38.341] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:38.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:190:0) after 1m0s
[14:02:38.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:190:0)
[14:02:38.430] Timer tick, asking Buildbucket for the build status
[14:02:38.821] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:38.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:191:0) after 1m0s
[14:03:38.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:191:0)
[14:03:38.918] Timer tick, asking Buildbucket for the build status
[14:03:39.118] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:39.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:192:0) after 1m0s
[14:04:39.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:192:0)
[14:04:39.251] Timer tick, asking Buildbucket for the build status
[14:04:39.526] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:39.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:193:0) after 1m0s
[14:05:39.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:193:0)
[14:05:39.660] Timer tick, asking Buildbucket for the build status
[14:05:39.726] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:39.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:194:0) after 1m0s
[14:06:41.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:194:0)
[14:06:41.804] Timer tick, asking Buildbucket for the build status
[14:06:41.878] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:41.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:195:0) after 1m0s
[14:07:41.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:195:0)
[14:07:41.865] Timer tick, asking Buildbucket for the build status
[14:07:41.921] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:41.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:196:0) after 1m0s
[14:08:41.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:196:0)
[14:08:41.979] Timer tick, asking Buildbucket for the build status
[14:08:42.078] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:42.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:197:0) after 1m0s
[14:09:42.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:197:0)
[14:09:42.174] Timer tick, asking Buildbucket for the build status
[14:09:42.335] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:42.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:198:0) after 1m0s
[14:10:42.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:198:0)
[14:10:42.385] Timer tick, asking Buildbucket for the build status
[14:10:42.539] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:42.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:199:0) after 1m0s
[14:11:42.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:199:0)
[14:11:42.604] Timer tick, asking Buildbucket for the build status
[14:11:42.740] Build 8869096777136746576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:42.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034526839163630224:200:0) after 1m0s
[14:12:07.716] Received PubSub notification, asking Buildbucket for the build status
[14:12:07.858] Build 8869096777136746576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:12:07.858] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1600179126856244",
    "created_by": "project:infra",
    "created_ts": "1600167216131622",
    "id": "8869096777136746576",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4264.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b0c99436-c6a9-4f12-a644-7f958f8fc56b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4264.0\"}}",
    "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-b2yr\"], \"image\": [\"chrome-xenial-20091300-9b3346fad90\"], \"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\": [\"5522-67570db\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1600167261889213",
    "status": "COMPLETED",
    "status_changed_ts": "1600179127249345",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9034526839163630224",
      "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/8869096777136746576/+/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:4eaa378f668e1610",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1600179127263245",
    "url": "https://ci.chromium.org/b/8869096777136746576",
    "utcnow_ts": "1600179127839580"
  }
}
[14:12:07.858] Invocation finished in 3h18m33.04398718s with status SUCCEEDED