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

• parent_buildername:publish_tarball
• user_agent:recipe

[00:34:20.509] New invocation is queued and will start shortly
[00:34:20.509] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[00:34:21.574] Starting the invocation (attempt 1)
[00:34:21.642] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:34:21.642] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:34:21.642] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9036921319419370432",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"86.0.4238.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"51384d12-6650-4c3c-a028-1f6bf61fa17d\",\"title\":\"publish_tarball/0\"}]},\"version\":\"86.0.4238.2\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9036921319419370432",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[00:34:22.931] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1597883661757903",
    "id": "8871491257447983888",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4238.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"51384d12-6650-4c3c-a028-1f6bf61fa17d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4238.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1597883661998707",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036921319419370432",
      "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/8871491257447983888/+/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": "1597883661999000",
    "url": "https://ci.chromium.org/b/8871491257447983888",
    "utcnow_ts": "1597883662912001"
  }
}
[00:34:22.931] Task URL: https://ci.chromium.org/b/8871491257447983888
[00:34:22.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:2:0) after 1m0s
[00:35:23.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:2:0)
[00:35:23.556] Timer tick, asking Buildbucket for the build status
[00:35:23.970] Build 8871491257447983888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:23.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:3:0) after 1m0s
[00:36:24.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:3:0)
[00:36:24.016] Timer tick, asking Buildbucket for the build status
[00:36:24.120] Build 8871491257447983888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:24.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:4:0) after 1m0s
[00:37:24.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:4:0)
[00:37:24.374] Timer tick, asking Buildbucket for the build status
[00:37:24.724] Build 8871491257447983888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:24.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:5:0) after 1m0s
[00:38:24.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:5:0)
[00:38:24.881] Timer tick, asking Buildbucket for the build status
[00:38:25.006] Build 8871491257447983888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:25.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:6:0) after 1m0s
[00:39:06.913] Received PubSub notification, asking Buildbucket for the build status
[00:39:07.100] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:25.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:6:0)
[00:39:25.004] Timer tick, asking Buildbucket for the build status
[00:39:25.173] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:25.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:8:0) after 1m0s
[00:40:25.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:8:0)
[00:40:25.212] Timer tick, asking Buildbucket for the build status
[00:40:25.650] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:25.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:9:0) after 1m0s
[00:41:25.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:9:0)
[00:41:25.770] Timer tick, asking Buildbucket for the build status
[00:41:25.909] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:25.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:10:0) after 1m0s
[00:42:25.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:10:0)
[00:42:25.932] Timer tick, asking Buildbucket for the build status
[00:42:27.044] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:27.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:11:0) after 1m0s
[00:43:27.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:11:0)
[00:43:27.076] Timer tick, asking Buildbucket for the build status
[00:43:27.254] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:27.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:12:0) after 1m0s
[00:44:27.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:12:0)
[00:44:27.282] Timer tick, asking Buildbucket for the build status
[00:44:27.489] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:27.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:13:0) after 1m0s
[00:45:27.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:13:0)
[00:45:27.589] Timer tick, asking Buildbucket for the build status
[00:45:27.685] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:27.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:14:0) after 1m0s
[00:46:27.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:14:0)
[00:46:27.697] Timer tick, asking Buildbucket for the build status
[00:46:27.924] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:27.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:15:0) after 1m0s
[00:47:28.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:15:0)
[00:47:28.018] Timer tick, asking Buildbucket for the build status
[00:47:28.079] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:28.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:16:0) after 1m0s
[00:48:28.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:16:0)
[00:48:28.137] Timer tick, asking Buildbucket for the build status
[00:48:28.342] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:28.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:17:0) after 1m0s
[00:49:28.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:17:0)
[00:49:28.370] Timer tick, asking Buildbucket for the build status
[00:49:30.890] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:30.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:18:0) after 1m0s
[00:50:31.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:18:0)
[00:50:31.153] Timer tick, asking Buildbucket for the build status
[00:50:31.225] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:31.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:19:0) after 1m0s
[00:51:31.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:19:0)
[00:51:31.303] Timer tick, asking Buildbucket for the build status
[00:51:31.385] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:31.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:20:0) after 1m0s
[00:52:31.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:20:0)
[00:52:31.381] Timer tick, asking Buildbucket for the build status
[00:52:31.503] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:31.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:21:0) after 1m0s
[00:53:31.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:21:0)
[00:53:31.664] Timer tick, asking Buildbucket for the build status
[00:53:31.851] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:31.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:22:0) after 1m0s
[00:54:31.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:22:0)
[00:54:31.898] Timer tick, asking Buildbucket for the build status
[00:54:32.049] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:32.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:23:0) after 1m0s
[00:55:32.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:23:0)
[00:55:32.099] Timer tick, asking Buildbucket for the build status
[00:55:32.312] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:32.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:24:0) after 1m0s
[00:56:32.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:24:0)
[00:56:32.336] Timer tick, asking Buildbucket for the build status
[00:56:32.448] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:32.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:25:0) after 1m0s
[00:57:32.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:25:0)
[00:57:32.459] Timer tick, asking Buildbucket for the build status
[00:57:32.529] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:32.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:26:0) after 1m0s
[00:58:32.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:26:0)
[00:58:32.600] Timer tick, asking Buildbucket for the build status
[00:58:32.665] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:32.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:27:0) after 1m0s
[00:59:32.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:27:0)
[00:59:32.748] Timer tick, asking Buildbucket for the build status
[00:59:32.865] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:32.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:28:0) after 1m0s
[01:00:32.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:28:0)
[01:00:32.966] Timer tick, asking Buildbucket for the build status
[01:00:33.156] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:33.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:29:0) after 1m0s
[01:01:33.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:29:0)
[01:01:33.325] Timer tick, asking Buildbucket for the build status
[01:01:33.537] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:33.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:30:0) after 1m0s
[01:02:33.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:30:0)
[01:02:33.642] Timer tick, asking Buildbucket for the build status
[01:02:33.751] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:33.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:31:0) after 1m0s
[01:03:33.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:31:0)
[01:03:33.786] Timer tick, asking Buildbucket for the build status
[01:03:33.870] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:33.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:32:0) after 1m0s
[01:04:33.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:32:0)
[01:04:33.948] Timer tick, asking Buildbucket for the build status
[01:04:34.156] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:34.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:33:0) after 1m0s
[01:05:34.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:33:0)
[01:05:34.151] Timer tick, asking Buildbucket for the build status
[01:05:34.342] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:34.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:34:0) after 1m0s
[01:06:34.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:34:0)
[01:06:34.317] Timer tick, asking Buildbucket for the build status
[01:06:34.431] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:34.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:35:0) after 1m0s
[01:07:34.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:35:0)
[01:07:34.460] Timer tick, asking Buildbucket for the build status
[01:07:34.548] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:34.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:36:0) after 1m0s
[01:08:34.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:36:0)
[01:08:34.569] Timer tick, asking Buildbucket for the build status
[01:08:34.734] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:34.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:37:0) after 1m0s
[01:09:34.778] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:37:0)
[01:09:34.778] Timer tick, asking Buildbucket for the build status
[01:09:34.929] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:34.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:38:0) after 1m0s
[01:10:34.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:38:0)
[01:10:34.991] Timer tick, asking Buildbucket for the build status
[01:10:35.125] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:35.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:39:0) after 1m0s
[01:11:35.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:39:0)
[01:11:35.155] Timer tick, asking Buildbucket for the build status
[01:11:35.745] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:35.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:40:0) after 1m0s
[01:12:35.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:40:0)
[01:12:35.794] Timer tick, asking Buildbucket for the build status
[01:12:35.859] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:35.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:41:0) after 1m0s
[01:13:35.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:41:0)
[01:13:35.892] Timer tick, asking Buildbucket for the build status
[01:13:36.377] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:36.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:42:0) after 1m0s
[01:14:36.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:42:0)
[01:14:36.440] Timer tick, asking Buildbucket for the build status
[01:14:37.217] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:37.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:43:0) after 1m0s
[01:15:37.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:43:0)
[01:15:37.308] Timer tick, asking Buildbucket for the build status
[01:15:37.450] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:37.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:44:0) after 1m0s
[01:16:37.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:44:0)
[01:16:37.476] Timer tick, asking Buildbucket for the build status
[01:16:37.560] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:37.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:45:0) after 1m0s
[01:17:37.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:45:0)
[01:17:37.579] Timer tick, asking Buildbucket for the build status
[01:17:39.088] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:39.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:46:0) after 1m0s
[01:18:39.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:46:0)
[01:18:39.111] Timer tick, asking Buildbucket for the build status
[01:18:39.199] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:39.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:47:0) after 1m0s
[01:19:39.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:47:0)
[01:19:39.322] Timer tick, asking Buildbucket for the build status
[01:19:39.632] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:39.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:48:0) after 1m0s
[01:20:39.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:48:0)
[01:20:39.671] Timer tick, asking Buildbucket for the build status
[01:20:39.747] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:39.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:49:0) after 1m0s
[01:21:39.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:49:0)
[01:21:39.852] Timer tick, asking Buildbucket for the build status
[01:21:39.942] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:39.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:50:0) after 1m0s
[01:22:39.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:50:0)
[01:22:39.999] Timer tick, asking Buildbucket for the build status
[01:22:40.112] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:40.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:51:0) after 1m0s
[01:23:40.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:51:0)
[01:23:40.171] Timer tick, asking Buildbucket for the build status
[01:23:40.251] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:40.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:52:0) after 1m0s
[01:24:40.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:52:0)
[01:24:40.288] Timer tick, asking Buildbucket for the build status
[01:24:40.639] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:40.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:53:0) after 1m0s
[01:25:40.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:53:0)
[01:25:40.639] Timer tick, asking Buildbucket for the build status
[01:25:40.696] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:40.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:54:0) after 1m0s
[01:26:40.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:54:0)
[01:26:40.794] Timer tick, asking Buildbucket for the build status
[01:26:41.505] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:41.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:55:0) after 1m0s
[01:27:41.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:55:0)
[01:27:41.485] Timer tick, asking Buildbucket for the build status
[01:27:41.737] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:41.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:56:0) after 1m0s
[01:28:41.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:56:0)
[01:28:41.764] Timer tick, asking Buildbucket for the build status
[01:28:41.949] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:41.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:57:0) after 1m0s
[01:29:41.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:57:0)
[01:29:41.970] Timer tick, asking Buildbucket for the build status
[01:29:42.196] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:42.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:58:0) after 1m0s
[01:30:42.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:58:0)
[01:30:42.604] Timer tick, asking Buildbucket for the build status
[01:30:42.693] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:42.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:59:0) after 1m0s
[01:31:42.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:59:0)
[01:31:42.766] Timer tick, asking Buildbucket for the build status
[01:31:42.832] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:42.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:60:0) after 1m0s
[01:32:43.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:60:0)
[01:32:43.740] Timer tick, asking Buildbucket for the build status
[01:32:43.812] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:43.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:61:0) after 1m0s
[01:33:43.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:61:0)
[01:33:43.825] Timer tick, asking Buildbucket for the build status
[01:33:43.901] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:43.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:62:0) after 1m0s
[01:34:43.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:62:0)
[01:34:43.934] Timer tick, asking Buildbucket for the build status
[01:34:44.101] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:44.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:63:0) after 1m0s
[01:35:44.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:63:0)
[01:35:44.162] Timer tick, asking Buildbucket for the build status
[01:35:44.259] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:44.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:64:0) after 1m0s
[01:36:44.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:64:0)
[01:36:44.363] Timer tick, asking Buildbucket for the build status
[01:36:44.458] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:44.458] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:65:0) after 1m0s
[01:37:44.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:65:0)
[01:37:44.515] Timer tick, asking Buildbucket for the build status
[01:37:44.615] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:44.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:66:0) after 1m0s
[01:38:45.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:66:0)
[01:38:45.045] Timer tick, asking Buildbucket for the build status
[01:38:45.140] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:45.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:67:0) after 1m0s
[01:39:45.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:67:0)
[01:39:45.678] Timer tick, asking Buildbucket for the build status
[01:39:46.106] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:46.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:68:0) after 1m0s
[01:40:46.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:68:0)
[01:40:46.126] Timer tick, asking Buildbucket for the build status
[01:40:46.326] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:46.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:69:0) after 1m0s
[01:41:46.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:69:0)
[01:41:46.470] Timer tick, asking Buildbucket for the build status
[01:41:46.560] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:46.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:70:0) after 1m0s
[01:42:46.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:70:0)
[01:42:46.677] Timer tick, asking Buildbucket for the build status
[01:42:46.763] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:46.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:71:0) after 1m0s
[01:43:47.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:71:0)
[01:43:47.076] Timer tick, asking Buildbucket for the build status
[01:43:47.159] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:47.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:72:0) after 1m0s
[01:44:47.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:72:0)
[01:44:47.387] Timer tick, asking Buildbucket for the build status
[01:44:47.517] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:47.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:73:0) after 1m0s
[01:45:47.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:73:0)
[01:45:47.699] Timer tick, asking Buildbucket for the build status
[01:45:47.789] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:47.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:74:0) after 1m0s
[01:46:47.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:74:0)
[01:46:47.863] Timer tick, asking Buildbucket for the build status
[01:46:47.958] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:47.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:75:0) after 1m0s
[01:47:48.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:75:0)
[01:47:48.081] Timer tick, asking Buildbucket for the build status
[01:47:48.226] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:48.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:76:0) after 1m0s
[01:48:48.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:76:0)
[01:48:48.295] Timer tick, asking Buildbucket for the build status
[01:48:48.364] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:48.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:77:0) after 1m0s
[01:49:48.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:77:0)
[01:49:48.423] Timer tick, asking Buildbucket for the build status
[01:49:48.558] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:48.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:78:0) after 1m0s
[01:50:48.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:78:0)
[01:50:48.638] Timer tick, asking Buildbucket for the build status
[01:50:48.768] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:48.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:79:0) after 1m0s
[01:51:48.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:79:0)
[01:51:48.852] Timer tick, asking Buildbucket for the build status
[01:51:49.117] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:49.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:80:0) after 1m0s
[01:52:49.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:80:0)
[01:52:49.458] Timer tick, asking Buildbucket for the build status
[01:52:50.058] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:50.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:81:0) after 1m0s
[01:53:50.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:81:0)
[01:53:50.172] Timer tick, asking Buildbucket for the build status
[01:53:50.340] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:50.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:82:0) after 1m0s
[01:54:50.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:82:0)
[01:54:50.517] Timer tick, asking Buildbucket for the build status
[01:54:50.963] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:50.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:83:0) after 1m0s
[01:55:51.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:83:0)
[01:55:51.044] Timer tick, asking Buildbucket for the build status
[01:55:51.131] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:51.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:84:0) after 1m0s
[01:56:51.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:84:0)
[01:56:51.267] Timer tick, asking Buildbucket for the build status
[01:56:51.320] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:51.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:85:0) after 1m0s
[01:57:51.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:85:0)
[01:57:51.320] Timer tick, asking Buildbucket for the build status
[01:57:51.414] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:51.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:86:0) after 1m0s
[01:58:51.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:86:0)
[01:58:51.512] Timer tick, asking Buildbucket for the build status
[01:58:51.591] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:51.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:87:0) after 1m0s
[01:59:51.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:87:0)
[01:59:51.622] Timer tick, asking Buildbucket for the build status
[01:59:51.943] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:51.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:88:0) after 1m0s
[02:00:53.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:88:0)
[02:00:53.297] Timer tick, asking Buildbucket for the build status
[02:00:53.509] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:53.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:89:0) after 1m0s
[02:01:53.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:89:0)
[02:01:53.629] Timer tick, asking Buildbucket for the build status
[02:01:54.489] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:54.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:90:0) after 1m0s
[02:02:54.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:90:0)
[02:02:54.585] Timer tick, asking Buildbucket for the build status
[02:02:54.821] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:54.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:91:0) after 1m0s
[02:03:54.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:91:0)
[02:03:54.927] Timer tick, asking Buildbucket for the build status
[02:03:55.002] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:55.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:92:0) after 1m0s
[02:04:55.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:92:0)
[02:04:55.284] Timer tick, asking Buildbucket for the build status
[02:04:55.443] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:55.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:93:0) after 1m0s
[02:05:55.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:93:0)
[02:05:55.676] Timer tick, asking Buildbucket for the build status
[02:05:55.817] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:55.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:94:0) after 1m0s
[02:06:55.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:94:0)
[02:06:55.839] Timer tick, asking Buildbucket for the build status
[02:06:56.034] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:56.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:95:0) after 1m0s
[02:07:56.087] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:95:0)
[02:07:56.087] Timer tick, asking Buildbucket for the build status
[02:07:56.183] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:56.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:96:0) after 1m0s
[02:08:56.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:96:0)
[02:08:56.219] Timer tick, asking Buildbucket for the build status
[02:08:56.276] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:56.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:97:0) after 1m0s
[02:09:56.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:97:0)
[02:09:56.304] Timer tick, asking Buildbucket for the build status
[02:09:56.478] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:56.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:98:0) after 1m0s
[02:10:56.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:98:0)
[02:10:56.497] Timer tick, asking Buildbucket for the build status
[02:10:56.595] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:56.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:99:0) after 1m0s
[02:11:56.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:99:0)
[02:11:56.669] Timer tick, asking Buildbucket for the build status
[02:11:56.754] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:56.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:100:0) after 1m0s
[02:12:56.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:100:0)
[02:12:56.800] Timer tick, asking Buildbucket for the build status
[02:12:56.955] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:56.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:101:0) after 1m0s
[02:13:57.019] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:101:0)
[02:13:57.019] Timer tick, asking Buildbucket for the build status
[02:13:57.520] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:57.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:102:0) after 1m0s
[02:14:57.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:102:0)
[02:14:57.568] Timer tick, asking Buildbucket for the build status
[02:14:57.721] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:57.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:103:0) after 1m0s
[02:15:57.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:103:0)
[02:15:57.775] Timer tick, asking Buildbucket for the build status
[02:15:57.852] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:57.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:104:0) after 1m0s
[02:16:57.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:104:0)
[02:16:57.899] Timer tick, asking Buildbucket for the build status
[02:16:57.981] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:57.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:105:0) after 1m0s
[02:17:57.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:105:0)
[02:17:57.991] Timer tick, asking Buildbucket for the build status
[02:17:58.082] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:58.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:106:0) after 1m0s
[02:18:58.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:106:0)
[02:18:58.096] Timer tick, asking Buildbucket for the build status
[02:18:58.202] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:58.202] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:107:0) after 1m0s
[02:19:58.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:107:0)
[02:19:58.297] Timer tick, asking Buildbucket for the build status
[02:19:58.425] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:58.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:108:0) after 1m0s
[02:20:58.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:108:0)
[02:20:58.525] Timer tick, asking Buildbucket for the build status
[02:20:58.627] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:58.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:109:0) after 1m0s
[02:21:58.717] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:109:0)
[02:21:58.717] Timer tick, asking Buildbucket for the build status
[02:21:58.803] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:58.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:110:0) after 1m0s
[02:22:58.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:110:0)
[02:22:58.833] Timer tick, asking Buildbucket for the build status
[02:22:58.915] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:58.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:111:0) after 1m0s
[02:23:59.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:111:0)
[02:23:59.018] Timer tick, asking Buildbucket for the build status
[02:23:59.134] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:59.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:112:0) after 1m0s
[02:24:59.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:112:0)
[02:24:59.198] Timer tick, asking Buildbucket for the build status
[02:24:59.349] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:59.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:113:0) after 1m0s
[02:25:59.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:113:0)
[02:25:59.468] Timer tick, asking Buildbucket for the build status
[02:25:59.637] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:59.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:114:0) after 1m0s
[02:26:59.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:114:0)
[02:26:59.616] Timer tick, asking Buildbucket for the build status
[02:26:59.705] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:59.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:115:0) after 1m0s
[02:27:59.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:115:0)
[02:27:59.798] Timer tick, asking Buildbucket for the build status
[02:27:59.873] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:59.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:116:0) after 1m0s
[02:28:59.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:116:0)
[02:28:59.962] Timer tick, asking Buildbucket for the build status
[02:29:00.094] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:00.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:117:0) after 1m0s
[02:30:00.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:117:0)
[02:30:00.121] Timer tick, asking Buildbucket for the build status
[02:30:00.375] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:00.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:118:0) after 1m0s
[02:31:00.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:118:0)
[02:31:00.429] Timer tick, asking Buildbucket for the build status
[02:31:00.499] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:00.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:119:0) after 1m0s
[02:32:00.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:119:0)
[02:32:00.895] Timer tick, asking Buildbucket for the build status
[02:32:00.984] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:00.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:120:0) after 1m0s
[02:33:01.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:120:0)
[02:33:01.198] Timer tick, asking Buildbucket for the build status
[02:33:03.424] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:03.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:121:0) after 1m0s
[02:34:03.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:121:0)
[02:34:03.445] Timer tick, asking Buildbucket for the build status
[02:34:03.649] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:03.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:122:0) after 1m0s
[02:35:03.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:122:0)
[02:35:03.678] Timer tick, asking Buildbucket for the build status
[02:35:04.095] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:04.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:123:0) after 1m0s
[02:36:04.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:123:0)
[02:36:04.124] Timer tick, asking Buildbucket for the build status
[02:36:04.381] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:04.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:124:0) after 1m0s
[02:37:04.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:124:0)
[02:37:04.456] Timer tick, asking Buildbucket for the build status
[02:37:04.529] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:04.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:125:0) after 1m0s
[02:38:04.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:125:0)
[02:38:04.672] Timer tick, asking Buildbucket for the build status
[02:38:05.126] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:05.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:126:0) after 1m0s
[02:39:05.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:126:0)
[02:39:05.151] Timer tick, asking Buildbucket for the build status
[02:39:05.214] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:05.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:127:0) after 1m0s
[02:40:05.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:127:0)
[02:40:05.210] Timer tick, asking Buildbucket for the build status
[02:40:05.336] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:05.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:128:0) after 1m0s
[02:41:05.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:128:0)
[02:41:05.427] Timer tick, asking Buildbucket for the build status
[02:41:05.539] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:05.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:129:0) after 1m0s
[02:42:05.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:129:0)
[02:42:05.596] Timer tick, asking Buildbucket for the build status
[02:42:05.668] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:05.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:130:0) after 1m0s
[02:43:05.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:130:0)
[02:43:05.722] Timer tick, asking Buildbucket for the build status
[02:43:05.780] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:05.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:131:0) after 1m0s
[02:44:05.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:131:0)
[02:44:05.810] Timer tick, asking Buildbucket for the build status
[02:44:06.518] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:06.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:132:0) after 1m0s
[02:45:06.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:132:0)
[02:45:06.523] Timer tick, asking Buildbucket for the build status
[02:45:06.629] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:06.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:133:0) after 1m0s
[02:46:06.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:133:0)
[02:46:06.647] Timer tick, asking Buildbucket for the build status
[02:46:06.712] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:06.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:134:0) after 1m0s
[02:47:06.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:134:0)
[02:47:06.740] Timer tick, asking Buildbucket for the build status
[02:47:06.803] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:06.803] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:135:0) after 1m0s
[02:48:06.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:135:0)
[02:48:06.832] Timer tick, asking Buildbucket for the build status
[02:48:06.908] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:06.908] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:136:0) after 1m0s
[02:49:07.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:136:0)
[02:49:07.104] Timer tick, asking Buildbucket for the build status
[02:49:07.182] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:07.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:137:0) after 1m0s
[02:50:07.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:137:0)
[02:50:07.286] Timer tick, asking Buildbucket for the build status
[02:50:07.423] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:07.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:138:0) after 1m0s
[02:51:07.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:138:0)
[02:51:07.925] Timer tick, asking Buildbucket for the build status
[02:51:08.025] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:08.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:139:0) after 1m0s
[02:52:08.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:139:0)
[02:52:08.307] Timer tick, asking Buildbucket for the build status
[02:52:08.371] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:08.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:140:0) after 1m0s
[02:53:08.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:140:0)
[02:53:08.437] Timer tick, asking Buildbucket for the build status
[02:53:08.517] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:08.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:141:0) after 1m0s
[02:54:08.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:141:0)
[02:54:08.803] Timer tick, asking Buildbucket for the build status
[02:54:08.922] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:08.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:142:0) after 1m0s
[02:55:09.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:142:0)
[02:55:09.270] Timer tick, asking Buildbucket for the build status
[02:55:09.439] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:09.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:143:0) after 1m0s
[02:56:09.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:143:0)
[02:56:09.463] Timer tick, asking Buildbucket for the build status
[02:56:10.872] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:10.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:144:0) after 1m0s
[02:57:11.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:144:0)
[02:57:11.032] Timer tick, asking Buildbucket for the build status
[02:57:11.121] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:11.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:145:0) after 1m0s
[02:58:11.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:145:0)
[02:58:11.163] Timer tick, asking Buildbucket for the build status
[02:58:11.416] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:11.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:146:0) after 1m0s
[02:59:11.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:146:0)
[02:59:11.478] Timer tick, asking Buildbucket for the build status
[02:59:11.643] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:11.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:147:0) after 1m0s
[03:00:11.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:147:0)
[03:00:11.735] Timer tick, asking Buildbucket for the build status
[03:00:13.464] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:13.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:148:0) after 1m0s
[03:01:13.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:148:0)
[03:01:13.943] Timer tick, asking Buildbucket for the build status
[03:01:14.294] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:14.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:149:0) after 1m0s
[03:02:14.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:149:0)
[03:02:14.308] Timer tick, asking Buildbucket for the build status
[03:02:14.541] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:14.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:150:0) after 1m0s
[03:03:14.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:150:0)
[03:03:14.588] Timer tick, asking Buildbucket for the build status
[03:03:14.663] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:14.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:151:0) after 1m0s
[03:04:14.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:151:0)
[03:04:14.724] Timer tick, asking Buildbucket for the build status
[03:04:14.924] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:14.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:152:0) after 1m0s
[03:05:14.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:152:0)
[03:05:14.950] Timer tick, asking Buildbucket for the build status
[03:05:15.233] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:15.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:153:0) after 1m0s
[03:06:15.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:153:0)
[03:06:15.219] Timer tick, asking Buildbucket for the build status
[03:06:15.287] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:15.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:154:0) after 1m0s
[03:07:15.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:154:0)
[03:07:15.435] Timer tick, asking Buildbucket for the build status
[03:07:15.517] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:15.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:155:0) after 1m0s
[03:08:15.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:155:0)
[03:08:15.696] Timer tick, asking Buildbucket for the build status
[03:08:15.797] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:15.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:156:0) after 1m0s
[03:09:15.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:156:0)
[03:09:15.985] Timer tick, asking Buildbucket for the build status
[03:09:16.096] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:16.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:157:0) after 1m0s
[03:10:16.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:157:0)
[03:10:16.128] Timer tick, asking Buildbucket for the build status
[03:10:16.593] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:16.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:158:0) after 1m0s
[03:11:16.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:158:0)
[03:11:16.616] Timer tick, asking Buildbucket for the build status
[03:11:16.744] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:16.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:159:0) after 1m0s
[03:12:16.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:159:0)
[03:12:16.793] Timer tick, asking Buildbucket for the build status
[03:12:17.711] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:17.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:160:0) after 1m0s
[03:13:17.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:160:0)
[03:13:17.737] Timer tick, asking Buildbucket for the build status
[03:13:17.827] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:17.827] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:161:0) after 1m0s
[03:14:17.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:161:0)
[03:14:17.854] Timer tick, asking Buildbucket for the build status
[03:14:18.774] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:18.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:162:0) after 1m0s
[03:15:18.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:162:0)
[03:15:18.807] Timer tick, asking Buildbucket for the build status
[03:15:19.126] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:19.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:163:0) after 1m0s
[03:16:19.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:163:0)
[03:16:19.193] Timer tick, asking Buildbucket for the build status
[03:16:19.269] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:19.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:164:0) after 1m0s
[03:17:19.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:164:0)
[03:17:19.381] Timer tick, asking Buildbucket for the build status
[03:17:19.459] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:19.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:165:0) after 1m0s
[03:18:19.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:165:0)
[03:18:19.488] Timer tick, asking Buildbucket for the build status
[03:18:19.558] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:19.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:166:0) after 1m0s
[03:19:19.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:166:0)
[03:19:19.608] Timer tick, asking Buildbucket for the build status
[03:19:19.665] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:19.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:167:0) after 1m0s
[03:20:20.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:167:0)
[03:20:20.909] Timer tick, asking Buildbucket for the build status
[03:20:21.962] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:21.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:168:0) after 1m0s
[03:21:22.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:168:0)
[03:21:22.168] Timer tick, asking Buildbucket for the build status
[03:21:22.295] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:22.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:169:0) after 1m0s
[03:22:22.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:169:0)
[03:22:22.416] Timer tick, asking Buildbucket for the build status
[03:22:22.500] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:22.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:170:0) after 1m0s
[03:23:22.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:170:0)
[03:23:22.619] Timer tick, asking Buildbucket for the build status
[03:23:22.724] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:22.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:171:0) after 1m0s
[03:24:22.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:171:0)
[03:24:22.781] Timer tick, asking Buildbucket for the build status
[03:24:22.927] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:22.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:172:0) after 1m0s
[03:25:23.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:172:0)
[03:25:23.062] Timer tick, asking Buildbucket for the build status
[03:25:23.241] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:23.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:173:0) after 1m0s
[03:26:23.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:173:0)
[03:26:23.342] Timer tick, asking Buildbucket for the build status
[03:26:23.446] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:23.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:174:0) after 1m0s
[03:27:23.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:174:0)
[03:27:23.493] Timer tick, asking Buildbucket for the build status
[03:27:23.692] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:23.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:175:0) after 1m0s
[03:28:23.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:175:0)
[03:28:23.805] Timer tick, asking Buildbucket for the build status
[03:28:23.895] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:23.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:176:0) after 1m0s
[03:29:24.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:176:0)
[03:29:24.004] Timer tick, asking Buildbucket for the build status
[03:29:24.101] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:24.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:177:0) after 1m0s
[03:30:24.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:177:0)
[03:30:24.239] Timer tick, asking Buildbucket for the build status
[03:30:24.727] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:24.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:178:0) after 1m0s
[03:31:24.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:178:0)
[03:31:24.977] Timer tick, asking Buildbucket for the build status
[03:31:25.044] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:25.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:179:0) after 1m0s
[03:32:25.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:179:0)
[03:32:25.170] Timer tick, asking Buildbucket for the build status
[03:32:25.250] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:25.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:180:0) after 1m0s
[03:33:25.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:180:0)
[03:33:25.374] Timer tick, asking Buildbucket for the build status
[03:33:25.528] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:25.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:181:0) after 1m0s
[03:34:25.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:181:0)
[03:34:25.637] Timer tick, asking Buildbucket for the build status
[03:34:25.700] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:25.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:182:0) after 1m0s
[03:35:25.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:182:0)
[03:35:25.764] Timer tick, asking Buildbucket for the build status
[03:35:25.869] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:25.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:183:0) after 1m0s
[03:36:26.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:183:0)
[03:36:26.045] Timer tick, asking Buildbucket for the build status
[03:36:26.327] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:26.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:184:0) after 1m0s
[03:37:26.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:184:0)
[03:37:26.388] Timer tick, asking Buildbucket for the build status
[03:37:26.624] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:26.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:185:0) after 1m0s
[03:38:26.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:185:0)
[03:38:26.793] Timer tick, asking Buildbucket for the build status
[03:38:27.113] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:27.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:186:0) after 1m0s
[03:39:27.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:186:0)
[03:39:27.175] Timer tick, asking Buildbucket for the build status
[03:39:27.272] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:27.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:187:0) after 1m0s
[03:40:27.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:187:0)
[03:40:27.322] Timer tick, asking Buildbucket for the build status
[03:40:27.823] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:27.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:188:0) after 1m0s
[03:41:27.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:188:0)
[03:41:27.853] Timer tick, asking Buildbucket for the build status
[03:41:28.238] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:28.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:189:0) after 1m0s
[03:42:28.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:189:0)
[03:42:28.367] Timer tick, asking Buildbucket for the build status
[03:42:28.515] Build 8871491257447983888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:28.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036921319419370432:190:0) after 1m0s
[03:42:55.781] Received PubSub notification, asking Buildbucket for the build status
[03:42:55.853] Build 8871491257447983888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:42:55.853] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1597894975068680",
    "created_by": "project:infra",
    "created_ts": "1597883661757903",
    "id": "8871491257447983888",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"86.0.4238.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"51384d12-6650-4c3c-a028-1f6bf61fa17d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"86.0.4238.2\"}}",
    "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-n33d\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1597883945961246",
    "status": "COMPLETED",
    "status_changed_ts": "1597894975502701",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036921319419370432",
      "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/8871491257447983888/+/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:4e221b48ce31cf10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1597894975519077",
    "url": "https://ci.chromium.org/b/8871491257447983888",
    "utcnow_ts": "1597894975844336"
  }
}
[03:42:55.853] Invocation finished in 3h8m35.360056681s with status SUCCEEDED