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

• parent_buildername:publish_tarball
• user_agent:recipe

[14:36:02.791] New invocation is queued and will start shortly
[14:36:02.791] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[14:36:03.925] Starting the invocation (attempt 1)
[14:36:03.970] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:36:03.970] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:36:03.970] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9028442846063621616",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"89.0.4331.3\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"d895d25d-f3b4-4a4c-addb-934efb76f7f0\",\"title\":\"publish_tarball/0\"}]},\"version\":\"89.0.4331.3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9028442846063621616",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[14:36:05.051] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1605969364073052",
    "id": "8863012784056288880",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4331.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d895d25d-f3b4-4a4c-addb-934efb76f7f0\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4331.3\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1605969364638311",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9028442846063621616",
      "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/8863012784056288880/+/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": "1605969364638491",
    "url": "https://ci.chromium.org/b/8863012784056288880",
    "utcnow_ts": "1605969365032094"
  }
}
[14:36:05.051] Task URL: https://ci.chromium.org/b/8863012784056288880
[14:36:05.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:2:0) after 1m0s
[14:36:31.497] Received PubSub notification, asking Buildbucket for the build status
[14:36:31.585] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:05.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:2:0)
[14:37:05.074] Timer tick, asking Buildbucket for the build status
[14:37:05.306] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:05.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:4:0) after 1m0s
[14:38:05.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:4:0)
[14:38:05.387] Timer tick, asking Buildbucket for the build status
[14:38:05.452] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:05.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:5:0) after 1m0s
[14:39:05.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:5:0)
[14:39:05.429] Timer tick, asking Buildbucket for the build status
[14:39:05.531] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:05.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:6:0) after 1m0s
[14:40:05.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:6:0)
[14:40:05.573] Timer tick, asking Buildbucket for the build status
[14:40:05.649] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:05.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:7:0) after 1m0s
[14:41:05.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:7:0)
[14:41:05.753] Timer tick, asking Buildbucket for the build status
[14:41:05.947] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:05.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:8:0) after 1m0s
[14:42:05.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:8:0)
[14:42:05.965] Timer tick, asking Buildbucket for the build status
[14:42:06.049] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:06.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:9:0) after 1m0s
[14:43:06.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:9:0)
[14:43:06.019] Timer tick, asking Buildbucket for the build status
[14:43:06.097] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:06.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:10:0) after 1m0s
[14:44:06.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:10:0)
[14:44:06.106] Timer tick, asking Buildbucket for the build status
[14:44:06.865] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:06.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:11:0) after 1m0s
[14:45:06.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:11:0)
[14:45:06.917] Timer tick, asking Buildbucket for the build status
[14:45:07.024] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:07.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:12:0) after 1m0s
[14:46:07.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:12:0)
[14:46:07.045] Timer tick, asking Buildbucket for the build status
[14:46:07.150] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:07.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:13:0) after 1m0s
[14:47:07.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:13:0)
[14:47:07.480] Timer tick, asking Buildbucket for the build status
[14:47:07.541] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:07.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:14:0) after 1m0s
[14:48:07.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:14:0)
[14:48:07.593] Timer tick, asking Buildbucket for the build status
[14:48:07.656] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:07.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:15:0) after 1m0s
[14:49:07.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:15:0)
[14:49:07.756] Timer tick, asking Buildbucket for the build status
[14:49:07.818] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:07.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:16:0) after 1m0s
[14:50:07.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:16:0)
[14:50:07.893] Timer tick, asking Buildbucket for the build status
[14:50:07.963] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:07.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:17:0) after 1m0s
[14:51:08.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:17:0)
[14:51:08.179] Timer tick, asking Buildbucket for the build status
[14:51:08.351] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:08.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:18:0) after 1m0s
[14:52:08.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:18:0)
[14:52:08.399] Timer tick, asking Buildbucket for the build status
[14:52:08.485] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:08.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:19:0) after 1m0s
[14:53:08.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:19:0)
[14:53:08.616] Timer tick, asking Buildbucket for the build status
[14:53:08.673] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:08.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:20:0) after 1m0s
[14:54:08.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:20:0)
[14:54:08.702] Timer tick, asking Buildbucket for the build status
[14:54:08.774] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:08.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:21:0) after 1m0s
[14:55:08.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:21:0)
[14:55:08.849] Timer tick, asking Buildbucket for the build status
[14:55:08.909] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:08.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:22:0) after 1m0s
[14:56:08.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:22:0)
[14:56:08.951] Timer tick, asking Buildbucket for the build status
[14:56:09.095] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:09.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:23:0) after 1m0s
[14:57:09.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:23:0)
[14:57:09.130] Timer tick, asking Buildbucket for the build status
[14:57:09.320] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:09.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:24:0) after 1m0s
[14:58:09.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:24:0)
[14:58:09.409] Timer tick, asking Buildbucket for the build status
[14:58:09.511] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:09.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:25:0) after 1m0s
[14:59:09.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:25:0)
[14:59:09.537] Timer tick, asking Buildbucket for the build status
[14:59:09.643] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:09.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:26:0) after 1m0s
[15:00:09.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:26:0)
[15:00:09.772] Timer tick, asking Buildbucket for the build status
[15:00:09.838] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:09.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:27:0) after 1m0s
[15:01:10.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:27:0)
[15:01:10.400] Timer tick, asking Buildbucket for the build status
[15:01:10.575] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:10.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:28:0) after 1m0s
[15:02:10.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:28:0)
[15:02:10.720] Timer tick, asking Buildbucket for the build status
[15:02:10.901] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:10.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:29:0) after 1m0s
[15:03:10.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:29:0)
[15:03:10.924] Timer tick, asking Buildbucket for the build status
[15:03:10.991] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:10.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:30:0) after 1m0s
[15:04:11.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:30:0)
[15:04:11.018] Timer tick, asking Buildbucket for the build status
[15:04:11.082] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:11.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:31:0) after 1m0s
[15:05:11.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:31:0)
[15:05:11.100] Timer tick, asking Buildbucket for the build status
[15:05:11.175] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:11.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:32:0) after 1m0s
[15:06:11.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:32:0)
[15:06:11.225] Timer tick, asking Buildbucket for the build status
[15:06:12.102] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:12.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:33:0) after 1m0s
[15:07:12.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:33:0)
[15:07:12.391] Timer tick, asking Buildbucket for the build status
[15:07:12.451] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:12.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:34:0) after 1m0s
[15:08:12.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:34:0)
[15:08:12.575] Timer tick, asking Buildbucket for the build status
[15:08:12.814] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:12.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:35:0) after 1m0s
[15:09:12.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:35:0)
[15:09:12.984] Timer tick, asking Buildbucket for the build status
[15:09:13.047] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:13.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:36:0) after 1m0s
[15:10:13.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:36:0)
[15:10:13.125] Timer tick, asking Buildbucket for the build status
[15:10:13.205] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:13.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:37:0) after 1m0s
[15:11:13.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:37:0)
[15:11:13.256] Timer tick, asking Buildbucket for the build status
[15:11:13.362] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:13.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:38:0) after 1m0s
[15:12:13.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:38:0)
[15:12:13.497] Timer tick, asking Buildbucket for the build status
[15:12:13.579] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:13.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:39:0) after 1m0s
[15:13:13.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:39:0)
[15:13:13.740] Timer tick, asking Buildbucket for the build status
[15:13:13.855] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:13.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:40:0) after 1m0s
[15:14:13.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:40:0)
[15:14:13.927] Timer tick, asking Buildbucket for the build status
[15:14:13.994] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:13.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:41:0) after 1m0s
[15:15:14.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:41:0)
[15:15:14.248] Timer tick, asking Buildbucket for the build status
[15:15:14.420] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:14.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:42:0) after 1m0s
[15:16:14.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:42:0)
[15:16:14.496] Timer tick, asking Buildbucket for the build status
[15:16:14.643] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:14.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:43:0) after 1m0s
[15:17:14.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:43:0)
[15:17:14.808] Timer tick, asking Buildbucket for the build status
[15:17:14.878] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:14.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:44:0) after 1m0s
[15:18:14.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:44:0)
[15:18:14.997] Timer tick, asking Buildbucket for the build status
[15:18:15.065] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:15.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:45:0) after 1m0s
[15:19:15.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:45:0)
[15:19:15.139] Timer tick, asking Buildbucket for the build status
[15:19:15.280] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:15.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:46:0) after 1m0s
[15:20:15.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:46:0)
[15:20:15.325] Timer tick, asking Buildbucket for the build status
[15:20:15.506] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:15.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:47:0) after 1m0s
[15:21:15.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:47:0)
[15:21:15.550] Timer tick, asking Buildbucket for the build status
[15:21:15.676] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:15.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:48:0) after 1m0s
[15:22:15.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:48:0)
[15:22:15.846] Timer tick, asking Buildbucket for the build status
[15:22:15.915] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:15.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:49:0) after 1m0s
[15:23:16.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:49:0)
[15:23:16.022] Timer tick, asking Buildbucket for the build status
[15:23:16.084] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:16.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:50:0) after 1m0s
[15:24:16.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:50:0)
[15:24:16.104] Timer tick, asking Buildbucket for the build status
[15:24:16.196] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:16.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:51:0) after 1m0s
[15:25:16.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:51:0)
[15:25:16.316] Timer tick, asking Buildbucket for the build status
[15:25:16.502] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:16.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:52:0) after 1m0s
[15:26:16.534] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:52:0)
[15:26:16.534] Timer tick, asking Buildbucket for the build status
[15:26:17.266] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:17.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:53:0) after 1m0s
[15:27:17.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:53:0)
[15:27:17.337] Timer tick, asking Buildbucket for the build status
[15:27:17.485] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:17.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:54:0) after 1m0s
[15:28:17.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:54:0)
[15:28:17.507] Timer tick, asking Buildbucket for the build status
[15:28:17.562] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:17.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:55:0) after 1m0s
[15:29:17.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:55:0)
[15:29:17.663] Timer tick, asking Buildbucket for the build status
[15:29:17.901] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:17.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:56:0) after 1m0s
[15:30:17.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:56:0)
[15:30:17.923] Timer tick, asking Buildbucket for the build status
[15:30:18.275] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:18.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:57:0) after 1m0s
[15:31:18.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:57:0)
[15:31:18.493] Timer tick, asking Buildbucket for the build status
[15:31:18.709] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:18.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:58:0) after 1m0s
[15:32:19.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:58:0)
[15:32:19.079] Timer tick, asking Buildbucket for the build status
[15:32:19.146] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:19.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:59:0) after 1m0s
[15:33:19.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:59:0)
[15:33:19.217] Timer tick, asking Buildbucket for the build status
[15:33:19.294] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:19.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:60:0) after 1m0s
[15:34:19.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:60:0)
[15:34:19.477] Timer tick, asking Buildbucket for the build status
[15:34:19.573] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:19.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:61:0) after 1m0s
[15:35:19.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:61:0)
[15:35:19.799] Timer tick, asking Buildbucket for the build status
[15:35:19.977] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:19.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:62:0) after 1m0s
[15:36:20.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:62:0)
[15:36:20.141] Timer tick, asking Buildbucket for the build status
[15:36:20.248] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:20.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:63:0) after 1m0s
[15:37:22.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:63:0)
[15:37:22.809] Timer tick, asking Buildbucket for the build status
[15:37:22.867] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:22.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:64:0) after 1m0s
[15:38:22.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:64:0)
[15:38:22.893] Timer tick, asking Buildbucket for the build status
[15:38:22.954] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:22.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:65:0) after 1m0s
[15:39:22.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:65:0)
[15:39:22.985] Timer tick, asking Buildbucket for the build status
[15:39:23.047] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:23.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:66:0) after 1m0s
[15:40:23.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:66:0)
[15:40:23.071] Timer tick, asking Buildbucket for the build status
[15:40:23.136] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:23.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:67:0) after 1m0s
[15:41:23.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:67:0)
[15:41:23.233] Timer tick, asking Buildbucket for the build status
[15:41:23.696] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:23.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:68:0) after 1m0s
[15:42:23.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:68:0)
[15:42:23.750] Timer tick, asking Buildbucket for the build status
[15:42:23.806] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:23.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:69:0) after 1m0s
[15:43:23.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:69:0)
[15:43:23.804] Timer tick, asking Buildbucket for the build status
[15:43:23.910] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:23.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:70:0) after 1m0s
[15:44:24.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:70:0)
[15:44:24.000] Timer tick, asking Buildbucket for the build status
[15:44:24.051] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:24.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:71:0) after 1m0s
[15:45:24.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:71:0)
[15:45:24.198] Timer tick, asking Buildbucket for the build status
[15:45:24.369] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:24.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:72:0) after 1m0s
[15:46:24.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:72:0)
[15:46:24.502] Timer tick, asking Buildbucket for the build status
[15:46:24.971] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:24.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:73:0) after 1m0s
[15:47:25.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:73:0)
[15:47:25.051] Timer tick, asking Buildbucket for the build status
[15:47:25.114] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:25.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:74:0) after 1m0s
[15:48:25.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:74:0)
[15:48:25.137] Timer tick, asking Buildbucket for the build status
[15:48:25.200] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:25.200] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:75:0) after 1m0s
[15:49:25.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:75:0)
[15:49:25.272] Timer tick, asking Buildbucket for the build status
[15:49:25.340] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:25.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:76:0) after 1m0s
[15:50:25.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:76:0)
[15:50:25.688] Timer tick, asking Buildbucket for the build status
[15:50:25.750] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:25.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:77:0) after 1m0s
[15:51:25.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:77:0)
[15:51:25.866] Timer tick, asking Buildbucket for the build status
[15:51:25.972] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:25.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:78:0) after 1m0s
[15:52:25.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:78:0)
[15:52:25.999] Timer tick, asking Buildbucket for the build status
[15:52:26.070] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:26.070] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:79:0) after 1m0s
[15:53:26.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:79:0)
[15:53:26.265] Timer tick, asking Buildbucket for the build status
[15:53:26.328] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:26.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:80:0) after 1m0s
[15:54:26.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:80:0)
[15:54:26.426] Timer tick, asking Buildbucket for the build status
[15:54:26.572] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:26.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:81:0) after 1m0s
[15:55:26.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:81:0)
[15:55:26.687] Timer tick, asking Buildbucket for the build status
[15:55:26.744] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:26.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:82:0) after 1m0s
[15:56:26.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:82:0)
[15:56:26.884] Timer tick, asking Buildbucket for the build status
[15:56:27.172] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:27.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:83:0) after 1m0s
[15:57:27.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:83:0)
[15:57:27.353] Timer tick, asking Buildbucket for the build status
[15:57:27.480] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:27.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:84:0) after 1m0s
[15:58:27.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:84:0)
[15:58:27.549] Timer tick, asking Buildbucket for the build status
[15:58:28.005] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:28.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:85:0) after 1m0s
[15:59:28.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:85:0)
[15:59:28.042] Timer tick, asking Buildbucket for the build status
[15:59:28.118] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:28.118] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:86:0) after 1m0s
[16:00:28.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:86:0)
[16:00:28.183] Timer tick, asking Buildbucket for the build status
[16:00:28.269] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:28.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:87:0) after 1m0s
[16:01:28.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:87:0)
[16:01:28.478] Timer tick, asking Buildbucket for the build status
[16:01:28.586] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:28.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:88:0) after 1m0s
[16:02:28.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:88:0)
[16:02:28.713] Timer tick, asking Buildbucket for the build status
[16:02:29.052] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:29.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:89:0) after 1m0s
[16:03:29.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:89:0)
[16:03:29.104] Timer tick, asking Buildbucket for the build status
[16:03:29.186] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:29.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:90:0) after 1m0s
[16:04:29.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:90:0)
[16:04:29.181] Timer tick, asking Buildbucket for the build status
[16:04:29.246] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:29.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:91:0) after 1m0s
[16:05:29.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:91:0)
[16:05:29.271] Timer tick, asking Buildbucket for the build status
[16:05:29.327] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:29.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:92:0) after 1m0s
[16:06:29.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:92:0)
[16:06:29.332] Timer tick, asking Buildbucket for the build status
[16:06:29.436] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:29.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:93:0) after 1m0s
[16:07:29.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:93:0)
[16:07:29.469] Timer tick, asking Buildbucket for the build status
[16:07:29.665] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:29.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:94:0) after 1m0s
[16:08:29.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:94:0)
[16:08:29.646] Timer tick, asking Buildbucket for the build status
[16:08:29.703] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:29.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:95:0) after 1m0s
[16:09:29.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:95:0)
[16:09:29.753] Timer tick, asking Buildbucket for the build status
[16:09:29.820] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:29.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:96:0) after 1m0s
[16:10:29.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:96:0)
[16:10:29.831] Timer tick, asking Buildbucket for the build status
[16:10:29.885] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:29.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:97:0) after 1m0s
[16:11:29.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:97:0)
[16:11:29.947] Timer tick, asking Buildbucket for the build status
[16:11:30.081] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:30.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:98:0) after 1m0s
[16:12:30.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:98:0)
[16:12:30.155] Timer tick, asking Buildbucket for the build status
[16:12:30.234] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:30.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:99:0) after 1m0s
[16:13:30.291] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:99:0)
[16:13:30.291] Timer tick, asking Buildbucket for the build status
[16:13:30.430] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:30.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:100:0) after 1m0s
[16:14:30.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:100:0)
[16:14:30.519] Timer tick, asking Buildbucket for the build status
[16:14:30.599] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:30.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:101:0) after 1m0s
[16:15:30.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:101:0)
[16:15:30.628] Timer tick, asking Buildbucket for the build status
[16:15:30.827] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:30.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:102:0) after 1m0s
[16:16:30.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:102:0)
[16:16:30.968] Timer tick, asking Buildbucket for the build status
[16:16:31.074] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:31.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:103:0) after 1m0s
[16:17:31.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:103:0)
[16:17:31.114] Timer tick, asking Buildbucket for the build status
[16:17:31.243] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:31.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:104:0) after 1m0s
[16:18:31.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:104:0)
[16:18:31.290] Timer tick, asking Buildbucket for the build status
[16:18:31.365] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:31.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:105:0) after 1m0s
[16:19:31.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:105:0)
[16:19:31.387] Timer tick, asking Buildbucket for the build status
[16:19:31.481] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:31.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:106:0) after 1m0s
[16:20:31.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:106:0)
[16:20:31.508] Timer tick, asking Buildbucket for the build status
[16:20:31.566] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:31.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:107:0) after 1m0s
[16:21:31.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:107:0)
[16:21:31.591] Timer tick, asking Buildbucket for the build status
[16:21:31.781] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:31.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:108:0) after 1m0s
[16:22:31.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:108:0)
[16:22:31.807] Timer tick, asking Buildbucket for the build status
[16:22:31.877] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:31.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:109:0) after 1m0s
[16:23:31.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:109:0)
[16:23:31.876] Timer tick, asking Buildbucket for the build status
[16:23:31.934] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:31.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:110:0) after 1m0s
[16:24:31.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:110:0)
[16:24:31.956] Timer tick, asking Buildbucket for the build status
[16:24:32.022] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:32.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:111:0) after 1m0s
[16:25:32.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:111:0)
[16:25:32.049] Timer tick, asking Buildbucket for the build status
[16:25:32.149] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:32.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:112:0) after 1m0s
[16:26:32.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:112:0)
[16:26:32.168] Timer tick, asking Buildbucket for the build status
[16:26:32.271] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:32.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:113:0) after 1m0s
[16:27:32.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:113:0)
[16:27:32.338] Timer tick, asking Buildbucket for the build status
[16:27:32.403] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:32.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:114:0) after 1m0s
[16:28:32.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:114:0)
[16:28:32.431] Timer tick, asking Buildbucket for the build status
[16:28:32.493] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:32.493] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:115:0) after 1m0s
[16:29:32.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:115:0)
[16:29:32.529] Timer tick, asking Buildbucket for the build status
[16:29:32.597] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:32.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:116:0) after 1m0s
[16:30:32.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:116:0)
[16:30:32.625] Timer tick, asking Buildbucket for the build status
[16:30:33.039] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:33.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:117:0) after 1m0s
[16:31:33.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:117:0)
[16:31:33.112] Timer tick, asking Buildbucket for the build status
[16:31:33.497] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:33.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:118:0) after 1m0s
[16:32:33.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:118:0)
[16:32:33.560] Timer tick, asking Buildbucket for the build status
[16:32:33.638] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:33.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:119:0) after 1m0s
[16:33:33.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:119:0)
[16:33:33.710] Timer tick, asking Buildbucket for the build status
[16:33:33.802] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:33.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:120:0) after 1m0s
[16:34:33.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:120:0)
[16:34:33.787] Timer tick, asking Buildbucket for the build status
[16:34:33.924] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:33.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:121:0) after 1m0s
[16:35:33.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:121:0)
[16:35:33.943] Timer tick, asking Buildbucket for the build status
[16:35:34.283] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:34.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:122:0) after 1m0s
[16:36:34.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:122:0)
[16:36:34.346] Timer tick, asking Buildbucket for the build status
[16:36:34.479] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:34.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:123:0) after 1m0s
[16:37:34.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:123:0)
[16:37:34.538] Timer tick, asking Buildbucket for the build status
[16:37:34.606] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:34.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:124:0) after 1m0s
[16:38:34.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:124:0)
[16:38:34.667] Timer tick, asking Buildbucket for the build status
[16:38:34.729] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:34.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:125:0) after 1m0s
[16:39:34.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:125:0)
[16:39:34.794] Timer tick, asking Buildbucket for the build status
[16:39:34.861] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:34.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:126:0) after 1m0s
[16:40:34.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:126:0)
[16:40:34.949] Timer tick, asking Buildbucket for the build status
[16:40:35.014] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:35.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:127:0) after 1m0s
[16:41:35.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:127:0)
[16:41:35.021] Timer tick, asking Buildbucket for the build status
[16:41:35.126] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:35.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:128:0) after 1m0s
[16:42:35.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:128:0)
[16:42:35.167] Timer tick, asking Buildbucket for the build status
[16:42:35.223] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:35.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:129:0) after 1m0s
[16:43:35.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:129:0)
[16:43:35.319] Timer tick, asking Buildbucket for the build status
[16:43:35.629] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:35.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:130:0) after 1m0s
[16:44:35.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:130:0)
[16:44:35.680] Timer tick, asking Buildbucket for the build status
[16:44:35.738] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:35.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:131:0) after 1m0s
[16:45:35.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:131:0)
[16:45:35.833] Timer tick, asking Buildbucket for the build status
[16:45:36.027] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:36.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:132:0) after 1m0s
[16:46:36.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:132:0)
[16:46:36.097] Timer tick, asking Buildbucket for the build status
[16:46:36.296] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:36.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:133:0) after 1m0s
[16:47:36.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:133:0)
[16:47:36.350] Timer tick, asking Buildbucket for the build status
[16:47:36.431] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:36.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:134:0) after 1m0s
[16:48:36.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:134:0)
[16:48:36.456] Timer tick, asking Buildbucket for the build status
[16:48:36.848] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:36.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:135:0) after 1m0s
[16:49:36.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:135:0)
[16:49:36.921] Timer tick, asking Buildbucket for the build status
[16:49:37.082] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:37.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:136:0) after 1m0s
[16:50:37.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:136:0)
[16:50:37.112] Timer tick, asking Buildbucket for the build status
[16:50:37.185] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:37.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:137:0) after 1m0s
[16:51:37.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:137:0)
[16:51:37.254] Timer tick, asking Buildbucket for the build status
[16:51:37.355] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:37.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:138:0) after 1m0s
[16:52:37.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:138:0)
[16:52:37.375] Timer tick, asking Buildbucket for the build status
[16:52:37.448] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:37.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:139:0) after 1m0s
[16:53:37.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:139:0)
[16:53:37.514] Timer tick, asking Buildbucket for the build status
[16:53:37.623] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:37.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:140:0) after 1m0s
[16:54:37.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:140:0)
[16:54:37.621] Timer tick, asking Buildbucket for the build status
[16:54:37.675] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:37.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:141:0) after 1m0s
[16:55:37.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:141:0)
[16:55:37.678] Timer tick, asking Buildbucket for the build status
[16:55:37.735] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:37.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:142:0) after 1m0s
[16:56:37.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:142:0)
[16:56:37.797] Timer tick, asking Buildbucket for the build status
[16:56:37.889] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:37.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:143:0) after 1m0s
[16:57:37.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:143:0)
[16:57:37.918] Timer tick, asking Buildbucket for the build status
[16:57:38.160] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:38.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:144:0) after 1m0s
[16:58:38.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:144:0)
[16:58:38.277] Timer tick, asking Buildbucket for the build status
[16:58:38.334] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:38.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:145:0) after 1m0s
[16:59:38.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:145:0)
[16:59:38.345] Timer tick, asking Buildbucket for the build status
[16:59:38.422] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:38.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:146:0) after 1m0s
[17:00:38.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:146:0)
[17:00:38.518] Timer tick, asking Buildbucket for the build status
[17:00:38.634] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:38.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:147:0) after 1m0s
[17:01:38.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:147:0)
[17:01:38.721] Timer tick, asking Buildbucket for the build status
[17:01:39.425] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:39.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:148:0) after 1m0s
[17:02:39.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:148:0)
[17:02:39.452] Timer tick, asking Buildbucket for the build status
[17:02:39.512] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:39.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:149:0) after 1m0s
[17:03:39.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:149:0)
[17:03:39.539] Timer tick, asking Buildbucket for the build status
[17:03:39.718] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:39.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:150:0) after 1m0s
[17:04:39.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:150:0)
[17:04:39.750] Timer tick, asking Buildbucket for the build status
[17:04:39.803] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:39.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:151:0) after 1m0s
[17:05:39.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:151:0)
[17:05:39.854] Timer tick, asking Buildbucket for the build status
[17:05:39.920] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:39.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:152:0) after 1m0s
[17:06:40.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:152:0)
[17:06:40.048] Timer tick, asking Buildbucket for the build status
[17:06:40.177] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:40.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:153:0) after 1m0s
[17:07:40.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:153:0)
[17:07:40.252] Timer tick, asking Buildbucket for the build status
[17:07:40.315] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:40.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:154:0) after 1m0s
[17:08:40.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:154:0)
[17:08:40.392] Timer tick, asking Buildbucket for the build status
[17:08:40.618] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:40.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:155:0) after 1m0s
[17:09:40.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:155:0)
[17:09:40.735] Timer tick, asking Buildbucket for the build status
[17:09:40.930] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:40.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:156:0) after 1m0s
[17:10:40.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:156:0)
[17:10:40.993] Timer tick, asking Buildbucket for the build status
[17:10:41.072] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:41.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:157:0) after 1m0s
[17:11:41.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:157:0)
[17:11:41.189] Timer tick, asking Buildbucket for the build status
[17:11:41.299] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:41.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:158:0) after 1m0s
[17:12:41.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:158:0)
[17:12:41.372] Timer tick, asking Buildbucket for the build status
[17:12:41.541] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:41.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:159:0) after 1m0s
[17:13:41.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:159:0)
[17:13:41.575] Timer tick, asking Buildbucket for the build status
[17:13:42.181] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:42.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:160:0) after 1m0s
[17:14:42.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:160:0)
[17:14:42.204] Timer tick, asking Buildbucket for the build status
[17:14:42.264] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:42.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:161:0) after 1m0s
[17:15:42.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:161:0)
[17:15:42.322] Timer tick, asking Buildbucket for the build status
[17:15:42.387] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:42.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:162:0) after 1m0s
[17:16:42.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:162:0)
[17:16:42.381] Timer tick, asking Buildbucket for the build status
[17:16:42.533] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:42.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:163:0) after 1m0s
[17:17:42.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:163:0)
[17:17:42.510] Timer tick, asking Buildbucket for the build status
[17:17:42.714] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:42.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:164:0) after 1m0s
[17:18:42.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:164:0)
[17:18:42.746] Timer tick, asking Buildbucket for the build status
[17:18:45.261] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:45.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:165:0) after 1m0s
[17:19:45.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:165:0)
[17:19:45.287] Timer tick, asking Buildbucket for the build status
[17:19:45.385] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:45.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:166:0) after 1m0s
[17:20:45.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:166:0)
[17:20:45.445] Timer tick, asking Buildbucket for the build status
[17:20:45.511] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:45.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:167:0) after 1m0s
[17:21:45.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:167:0)
[17:21:45.502] Timer tick, asking Buildbucket for the build status
[17:21:45.994] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:45.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:168:0) after 1m0s
[17:22:46.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:168:0)
[17:22:46.139] Timer tick, asking Buildbucket for the build status
[17:22:46.195] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:46.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:169:0) after 1m0s
[17:23:46.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:169:0)
[17:23:46.340] Timer tick, asking Buildbucket for the build status
[17:23:46.394] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:46.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:170:0) after 1m0s
[17:24:46.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:170:0)
[17:24:46.392] Timer tick, asking Buildbucket for the build status
[17:24:46.491] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:46.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:171:0) after 1m0s
[17:25:46.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:171:0)
[17:25:46.549] Timer tick, asking Buildbucket for the build status
[17:25:46.607] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:46.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:172:0) after 1m0s
[17:26:46.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:172:0)
[17:26:46.594] Timer tick, asking Buildbucket for the build status
[17:26:46.689] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:46.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:173:0) after 1m0s
[17:27:46.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:173:0)
[17:27:46.723] Timer tick, asking Buildbucket for the build status
[17:27:46.785] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:46.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:174:0) after 1m0s
[17:28:46.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:174:0)
[17:28:46.815] Timer tick, asking Buildbucket for the build status
[17:28:46.908] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:46.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:175:0) after 1m0s
[17:29:46.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:175:0)
[17:29:46.901] Timer tick, asking Buildbucket for the build status
[17:29:47.204] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:47.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:176:0) after 1m0s
[17:30:47.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:176:0)
[17:30:47.234] Timer tick, asking Buildbucket for the build status
[17:30:47.447] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:47.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:177:0) after 1m0s
[17:31:47.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:177:0)
[17:31:47.531] Timer tick, asking Buildbucket for the build status
[17:31:47.740] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:47.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:178:0) after 1m0s
[17:32:47.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:178:0)
[17:32:47.768] Timer tick, asking Buildbucket for the build status
[17:32:47.842] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:47.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:179:0) after 1m0s
[17:33:47.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:179:0)
[17:33:47.869] Timer tick, asking Buildbucket for the build status
[17:33:48.136] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:48.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:180:0) after 1m0s
[17:34:48.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:180:0)
[17:34:48.166] Timer tick, asking Buildbucket for the build status
[17:34:49.175] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:49.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:181:0) after 1m0s
[17:35:49.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:181:0)
[17:35:49.207] Timer tick, asking Buildbucket for the build status
[17:35:49.340] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:49.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:182:0) after 1m0s
[17:36:49.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:182:0)
[17:36:49.365] Timer tick, asking Buildbucket for the build status
[17:36:49.654] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:49.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:183:0) after 1m0s
[17:37:49.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:183:0)
[17:37:49.687] Timer tick, asking Buildbucket for the build status
[17:37:49.755] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:49.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:184:0) after 1m0s
[17:38:49.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:184:0)
[17:38:49.798] Timer tick, asking Buildbucket for the build status
[17:38:49.864] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:49.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:185:0) after 1m0s
[17:39:49.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:185:0)
[17:39:49.887] Timer tick, asking Buildbucket for the build status
[17:39:51.569] Build 8863012784056288880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:51.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9028442846063621616:186:0) after 1m0s
[17:39:59.017] Received PubSub notification, asking Buildbucket for the build status
[17:39:59.108] Build 8863012784056288880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:39:59.108] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1605980398533355",
    "created_by": "project:infra",
    "created_ts": "1605969364073052",
    "id": "8863012784056288880",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"89.0.4331.3\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"d895d25d-f3b4-4a4c-addb-934efb76f7f0\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"89.0.4331.3\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-npcn\"], \"image\": [\"chrome-xenial-20111600-a937a23cefb\"], \"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\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1605969390399445",
    "status": "COMPLETED",
    "status_changed_ts": "1605980398847863",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9028442846063621616",
      "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/8863012784056288880/+/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:50040d5060a21810",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1605980398861434",
    "url": "https://ci.chromium.org/b/8863012784056288880",
    "utcnow_ts": "1605980399099850"
  }
}
[17:39:59.108] Invocation finished in 3h3m56.332961441s with status SUCCEEDED