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

• parent_buildername:publish_tarball
• user_agent:recipe

[17:21:58.213] New invocation is queued and will start shortly
[17:21:58.213] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[17:21:59.294] Starting the invocation (attempt 1)
[17:21:59.336] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:21:59.336] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:21:59.336] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9032418673568945552",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4286.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"8d7ffb89-9c5f-4558-bf10-1c59d91e2c5d\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4286.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:9032418673568945552",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[17:21:59.673] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1602177719414992",
    "id": "8866988611646294160",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4286.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"8d7ffb89-9c5f-4558-bf10-1c59d91e2c5d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4286.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1602177719566639",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9032418673568945552",
      "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/8866988611646294160/+/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": "1602177719566760",
    "url": "https://ci.chromium.org/b/8866988611646294160",
    "utcnow_ts": "1602177719663796"
  }
}
[17:21:59.673] Task URL: https://ci.chromium.org/b/8866988611646294160
[17:21:59.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:2:0) after 1m0s
[17:22:51.816] Received PubSub notification, asking Buildbucket for the build status
[17:22:52.199] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:59.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:2:0)
[17:22:59.727] Timer tick, asking Buildbucket for the build status
[17:22:59.927] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:59.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:4:0) after 1m0s
[17:23:59.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:4:0)
[17:23:59.984] Timer tick, asking Buildbucket for the build status
[17:24:00.259] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:00.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:5:0) after 1m0s
[17:25:00.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:5:0)
[17:25:00.311] Timer tick, asking Buildbucket for the build status
[17:25:00.445] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:00.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:6:0) after 1m0s
[17:26:00.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:6:0)
[17:26:00.443] Timer tick, asking Buildbucket for the build status
[17:26:00.591] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:00.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:7:0) after 1m0s
[17:27:00.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:7:0)
[17:27:00.641] Timer tick, asking Buildbucket for the build status
[17:27:00.718] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:00.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:8:0) after 1m0s
[17:28:01.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:8:0)
[17:28:01.373] Timer tick, asking Buildbucket for the build status
[17:28:01.526] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:01.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:9:0) after 1m0s
[17:29:01.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:9:0)
[17:29:01.666] Timer tick, asking Buildbucket for the build status
[17:29:01.747] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:01.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:10:0) after 1m0s
[17:30:02.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:10:0)
[17:30:02.443] Timer tick, asking Buildbucket for the build status
[17:30:02.516] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:02.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:11:0) after 1m0s
[17:31:02.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:11:0)
[17:31:02.585] Timer tick, asking Buildbucket for the build status
[17:31:02.664] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:02.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:12:0) after 1m0s
[17:32:02.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:12:0)
[17:32:02.680] Timer tick, asking Buildbucket for the build status
[17:32:02.905] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:02.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:13:0) after 1m0s
[17:33:02.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:13:0)
[17:33:02.940] Timer tick, asking Buildbucket for the build status
[17:33:03.015] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:03.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:14:0) after 1m0s
[17:34:03.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:14:0)
[17:34:03.047] Timer tick, asking Buildbucket for the build status
[17:34:03.138] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:03.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:15:0) after 1m0s
[17:35:03.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:15:0)
[17:35:03.175] Timer tick, asking Buildbucket for the build status
[17:35:03.434] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:03.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:16:0) after 1m0s
[17:36:03.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:16:0)
[17:36:03.464] Timer tick, asking Buildbucket for the build status
[17:36:03.872] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:03.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:17:0) after 1m0s
[17:37:03.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:17:0)
[17:37:03.906] Timer tick, asking Buildbucket for the build status
[17:37:04.697] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:04.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:18:0) after 1m0s
[17:38:04.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:18:0)
[17:38:04.720] Timer tick, asking Buildbucket for the build status
[17:38:04.779] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:04.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:19:0) after 1m0s
[17:39:04.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:19:0)
[17:39:04.863] Timer tick, asking Buildbucket for the build status
[17:39:04.982] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:04.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:20:0) after 1m0s
[17:40:05.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:20:0)
[17:40:05.016] Timer tick, asking Buildbucket for the build status
[17:40:05.096] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:05.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:21:0) after 1m0s
[17:41:05.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:21:0)
[17:41:05.113] Timer tick, asking Buildbucket for the build status
[17:41:05.193] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:05.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:22:0) after 1m0s
[17:42:05.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:22:0)
[17:42:05.223] Timer tick, asking Buildbucket for the build status
[17:42:05.371] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:05.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:23:0) after 1m0s
[17:43:05.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:23:0)
[17:43:05.408] Timer tick, asking Buildbucket for the build status
[17:43:05.462] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:05.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:24:0) after 1m0s
[17:44:05.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:24:0)
[17:44:05.507] Timer tick, asking Buildbucket for the build status
[17:44:05.593] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:05.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:25:0) after 1m0s
[17:45:05.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:25:0)
[17:45:05.619] Timer tick, asking Buildbucket for the build status
[17:45:05.682] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:05.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:26:0) after 1m0s
[17:46:05.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:26:0)
[17:46:05.739] Timer tick, asking Buildbucket for the build status
[17:46:06.027] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:06.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:27:0) after 1m0s
[17:47:06.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:27:0)
[17:47:06.045] Timer tick, asking Buildbucket for the build status
[17:47:06.162] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:06.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:28:0) after 1m0s
[17:48:06.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:28:0)
[17:48:06.233] Timer tick, asking Buildbucket for the build status
[17:48:06.330] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:06.330] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:29:0) after 1m0s
[17:49:06.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:29:0)
[17:49:06.314] Timer tick, asking Buildbucket for the build status
[17:49:06.426] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:06.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:30:0) after 1m0s
[17:50:06.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:30:0)
[17:50:06.406] Timer tick, asking Buildbucket for the build status
[17:50:06.476] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:06.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:31:0) after 1m0s
[17:51:06.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:31:0)
[17:51:06.555] Timer tick, asking Buildbucket for the build status
[17:51:06.778] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:06.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:32:0) after 1m0s
[17:52:06.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:32:0)
[17:52:06.822] Timer tick, asking Buildbucket for the build status
[17:52:06.874] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:06.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:33:0) after 1m0s
[17:53:06.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:33:0)
[17:53:06.913] Timer tick, asking Buildbucket for the build status
[17:53:06.983] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:06.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:34:0) after 1m0s
[17:54:07.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:34:0)
[17:54:07.068] Timer tick, asking Buildbucket for the build status
[17:54:07.178] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:07.178] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:35:0) after 1m0s
[17:55:07.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:35:0)
[17:55:07.255] Timer tick, asking Buildbucket for the build status
[17:55:07.315] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:07.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:36:0) after 1m0s
[17:56:07.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:36:0)
[17:56:07.431] Timer tick, asking Buildbucket for the build status
[17:56:07.512] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:07.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:37:0) after 1m0s
[17:57:07.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:37:0)
[17:57:07.922] Timer tick, asking Buildbucket for the build status
[17:57:07.981] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:07.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:38:0) after 1m0s
[17:58:08.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:38:0)
[17:58:08.187] Timer tick, asking Buildbucket for the build status
[17:58:08.322] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:08.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:39:0) after 1m0s
[17:59:08.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:39:0)
[17:59:08.500] Timer tick, asking Buildbucket for the build status
[17:59:08.714] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:08.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:40:0) after 1m0s
[18:00:08.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:40:0)
[18:00:08.722] Timer tick, asking Buildbucket for the build status
[18:00:08.864] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:08.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:41:0) after 1m0s
[18:01:09.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:41:0)
[18:01:09.250] Timer tick, asking Buildbucket for the build status
[18:01:09.684] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:09.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:42:0) after 1m0s
[18:02:09.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:42:0)
[18:02:09.761] Timer tick, asking Buildbucket for the build status
[18:02:09.841] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:09.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:43:0) after 1m0s
[18:03:09.933] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:43:0)
[18:03:09.933] Timer tick, asking Buildbucket for the build status
[18:03:10.062] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:10.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:44:0) after 1m0s
[18:04:10.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:44:0)
[18:04:10.137] Timer tick, asking Buildbucket for the build status
[18:04:10.272] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:10.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:45:0) after 1m0s
[18:05:10.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:45:0)
[18:05:10.478] Timer tick, asking Buildbucket for the build status
[18:05:10.554] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:10.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:46:0) after 1m0s
[18:06:10.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:46:0)
[18:06:10.671] Timer tick, asking Buildbucket for the build status
[18:06:10.751] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:10.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:47:0) after 1m0s
[18:07:10.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:47:0)
[18:07:10.879] Timer tick, asking Buildbucket for the build status
[18:07:11.005] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:11.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:48:0) after 1m0s
[18:08:11.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:48:0)
[18:08:11.138] Timer tick, asking Buildbucket for the build status
[18:08:11.205] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:11.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:49:0) after 1m0s
[18:09:11.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:49:0)
[18:09:11.230] Timer tick, asking Buildbucket for the build status
[18:09:11.351] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:11.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:50:0) after 1m0s
[18:10:11.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:50:0)
[18:10:11.427] Timer tick, asking Buildbucket for the build status
[18:10:11.488] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:11.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:51:0) after 1m0s
[18:11:11.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:51:0)
[18:11:11.517] Timer tick, asking Buildbucket for the build status
[18:11:11.821] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:11.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:52:0) after 1m0s
[18:12:11.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:52:0)
[18:12:11.913] Timer tick, asking Buildbucket for the build status
[18:12:12.068] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:12.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:53:0) after 1m0s
[18:13:12.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:53:0)
[18:13:12.119] Timer tick, asking Buildbucket for the build status
[18:13:12.304] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:12.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:54:0) after 1m0s
[18:14:12.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:54:0)
[18:14:12.344] Timer tick, asking Buildbucket for the build status
[18:14:12.445] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:12.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:55:0) after 1m0s
[18:15:12.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:55:0)
[18:15:12.477] Timer tick, asking Buildbucket for the build status
[18:15:12.544] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:12.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:56:0) after 1m0s
[18:16:12.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:56:0)
[18:16:12.568] Timer tick, asking Buildbucket for the build status
[18:16:12.650] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:12.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:57:0) after 1m0s
[18:17:12.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:57:0)
[18:17:12.739] Timer tick, asking Buildbucket for the build status
[18:17:12.796] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:12.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:58:0) after 1m0s
[18:18:12.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:58:0)
[18:18:12.840] Timer tick, asking Buildbucket for the build status
[18:18:12.910] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:12.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:59:0) after 1m0s
[18:19:13.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:59:0)
[18:19:13.105] Timer tick, asking Buildbucket for the build status
[18:19:13.435] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:13.435] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:60:0) after 1m0s
[18:20:13.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:60:0)
[18:20:13.580] Timer tick, asking Buildbucket for the build status
[18:20:13.708] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:13.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:61:0) after 1m0s
[18:21:13.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:61:0)
[18:21:13.762] Timer tick, asking Buildbucket for the build status
[18:21:13.842] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:13.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:62:0) after 1m0s
[18:22:13.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:62:0)
[18:22:13.938] Timer tick, asking Buildbucket for the build status
[18:22:14.012] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:14.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:63:0) after 1m0s
[18:23:14.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:63:0)
[18:23:14.201] Timer tick, asking Buildbucket for the build status
[18:23:14.270] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:14.270] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:64:0) after 1m0s
[18:24:14.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:64:0)
[18:24:14.356] Timer tick, asking Buildbucket for the build status
[18:24:15.079] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:15.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:65:0) after 1m0s
[18:25:15.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:65:0)
[18:25:15.193] Timer tick, asking Buildbucket for the build status
[18:25:15.267] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:15.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:66:0) after 1m0s
[18:26:15.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:66:0)
[18:26:15.310] Timer tick, asking Buildbucket for the build status
[18:26:15.415] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:15.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:67:0) after 1m0s
[18:27:15.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:67:0)
[18:27:15.507] Timer tick, asking Buildbucket for the build status
[18:27:15.583] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:15.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:68:0) after 1m0s
[18:28:15.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:68:0)
[18:28:15.611] Timer tick, asking Buildbucket for the build status
[18:28:15.999] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:15.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:69:0) after 1m0s
[18:29:16.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:69:0)
[18:29:16.167] Timer tick, asking Buildbucket for the build status
[18:29:16.311] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:16.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:70:0) after 1m0s
[18:30:16.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:70:0)
[18:30:16.405] Timer tick, asking Buildbucket for the build status
[18:30:16.560] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:16.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:71:0) after 1m0s
[18:31:16.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:71:0)
[18:31:16.600] Timer tick, asking Buildbucket for the build status
[18:31:16.656] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:16.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:72:0) after 1m0s
[18:32:16.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:72:0)
[18:32:16.738] Timer tick, asking Buildbucket for the build status
[18:32:16.811] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:16.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:73:0) after 1m0s
[18:33:16.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:73:0)
[18:33:16.839] Timer tick, asking Buildbucket for the build status
[18:33:17.128] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:17.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:74:0) after 1m0s
[18:34:17.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:74:0)
[18:34:17.170] Timer tick, asking Buildbucket for the build status
[18:34:17.292] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:17.292] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:75:0) after 1m0s
[18:35:17.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:75:0)
[18:35:17.385] Timer tick, asking Buildbucket for the build status
[18:35:17.459] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:17.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:76:0) after 1m0s
[18:36:17.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:76:0)
[18:36:17.807] Timer tick, asking Buildbucket for the build status
[18:36:17.858] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:17.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:77:0) after 1m0s
[18:37:18.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:77:0)
[18:37:18.195] Timer tick, asking Buildbucket for the build status
[18:37:18.269] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:18.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:78:0) after 1m0s
[18:38:18.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:78:0)
[18:38:18.656] Timer tick, asking Buildbucket for the build status
[18:38:18.748] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:18.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:79:0) after 1m0s
[18:39:18.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:79:0)
[18:39:18.757] Timer tick, asking Buildbucket for the build status
[18:39:18.865] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:18.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:80:0) after 1m0s
[18:40:19.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:80:0)
[18:40:19.052] Timer tick, asking Buildbucket for the build status
[18:40:21.295] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:21.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:81:0) after 1m0s
[18:41:23.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:81:0)
[18:41:23.012] Timer tick, asking Buildbucket for the build status
[18:41:23.199] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:23.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:82:0) after 1m0s
[18:42:23.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:82:0)
[18:42:23.395] Timer tick, asking Buildbucket for the build status
[18:42:23.526] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:23.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:83:0) after 1m0s
[18:43:23.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:83:0)
[18:43:23.613] Timer tick, asking Buildbucket for the build status
[18:43:23.693] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:23.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:84:0) after 1m0s
[18:44:23.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:84:0)
[18:44:23.720] Timer tick, asking Buildbucket for the build status
[18:44:24.014] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:24.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:85:0) after 1m0s
[18:45:24.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:85:0)
[18:45:24.157] Timer tick, asking Buildbucket for the build status
[18:45:24.227] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:24.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:86:0) after 1m0s
[18:46:24.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:86:0)
[18:46:24.279] Timer tick, asking Buildbucket for the build status
[18:46:24.359] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:24.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:87:0) after 1m0s
[18:47:24.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:87:0)
[18:47:24.379] Timer tick, asking Buildbucket for the build status
[18:47:24.478] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:24.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:88:0) after 1m0s
[18:48:24.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:88:0)
[18:48:24.514] Timer tick, asking Buildbucket for the build status
[18:48:25.243] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:25.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:89:0) after 1m0s
[18:49:25.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:89:0)
[18:49:25.237] Timer tick, asking Buildbucket for the build status
[18:49:25.377] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:25.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:90:0) after 1m0s
[18:50:25.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:90:0)
[18:50:25.475] Timer tick, asking Buildbucket for the build status
[18:50:25.543] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:25.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:91:0) after 1m0s
[18:51:25.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:91:0)
[18:51:25.553] Timer tick, asking Buildbucket for the build status
[18:51:25.608] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:25.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:92:0) after 1m0s
[18:52:25.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:92:0)
[18:52:25.653] Timer tick, asking Buildbucket for the build status
[18:52:25.716] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:25.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:93:0) after 1m0s
[18:53:25.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:93:0)
[18:53:25.742] Timer tick, asking Buildbucket for the build status
[18:53:25.814] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:25.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:94:0) after 1m0s
[18:54:25.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:94:0)
[18:54:25.825] Timer tick, asking Buildbucket for the build status
[18:54:26.003] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:26.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:95:0) after 1m0s
[18:55:26.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:95:0)
[18:55:26.037] Timer tick, asking Buildbucket for the build status
[18:55:26.112] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:26.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:96:0) after 1m0s
[18:56:26.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:96:0)
[18:56:26.191] Timer tick, asking Buildbucket for the build status
[18:56:26.276] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:26.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:97:0) after 1m0s
[18:57:26.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:97:0)
[18:57:26.354] Timer tick, asking Buildbucket for the build status
[18:57:26.428] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:26.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:98:0) after 1m0s
[18:58:26.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:98:0)
[18:58:26.472] Timer tick, asking Buildbucket for the build status
[18:58:26.531] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:26.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:99:0) after 1m0s
[18:59:26.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:99:0)
[18:59:26.615] Timer tick, asking Buildbucket for the build status
[18:59:27.711] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:27.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:100:0) after 1m0s
[19:00:27.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:100:0)
[19:00:27.815] Timer tick, asking Buildbucket for the build status
[19:00:27.877] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:27.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:101:0) after 1m0s
[19:01:27.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:101:0)
[19:01:27.903] Timer tick, asking Buildbucket for the build status
[19:01:28.019] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:28.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:102:0) after 1m0s
[19:02:28.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:102:0)
[19:02:28.077] Timer tick, asking Buildbucket for the build status
[19:02:28.212] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:28.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:103:0) after 1m0s
[19:03:28.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:103:0)
[19:03:28.219] Timer tick, asking Buildbucket for the build status
[19:03:28.280] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:28.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:104:0) after 1m0s
[19:04:28.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:104:0)
[19:04:28.328] Timer tick, asking Buildbucket for the build status
[19:04:28.644] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:28.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:105:0) after 1m0s
[19:05:28.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:105:0)
[19:05:28.771] Timer tick, asking Buildbucket for the build status
[19:05:28.832] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:28.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:106:0) after 1m0s
[19:06:28.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:106:0)
[19:06:28.942] Timer tick, asking Buildbucket for the build status
[19:06:29.015] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:29.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:107:0) after 1m0s
[19:07:29.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:107:0)
[19:07:29.083] Timer tick, asking Buildbucket for the build status
[19:07:29.680] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:29.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:108:0) after 1m0s
[19:08:29.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:108:0)
[19:08:29.794] Timer tick, asking Buildbucket for the build status
[19:08:29.961] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:29.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:109:0) after 1m0s
[19:09:30.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:109:0)
[19:09:30.163] Timer tick, asking Buildbucket for the build status
[19:09:30.303] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:30.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:110:0) after 1m0s
[19:10:30.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:110:0)
[19:10:30.351] Timer tick, asking Buildbucket for the build status
[19:10:30.493] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:30.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:111:0) after 1m0s
[19:11:30.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:111:0)
[19:11:30.501] Timer tick, asking Buildbucket for the build status
[19:11:30.561] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:30.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:112:0) after 1m0s
[19:12:30.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:112:0)
[19:12:30.588] Timer tick, asking Buildbucket for the build status
[19:12:30.698] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:30.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:113:0) after 1m0s
[19:13:30.745] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:113:0)
[19:13:30.745] Timer tick, asking Buildbucket for the build status
[19:13:30.813] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:30.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:114:0) after 1m0s
[19:14:30.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:114:0)
[19:14:30.916] Timer tick, asking Buildbucket for the build status
[19:14:31.037] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:31.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:115:0) after 1m0s
[19:15:31.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:115:0)
[19:15:31.100] Timer tick, asking Buildbucket for the build status
[19:15:31.163] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:31.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:116:0) after 1m0s
[19:16:31.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:116:0)
[19:16:31.533] Timer tick, asking Buildbucket for the build status
[19:16:31.615] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:31.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:117:0) after 1m0s
[19:17:32.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:117:0)
[19:17:32.098] Timer tick, asking Buildbucket for the build status
[19:17:32.198] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:32.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:118:0) after 1m0s
[19:18:32.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:118:0)
[19:18:32.590] Timer tick, asking Buildbucket for the build status
[19:18:32.676] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:32.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:119:0) after 1m0s
[19:19:32.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:119:0)
[19:19:32.907] Timer tick, asking Buildbucket for the build status
[19:19:33.039] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:33.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:120:0) after 1m0s
[19:20:33.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:120:0)
[19:20:33.118] Timer tick, asking Buildbucket for the build status
[19:20:33.261] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:33.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:121:0) after 1m0s
[19:21:33.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:121:0)
[19:21:33.416] Timer tick, asking Buildbucket for the build status
[19:21:33.503] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:33.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:122:0) after 1m0s
[19:22:33.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:122:0)
[19:22:33.588] Timer tick, asking Buildbucket for the build status
[19:22:33.652] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:33.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:123:0) after 1m0s
[19:23:33.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:123:0)
[19:23:33.680] Timer tick, asking Buildbucket for the build status
[19:23:33.771] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:33.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:124:0) after 1m0s
[19:24:33.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:124:0)
[19:24:33.995] Timer tick, asking Buildbucket for the build status
[19:24:34.424] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:34.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:125:0) after 1m0s
[19:25:34.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:125:0)
[19:25:34.513] Timer tick, asking Buildbucket for the build status
[19:25:34.577] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:34.577] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:126:0) after 1m0s
[19:26:34.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:126:0)
[19:26:34.724] Timer tick, asking Buildbucket for the build status
[19:26:34.783] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:34.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:127:0) after 1m0s
[19:27:36.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:127:0)
[19:27:36.189] Timer tick, asking Buildbucket for the build status
[19:27:36.317] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:36.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:128:0) after 1m0s
[19:28:36.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:128:0)
[19:28:36.449] Timer tick, asking Buildbucket for the build status
[19:28:37.143] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:37.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:129:0) after 1m0s
[19:29:39.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:129:0)
[19:29:39.471] Timer tick, asking Buildbucket for the build status
[19:29:39.565] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:39.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:130:0) after 1m0s
[19:30:39.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:130:0)
[19:30:39.624] Timer tick, asking Buildbucket for the build status
[19:30:39.689] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:39.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:131:0) after 1m0s
[19:31:39.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:131:0)
[19:31:39.678] Timer tick, asking Buildbucket for the build status
[19:31:39.911] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:39.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:132:0) after 1m0s
[19:32:39.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:132:0)
[19:32:39.942] Timer tick, asking Buildbucket for the build status
[19:32:39.996] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:39.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:133:0) after 1m0s
[19:33:40.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:133:0)
[19:33:40.023] Timer tick, asking Buildbucket for the build status
[19:33:40.126] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:40.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:134:0) after 1m0s
[19:34:40.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:134:0)
[19:34:40.198] Timer tick, asking Buildbucket for the build status
[19:34:40.306] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:40.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:135:0) after 1m0s
[19:35:40.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:135:0)
[19:35:40.377] Timer tick, asking Buildbucket for the build status
[19:35:40.797] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:40.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:136:0) after 1m0s
[19:36:40.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:136:0)
[19:36:40.820] Timer tick, asking Buildbucket for the build status
[19:36:40.877] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:40.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:137:0) after 1m0s
[19:37:40.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:137:0)
[19:37:40.862] Timer tick, asking Buildbucket for the build status
[19:37:40.972] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:40.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:138:0) after 1m0s
[19:38:41.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:138:0)
[19:38:41.358] Timer tick, asking Buildbucket for the build status
[19:38:41.513] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:41.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:139:0) after 1m0s
[19:39:41.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:139:0)
[19:39:41.558] Timer tick, asking Buildbucket for the build status
[19:39:41.671] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:41.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:140:0) after 1m0s
[19:40:41.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:140:0)
[19:40:41.739] Timer tick, asking Buildbucket for the build status
[19:40:41.847] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:41.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:141:0) after 1m0s
[19:41:41.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:141:0)
[19:41:41.896] Timer tick, asking Buildbucket for the build status
[19:41:42.092] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:42.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:142:0) after 1m0s
[19:42:42.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:142:0)
[19:42:42.263] Timer tick, asking Buildbucket for the build status
[19:42:42.340] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:42.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:143:0) after 1m0s
[19:43:42.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:143:0)
[19:43:42.436] Timer tick, asking Buildbucket for the build status
[19:43:42.740] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:42.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:144:0) after 1m0s
[19:44:42.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:144:0)
[19:44:42.792] Timer tick, asking Buildbucket for the build status
[19:44:43.082] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:43.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:145:0) after 1m0s
[19:45:43.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:145:0)
[19:45:43.106] Timer tick, asking Buildbucket for the build status
[19:45:43.172] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:43.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:146:0) after 1m0s
[19:46:43.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:146:0)
[19:46:43.211] Timer tick, asking Buildbucket for the build status
[19:46:43.263] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:43.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:147:0) after 1m0s
[19:47:43.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:147:0)
[19:47:43.293] Timer tick, asking Buildbucket for the build status
[19:47:43.421] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:43.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:148:0) after 1m0s
[19:48:43.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:148:0)
[19:48:43.433] Timer tick, asking Buildbucket for the build status
[19:48:43.514] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:43.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:149:0) after 1m0s
[19:49:43.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:149:0)
[19:49:43.562] Timer tick, asking Buildbucket for the build status
[19:49:44.520] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:44.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:150:0) after 1m0s
[19:50:44.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:150:0)
[19:50:44.606] Timer tick, asking Buildbucket for the build status
[19:50:44.687] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:44.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:151:0) after 1m0s
[19:51:44.755] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:151:0)
[19:51:44.755] Timer tick, asking Buildbucket for the build status
[19:51:44.816] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:44.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:152:0) after 1m0s
[19:52:44.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:152:0)
[19:52:44.832] Timer tick, asking Buildbucket for the build status
[19:52:44.918] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:44.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:153:0) after 1m0s
[19:53:45.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:153:0)
[19:53:45.008] Timer tick, asking Buildbucket for the build status
[19:53:45.078] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:45.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:154:0) after 1m0s
[19:54:45.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:154:0)
[19:54:45.153] Timer tick, asking Buildbucket for the build status
[19:54:45.587] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:45.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:155:0) after 1m0s
[19:55:45.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:155:0)
[19:55:45.671] Timer tick, asking Buildbucket for the build status
[19:55:45.831] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:45.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:156:0) after 1m0s
[19:56:45.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:156:0)
[19:56:45.879] Timer tick, asking Buildbucket for the build status
[19:56:46.002] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:46.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:157:0) after 1m0s
[19:57:46.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:157:0)
[19:57:46.155] Timer tick, asking Buildbucket for the build status
[19:57:46.219] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:46.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:158:0) after 1m0s
[19:58:46.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:158:0)
[19:58:46.345] Timer tick, asking Buildbucket for the build status
[19:58:46.458] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:46.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:159:0) after 1m0s
[19:59:46.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:159:0)
[19:59:46.538] Timer tick, asking Buildbucket for the build status
[19:59:46.670] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:46.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:160:0) after 1m0s
[20:00:46.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:160:0)
[20:00:46.783] Timer tick, asking Buildbucket for the build status
[20:00:46.864] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:46.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:161:0) after 1m0s
[20:01:47.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:161:0)
[20:01:47.023] Timer tick, asking Buildbucket for the build status
[20:01:47.108] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:47.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:162:0) after 1m0s
[20:02:47.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:162:0)
[20:02:47.226] Timer tick, asking Buildbucket for the build status
[20:02:47.308] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:47.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:163:0) after 1m0s
[20:03:47.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:163:0)
[20:03:47.395] Timer tick, asking Buildbucket for the build status
[20:03:47.452] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:47.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:164:0) after 1m0s
[20:04:47.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:164:0)
[20:04:47.560] Timer tick, asking Buildbucket for the build status
[20:04:47.691] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:47.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:165:0) after 1m0s
[20:05:47.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:165:0)
[20:05:47.748] Timer tick, asking Buildbucket for the build status
[20:05:47.855] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:47.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:166:0) after 1m0s
[20:06:47.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:166:0)
[20:06:47.880] Timer tick, asking Buildbucket for the build status
[20:06:47.955] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:47.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:167:0) after 1m0s
[20:07:48.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:167:0)
[20:07:48.011] Timer tick, asking Buildbucket for the build status
[20:07:48.505] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:48.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:168:0) after 1m0s
[20:08:48.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:168:0)
[20:08:48.534] Timer tick, asking Buildbucket for the build status
[20:08:48.597] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:48.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:169:0) after 1m0s
[20:09:48.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:169:0)
[20:09:48.716] Timer tick, asking Buildbucket for the build status
[20:09:48.824] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:48.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:170:0) after 1m0s
[20:10:48.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:170:0)
[20:10:48.857] Timer tick, asking Buildbucket for the build status
[20:10:49.031] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:49.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:171:0) after 1m0s
[20:11:49.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:171:0)
[20:11:49.114] Timer tick, asking Buildbucket for the build status
[20:11:49.170] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:49.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:172:0) after 1m0s
[20:12:49.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:172:0)
[20:12:49.316] Timer tick, asking Buildbucket for the build status
[20:12:49.471] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:49.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:173:0) after 1m0s
[20:13:49.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:173:0)
[20:13:49.519] Timer tick, asking Buildbucket for the build status
[20:13:49.595] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:49.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:174:0) after 1m0s
[20:14:49.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:174:0)
[20:14:49.643] Timer tick, asking Buildbucket for the build status
[20:14:49.767] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:49.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:175:0) after 1m0s
[20:15:49.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:175:0)
[20:15:49.810] Timer tick, asking Buildbucket for the build status
[20:15:49.859] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:49.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:176:0) after 1m0s
[20:16:49.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:176:0)
[20:16:49.910] Timer tick, asking Buildbucket for the build status
[20:16:49.974] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:49.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:177:0) after 1m0s
[20:17:50.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:177:0)
[20:17:50.033] Timer tick, asking Buildbucket for the build status
[20:17:50.083] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:50.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:178:0) after 1m0s
[20:18:50.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:178:0)
[20:18:50.125] Timer tick, asking Buildbucket for the build status
[20:18:50.200] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:50.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:179:0) after 1m0s
[20:19:50.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:179:0)
[20:19:50.254] Timer tick, asking Buildbucket for the build status
[20:19:50.397] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:50.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:180:0) after 1m0s
[20:20:50.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:180:0)
[20:20:50.423] Timer tick, asking Buildbucket for the build status
[20:20:50.480] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:50.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:181:0) after 1m0s
[20:21:50.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:181:0)
[20:21:50.536] Timer tick, asking Buildbucket for the build status
[20:21:50.591] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:50.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:182:0) after 1m0s
[20:22:50.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:182:0)
[20:22:50.645] Timer tick, asking Buildbucket for the build status
[20:22:50.708] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:50.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:183:0) after 1m0s
[20:23:50.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:183:0)
[20:23:50.733] Timer tick, asking Buildbucket for the build status
[20:23:50.797] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:50.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:184:0) after 1m0s
[20:24:50.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:184:0)
[20:24:50.868] Timer tick, asking Buildbucket for the build status
[20:24:51.054] Build 8866988611646294160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:51.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9032418673568945552:185:0) after 1m0s
[20:24:53.479] Received PubSub notification, asking Buildbucket for the build status
[20:24:53.696] Build 8866988611646294160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:24:53.696] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1602188692449939",
    "created_by": "project:infra",
    "created_ts": "1602177719414992",
    "id": "8866988611646294160",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4286.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"8d7ffb89-9c5f-4558-bf10-1c59d91e2c5d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4286.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-j22x\"], \"image\": [\"chrome-xenial-20100400-30cd36dae64\"], \"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\": [\"5570-fa4adf0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1602177771024552",
    "status": "COMPLETED",
    "status_changed_ts": "1602188692884628",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9032418673568945552",
      "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/8866988611646294160/+/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:4f220d66cce17b10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1602188693214642",
    "url": "https://ci.chromium.org/b/8866988611646294160",
    "utcnow_ts": "1602188693679880"
  }
}
[20:24:53.696] Invocation finished in 3h2m55.499033781s with status SUCCEEDED