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

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

{
  "version": "87.0.4262.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[08:09:45.567] New invocation is queued and will start shortly
[08:09:45.567] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:09:46.722] Starting the invocation (attempt 1)
[08:09:46.788] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:09:46.790] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:09:46.790] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9034808936792111008",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"87.0.4262.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"65cd3143-f0e1-49a3-bb97-1b801a53ee0d\",\"title\":\"publish_tarball/0\"}]},\"version\":\"87.0.4262.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9034808936792111008",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[08:09:47.816] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1599898186975282",
    "id": "8869378874652512160",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4262.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"65cd3143-f0e1-49a3-bb97-1b801a53ee0d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4262.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1599898187563757",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9034808936792111008",
      "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/8869378874652512160/+/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": "1599898187564137",
    "url": "https://ci.chromium.org/b/8869378874652512160",
    "utcnow_ts": "1599898187800867"
  }
}
[08:09:47.816] Task URL: https://ci.chromium.org/b/8869378874652512160
[08:09:47.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:2:0) after 1m0s
[08:10:28.172] Received PubSub notification, asking Buildbucket for the build status
[08:10:28.402] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:48.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:2:0)
[08:10:48.103] Timer tick, asking Buildbucket for the build status
[08:10:48.378] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:48.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:4:0) after 1m0s
[08:11:48.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:4:0)
[08:11:48.600] Timer tick, asking Buildbucket for the build status
[08:11:48.757] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:48.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:5:0) after 1m0s
[08:12:48.819] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:5:0)
[08:12:48.819] Timer tick, asking Buildbucket for the build status
[08:12:48.964] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:48.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:6:0) after 1m0s
[08:13:49.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:6:0)
[08:13:49.239] Timer tick, asking Buildbucket for the build status
[08:13:49.353] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:49.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:7:0) after 1m0s
[08:14:49.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:7:0)
[08:14:49.381] Timer tick, asking Buildbucket for the build status
[08:14:49.786] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:49.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:8:0) after 1m0s
[08:15:49.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:8:0)
[08:15:49.893] Timer tick, asking Buildbucket for the build status
[08:15:49.962] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:49.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:9:0) after 1m0s
[08:16:50.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:9:0)
[08:16:50.042] Timer tick, asking Buildbucket for the build status
[08:16:50.101] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:50.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:10:0) after 1m0s
[08:17:50.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:10:0)
[08:17:50.137] Timer tick, asking Buildbucket for the build status
[08:17:50.201] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:50.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:11:0) after 1m0s
[08:18:50.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:11:0)
[08:18:50.220] Timer tick, asking Buildbucket for the build status
[08:18:50.284] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:50.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:12:0) after 1m0s
[08:19:50.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:12:0)
[08:19:50.324] Timer tick, asking Buildbucket for the build status
[08:19:50.628] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:50.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:13:0) after 1m0s
[08:20:50.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:13:0)
[08:20:50.688] Timer tick, asking Buildbucket for the build status
[08:20:50.776] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:50.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:14:0) after 1m0s
[08:21:50.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:14:0)
[08:21:50.753] Timer tick, asking Buildbucket for the build status
[08:21:50.820] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:50.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:15:0) after 1m0s
[08:22:50.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:15:0)
[08:22:50.840] Timer tick, asking Buildbucket for the build status
[08:22:50.908] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:50.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:16:0) after 1m0s
[08:23:50.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:16:0)
[08:23:50.953] Timer tick, asking Buildbucket for the build status
[08:23:51.201] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:51.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:17:0) after 1m0s
[08:24:51.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:17:0)
[08:24:51.224] Timer tick, asking Buildbucket for the build status
[08:24:51.362] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:51.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:18:0) after 1m0s
[08:25:51.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:18:0)
[08:25:51.412] Timer tick, asking Buildbucket for the build status
[08:25:51.625] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:51.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:19:0) after 1m0s
[08:26:51.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:19:0)
[08:26:51.731] Timer tick, asking Buildbucket for the build status
[08:26:51.793] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:51.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:20:0) after 1m0s
[08:27:51.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:20:0)
[08:27:51.903] Timer tick, asking Buildbucket for the build status
[08:27:52.088] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:52.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:21:0) after 1m0s
[08:28:52.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:21:0)
[08:28:52.112] Timer tick, asking Buildbucket for the build status
[08:28:52.226] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:52.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:22:0) after 1m0s
[08:29:52.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:22:0)
[08:29:52.254] Timer tick, asking Buildbucket for the build status
[08:29:52.382] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:52.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:23:0) after 1m0s
[08:30:52.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:23:0)
[08:30:52.417] Timer tick, asking Buildbucket for the build status
[08:30:52.578] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:52.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:24:0) after 1m0s
[08:31:52.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:24:0)
[08:31:52.600] Timer tick, asking Buildbucket for the build status
[08:31:52.784] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:52.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:25:0) after 1m0s
[08:32:52.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:25:0)
[08:32:52.805] Timer tick, asking Buildbucket for the build status
[08:32:52.877] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:52.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:26:0) after 1m0s
[08:33:52.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:26:0)
[08:33:52.919] Timer tick, asking Buildbucket for the build status
[08:33:52.983] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:52.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:27:0) after 1m0s
[08:34:53.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:27:0)
[08:34:53.038] Timer tick, asking Buildbucket for the build status
[08:34:53.424] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:53.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:28:0) after 1m0s
[08:35:53.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:28:0)
[08:35:53.447] Timer tick, asking Buildbucket for the build status
[08:35:53.608] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:53.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:29:0) after 1m0s
[08:36:53.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:29:0)
[08:36:53.662] Timer tick, asking Buildbucket for the build status
[08:36:53.814] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:53.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:30:0) after 1m0s
[08:37:53.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:30:0)
[08:37:53.836] Timer tick, asking Buildbucket for the build status
[08:37:54.105] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:54.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:31:0) after 1m0s
[08:38:54.194] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:31:0)
[08:38:54.194] Timer tick, asking Buildbucket for the build status
[08:38:54.311] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:54.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:32:0) after 1m0s
[08:39:54.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:32:0)
[08:39:54.327] Timer tick, asking Buildbucket for the build status
[08:39:54.478] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:54.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:33:0) after 1m0s
[08:40:54.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:33:0)
[08:40:54.525] Timer tick, asking Buildbucket for the build status
[08:40:54.595] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:54.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:34:0) after 1m0s
[08:41:54.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:34:0)
[08:41:54.620] Timer tick, asking Buildbucket for the build status
[08:41:54.678] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:54.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:35:0) after 1m0s
[08:42:54.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:35:0)
[08:42:54.855] Timer tick, asking Buildbucket for the build status
[08:42:54.922] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:54.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:36:0) after 1m0s
[08:43:54.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:36:0)
[08:43:54.950] Timer tick, asking Buildbucket for the build status
[08:43:55.222] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:55.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:37:0) after 1m0s
[08:44:55.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:37:0)
[08:44:55.259] Timer tick, asking Buildbucket for the build status
[08:44:55.608] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:55.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:38:0) after 1m0s
[08:45:55.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:38:0)
[08:45:55.627] Timer tick, asking Buildbucket for the build status
[08:45:55.707] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:55.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:39:0) after 1m0s
[08:46:55.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:39:0)
[08:46:55.764] Timer tick, asking Buildbucket for the build status
[08:46:55.873] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:55.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:40:0) after 1m0s
[08:47:55.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:40:0)
[08:47:55.917] Timer tick, asking Buildbucket for the build status
[08:47:56.071] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:56.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:41:0) after 1m0s
[08:48:56.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:41:0)
[08:48:56.098] Timer tick, asking Buildbucket for the build status
[08:48:56.282] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:56.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:42:0) after 1m0s
[08:49:56.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:42:0)
[08:49:56.314] Timer tick, asking Buildbucket for the build status
[08:49:56.474] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:56.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:43:0) after 1m0s
[08:50:56.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:43:0)
[08:50:56.537] Timer tick, asking Buildbucket for the build status
[08:50:56.895] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:56.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:44:0) after 1m0s
[08:51:56.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:44:0)
[08:51:56.892] Timer tick, asking Buildbucket for the build status
[08:51:56.952] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:56.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:45:0) after 1m0s
[08:52:57.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:45:0)
[08:52:57.023] Timer tick, asking Buildbucket for the build status
[08:52:57.226] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:57.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:46:0) after 1m0s
[08:53:57.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:46:0)
[08:53:57.257] Timer tick, asking Buildbucket for the build status
[08:53:57.308] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:57.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:47:0) after 1m0s
[08:54:57.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:47:0)
[08:54:57.336] Timer tick, asking Buildbucket for the build status
[08:54:57.447] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:57.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:48:0) after 1m0s
[08:55:57.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:48:0)
[08:55:57.475] Timer tick, asking Buildbucket for the build status
[08:55:57.572] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:57.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:49:0) after 1m0s
[08:56:57.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:49:0)
[08:56:57.642] Timer tick, asking Buildbucket for the build status
[08:56:57.888] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:57.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:50:0) after 1m0s
[08:57:57.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:50:0)
[08:57:57.886] Timer tick, asking Buildbucket for the build status
[08:57:57.982] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:57.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:51:0) after 1m0s
[08:58:58.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:51:0)
[08:58:58.023] Timer tick, asking Buildbucket for the build status
[08:58:58.096] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:58.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:52:0) after 1m0s
[08:59:58.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:52:0)
[08:59:58.123] Timer tick, asking Buildbucket for the build status
[08:59:58.264] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:58.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:53:0) after 1m0s
[09:00:58.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:53:0)
[09:00:58.307] Timer tick, asking Buildbucket for the build status
[09:00:58.389] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:58.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:54:0) after 1m0s
[09:01:58.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:54:0)
[09:01:58.423] Timer tick, asking Buildbucket for the build status
[09:01:58.481] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:58.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:55:0) after 1m0s
[09:02:58.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:55:0)
[09:02:58.518] Timer tick, asking Buildbucket for the build status
[09:02:58.709] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:58.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:56:0) after 1m0s
[09:03:58.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:56:0)
[09:03:58.734] Timer tick, asking Buildbucket for the build status
[09:03:58.810] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:58.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:57:0) after 1m0s
[09:04:58.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:57:0)
[09:04:58.832] Timer tick, asking Buildbucket for the build status
[09:04:58.977] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:58.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:58:0) after 1m0s
[09:05:59.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:58:0)
[09:05:59.088] Timer tick, asking Buildbucket for the build status
[09:05:59.152] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:59.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:59:0) after 1m0s
[09:06:59.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:59:0)
[09:06:59.174] Timer tick, asking Buildbucket for the build status
[09:06:59.246] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:59.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:60:0) after 1m0s
[09:07:59.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:60:0)
[09:07:59.271] Timer tick, asking Buildbucket for the build status
[09:07:59.419] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:59.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:61:0) after 1m0s
[09:08:59.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:61:0)
[09:08:59.462] Timer tick, asking Buildbucket for the build status
[09:08:59.611] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:59.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:62:0) after 1m0s
[09:09:59.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:62:0)
[09:09:59.670] Timer tick, asking Buildbucket for the build status
[09:09:59.802] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:59.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:63:0) after 1m0s
[09:10:59.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:63:0)
[09:10:59.863] Timer tick, asking Buildbucket for the build status
[09:10:59.934] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:59.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:64:0) after 1m0s
[09:12:00.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:64:0)
[09:12:00.032] Timer tick, asking Buildbucket for the build status
[09:12:00.122] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:00.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:65:0) after 1m0s
[09:13:00.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:65:0)
[09:13:00.316] Timer tick, asking Buildbucket for the build status
[09:13:00.387] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:00.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:66:0) after 1m0s
[09:14:01.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:66:0)
[09:14:01.046] Timer tick, asking Buildbucket for the build status
[09:14:01.143] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:01.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:67:0) after 1m0s
[09:15:14.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:67:0)
[09:15:14.339] Timer tick, asking Buildbucket for the build status
[09:15:14.483] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:14.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:68:0) after 1m0s
[09:16:14.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:68:0)
[09:16:14.560] Timer tick, asking Buildbucket for the build status
[09:16:14.653] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:14.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:69:0) after 1m0s
[09:17:14.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:69:0)
[09:17:14.644] Timer tick, asking Buildbucket for the build status
[09:17:14.708] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:14.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:70:0) after 1m0s
[09:18:14.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:70:0)
[09:18:14.914] Timer tick, asking Buildbucket for the build status
[09:18:15.006] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:15.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:71:0) after 1m0s
[09:19:15.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:71:0)
[09:19:15.580] Timer tick, asking Buildbucket for the build status
[09:19:15.806] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:15.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:72:0) after 1m0s
[09:20:16.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:72:0)
[09:20:16.596] Timer tick, asking Buildbucket for the build status
[09:20:16.881] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:16.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:73:0) after 1m0s
[09:21:16.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:73:0)
[09:21:16.955] Timer tick, asking Buildbucket for the build status
[09:21:17.028] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:17.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:74:0) after 1m0s
[09:22:17.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:74:0)
[09:22:17.155] Timer tick, asking Buildbucket for the build status
[09:22:17.346] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:17.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:75:0) after 1m0s
[09:23:17.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:75:0)
[09:23:17.472] Timer tick, asking Buildbucket for the build status
[09:23:17.531] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:17.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:76:0) after 1m0s
[09:24:17.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:76:0)
[09:24:17.640] Timer tick, asking Buildbucket for the build status
[09:24:17.710] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:17.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:77:0) after 1m0s
[09:25:17.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:77:0)
[09:25:17.808] Timer tick, asking Buildbucket for the build status
[09:25:17.917] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:17.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:78:0) after 1m0s
[09:26:17.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:78:0)
[09:26:17.987] Timer tick, asking Buildbucket for the build status
[09:26:18.078] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:18.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:79:0) after 1m0s
[09:27:18.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:79:0)
[09:27:18.102] Timer tick, asking Buildbucket for the build status
[09:27:18.161] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:18.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:80:0) after 1m0s
[09:28:18.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:80:0)
[09:28:18.221] Timer tick, asking Buildbucket for the build status
[09:28:19.088] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:19.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:81:0) after 1m0s
[09:29:19.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:81:0)
[09:29:19.225] Timer tick, asking Buildbucket for the build status
[09:29:19.405] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:19.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:82:0) after 1m0s
[09:30:19.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:82:0)
[09:30:19.419] Timer tick, asking Buildbucket for the build status
[09:30:19.640] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:19.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:83:0) after 1m0s
[09:31:19.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:83:0)
[09:31:19.939] Timer tick, asking Buildbucket for the build status
[09:31:20.022] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:20.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:84:0) after 1m0s
[09:32:20.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:84:0)
[09:32:20.162] Timer tick, asking Buildbucket for the build status
[09:32:20.259] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:20.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:85:0) after 1m0s
[09:33:20.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:85:0)
[09:33:20.319] Timer tick, asking Buildbucket for the build status
[09:33:20.409] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:20.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:86:0) after 1m0s
[09:34:20.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:86:0)
[09:34:20.432] Timer tick, asking Buildbucket for the build status
[09:34:20.762] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:20.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:87:0) after 1m0s
[09:35:20.832] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:87:0)
[09:35:20.832] Timer tick, asking Buildbucket for the build status
[09:35:21.841] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:21.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:88:0) after 1m0s
[09:36:21.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:88:0)
[09:36:21.969] Timer tick, asking Buildbucket for the build status
[09:36:22.044] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:22.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:89:0) after 1m0s
[09:37:22.181] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:89:0)
[09:37:22.181] Timer tick, asking Buildbucket for the build status
[09:37:22.405] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:22.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:90:0) after 1m0s
[09:38:22.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:90:0)
[09:38:22.427] Timer tick, asking Buildbucket for the build status
[09:38:22.502] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:22.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:91:0) after 1m0s
[09:39:22.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:91:0)
[09:39:22.527] Timer tick, asking Buildbucket for the build status
[09:39:22.596] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:22.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:92:0) after 1m0s
[09:40:22.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:92:0)
[09:40:22.672] Timer tick, asking Buildbucket for the build status
[09:40:22.775] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:22.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:93:0) after 1m0s
[09:41:22.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:93:0)
[09:41:22.815] Timer tick, asking Buildbucket for the build status
[09:41:22.876] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:22.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:94:0) after 1m0s
[09:42:22.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:94:0)
[09:42:22.959] Timer tick, asking Buildbucket for the build status
[09:42:23.046] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:23.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:95:0) after 1m0s
[09:43:23.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:95:0)
[09:43:23.106] Timer tick, asking Buildbucket for the build status
[09:43:23.399] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:23.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:96:0) after 1m0s
[09:44:23.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:96:0)
[09:44:23.515] Timer tick, asking Buildbucket for the build status
[09:44:23.701] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:23.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:97:0) after 1m0s
[09:45:23.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:97:0)
[09:45:23.711] Timer tick, asking Buildbucket for the build status
[09:45:24.064] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:24.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:98:0) after 1m0s
[09:46:24.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:98:0)
[09:46:24.092] Timer tick, asking Buildbucket for the build status
[09:46:24.148] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:24.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:99:0) after 1m0s
[09:47:24.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:99:0)
[09:47:24.235] Timer tick, asking Buildbucket for the build status
[09:47:24.637] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:24.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:100:0) after 1m0s
[09:48:24.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:100:0)
[09:48:24.631] Timer tick, asking Buildbucket for the build status
[09:48:24.714] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:24.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:101:0) after 1m0s
[09:49:24.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:101:0)
[09:49:24.817] Timer tick, asking Buildbucket for the build status
[09:49:24.869] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:24.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:102:0) after 1m0s
[09:50:24.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:102:0)
[09:50:24.972] Timer tick, asking Buildbucket for the build status
[09:50:25.403] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:25.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:103:0) after 1m0s
[09:51:25.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:103:0)
[09:51:25.758] Timer tick, asking Buildbucket for the build status
[09:51:25.821] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:25.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:104:0) after 1m0s
[09:52:25.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:104:0)
[09:52:25.918] Timer tick, asking Buildbucket for the build status
[09:52:25.977] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:25.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:105:0) after 1m0s
[09:53:26.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:105:0)
[09:53:26.012] Timer tick, asking Buildbucket for the build status
[09:53:26.238] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:26.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:106:0) after 1m0s
[09:54:26.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:106:0)
[09:54:26.265] Timer tick, asking Buildbucket for the build status
[09:54:26.451] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:26.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:107:0) after 1m0s
[09:55:26.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:107:0)
[09:55:26.483] Timer tick, asking Buildbucket for the build status
[09:55:26.595] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:26.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:108:0) after 1m0s
[09:56:26.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:108:0)
[09:56:26.783] Timer tick, asking Buildbucket for the build status
[09:56:26.857] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:26.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:109:0) after 1m0s
[09:57:26.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:109:0)
[09:57:26.938] Timer tick, asking Buildbucket for the build status
[09:57:27.020] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:27.020] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:110:0) after 1m0s
[09:58:27.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:110:0)
[09:58:27.108] Timer tick, asking Buildbucket for the build status
[09:58:27.220] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:27.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:111:0) after 1m0s
[09:59:27.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:111:0)
[09:59:27.260] Timer tick, asking Buildbucket for the build status
[09:59:27.333] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:27.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:112:0) after 1m0s
[10:00:27.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:112:0)
[10:00:27.374] Timer tick, asking Buildbucket for the build status
[10:00:28.329] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:28.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:113:0) after 1m0s
[10:01:28.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:113:0)
[10:01:28.414] Timer tick, asking Buildbucket for the build status
[10:01:28.473] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:28.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:114:0) after 1m0s
[10:02:28.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:114:0)
[10:02:28.513] Timer tick, asking Buildbucket for the build status
[10:02:28.949] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:28.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:115:0) after 1m0s
[10:03:29.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:115:0)
[10:03:29.211] Timer tick, asking Buildbucket for the build status
[10:03:29.388] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:29.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:116:0) after 1m0s
[10:04:29.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:116:0)
[10:04:29.614] Timer tick, asking Buildbucket for the build status
[10:04:29.818] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:29.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:117:0) after 1m0s
[10:05:29.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:117:0)
[10:05:29.842] Timer tick, asking Buildbucket for the build status
[10:05:29.957] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:29.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:118:0) after 1m0s
[10:06:30.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:118:0)
[10:06:30.037] Timer tick, asking Buildbucket for the build status
[10:06:30.105] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:30.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:119:0) after 1m0s
[10:07:30.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:119:0)
[10:07:30.283] Timer tick, asking Buildbucket for the build status
[10:07:30.508] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:30.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:120:0) after 1m0s
[10:08:30.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:120:0)
[10:08:30.559] Timer tick, asking Buildbucket for the build status
[10:08:30.632] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:30.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:121:0) after 1m0s
[10:09:30.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:121:0)
[10:09:30.910] Timer tick, asking Buildbucket for the build status
[10:09:30.973] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:30.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:122:0) after 1m0s
[10:10:31.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:122:0)
[10:10:31.116] Timer tick, asking Buildbucket for the build status
[10:10:31.216] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:31.216] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:123:0) after 1m0s
[10:11:31.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:123:0)
[10:11:31.303] Timer tick, asking Buildbucket for the build status
[10:11:31.383] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:31.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:124:0) after 1m0s
[10:12:31.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:124:0)
[10:12:31.483] Timer tick, asking Buildbucket for the build status
[10:12:31.571] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:31.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:125:0) after 1m0s
[10:13:31.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:125:0)
[10:13:31.946] Timer tick, asking Buildbucket for the build status
[10:13:32.116] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:32.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:126:0) after 1m0s
[10:14:32.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:126:0)
[10:14:32.145] Timer tick, asking Buildbucket for the build status
[10:14:32.219] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:32.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:127:0) after 1m0s
[10:15:32.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:127:0)
[10:15:32.369] Timer tick, asking Buildbucket for the build status
[10:15:32.474] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:32.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:128:0) after 1m0s
[10:16:32.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:128:0)
[10:16:32.584] Timer tick, asking Buildbucket for the build status
[10:16:32.716] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:32.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:129:0) after 1m0s
[10:17:32.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:129:0)
[10:17:32.906] Timer tick, asking Buildbucket for the build status
[10:17:33.004] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:33.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:130:0) after 1m0s
[10:18:33.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:130:0)
[10:18:33.135] Timer tick, asking Buildbucket for the build status
[10:18:33.198] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:33.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:131:0) after 1m0s
[10:19:33.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:131:0)
[10:19:33.235] Timer tick, asking Buildbucket for the build status
[10:19:33.304] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:33.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:132:0) after 1m0s
[10:20:33.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:132:0)
[10:20:33.355] Timer tick, asking Buildbucket for the build status
[10:20:33.453] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:33.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:133:0) after 1m0s
[10:21:33.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:133:0)
[10:21:33.479] Timer tick, asking Buildbucket for the build status
[10:21:33.554] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:33.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:134:0) after 1m0s
[10:22:33.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:134:0)
[10:22:33.609] Timer tick, asking Buildbucket for the build status
[10:22:33.820] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:33.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:135:0) after 1m0s
[10:23:33.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:135:0)
[10:23:33.890] Timer tick, asking Buildbucket for the build status
[10:23:34.182] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:34.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:136:0) after 1m0s
[10:24:34.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:136:0)
[10:24:34.326] Timer tick, asking Buildbucket for the build status
[10:24:34.637] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:34.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:137:0) after 1m0s
[10:25:34.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:137:0)
[10:25:34.666] Timer tick, asking Buildbucket for the build status
[10:25:34.795] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:34.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:138:0) after 1m0s
[10:26:35.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:138:0)
[10:26:35.052] Timer tick, asking Buildbucket for the build status
[10:26:35.838] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:35.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:139:0) after 1m0s
[10:27:35.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:139:0)
[10:27:35.900] Timer tick, asking Buildbucket for the build status
[10:27:35.963] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:35.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:140:0) after 1m0s
[10:28:36.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:140:0)
[10:28:36.002] Timer tick, asking Buildbucket for the build status
[10:28:36.071] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:36.071] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:141:0) after 1m0s
[10:29:36.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:141:0)
[10:29:36.246] Timer tick, asking Buildbucket for the build status
[10:29:36.597] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:36.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:142:0) after 1m0s
[10:30:36.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:142:0)
[10:30:36.676] Timer tick, asking Buildbucket for the build status
[10:30:36.864] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:36.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:143:0) after 1m0s
[10:31:36.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:143:0)
[10:31:36.848] Timer tick, asking Buildbucket for the build status
[10:31:36.911] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:36.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:144:0) after 1m0s
[10:32:36.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:144:0)
[10:32:36.944] Timer tick, asking Buildbucket for the build status
[10:32:37.113] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:37.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:145:0) after 1m0s
[10:33:37.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:145:0)
[10:33:37.177] Timer tick, asking Buildbucket for the build status
[10:33:37.620] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:37.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:146:0) after 1m0s
[10:34:37.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:146:0)
[10:34:37.711] Timer tick, asking Buildbucket for the build status
[10:34:37.786] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:37.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:147:0) after 1m0s
[10:35:37.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:147:0)
[10:35:37.820] Timer tick, asking Buildbucket for the build status
[10:35:37.928] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:37.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:148:0) after 1m0s
[10:36:38.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:148:0)
[10:36:38.076] Timer tick, asking Buildbucket for the build status
[10:36:38.154] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:38.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:149:0) after 1m0s
[10:37:38.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:149:0)
[10:37:38.211] Timer tick, asking Buildbucket for the build status
[10:37:38.263] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:38.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:150:0) after 1m0s
[10:38:38.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:150:0)
[10:38:38.515] Timer tick, asking Buildbucket for the build status
[10:38:38.715] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:38.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:151:0) after 1m0s
[10:39:38.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:151:0)
[10:39:38.867] Timer tick, asking Buildbucket for the build status
[10:39:38.976] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:38.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:152:0) after 1m0s
[10:40:39.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:152:0)
[10:40:39.041] Timer tick, asking Buildbucket for the build status
[10:40:39.298] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:39.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:153:0) after 1m0s
[10:41:39.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:153:0)
[10:41:39.342] Timer tick, asking Buildbucket for the build status
[10:41:39.426] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:39.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:154:0) after 1m0s
[10:42:39.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:154:0)
[10:42:39.453] Timer tick, asking Buildbucket for the build status
[10:42:39.515] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:39.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:155:0) after 1m0s
[10:43:39.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:155:0)
[10:43:39.550] Timer tick, asking Buildbucket for the build status
[10:43:39.612] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:39.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:156:0) after 1m0s
[10:44:39.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:156:0)
[10:44:39.644] Timer tick, asking Buildbucket for the build status
[10:44:39.900] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:39.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:157:0) after 1m0s
[10:45:39.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:157:0)
[10:45:39.936] Timer tick, asking Buildbucket for the build status
[10:45:40.047] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:40.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:158:0) after 1m0s
[10:46:40.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:158:0)
[10:46:40.093] Timer tick, asking Buildbucket for the build status
[10:46:40.223] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:40.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:159:0) after 1m0s
[10:47:40.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:159:0)
[10:47:40.246] Timer tick, asking Buildbucket for the build status
[10:47:40.325] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:40.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:160:0) after 1m0s
[10:48:40.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:160:0)
[10:48:40.365] Timer tick, asking Buildbucket for the build status
[10:48:40.432] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:40.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:161:0) after 1m0s
[10:49:40.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:161:0)
[10:49:40.457] Timer tick, asking Buildbucket for the build status
[10:49:40.522] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:40.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:162:0) after 1m0s
[10:50:40.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:162:0)
[10:50:40.589] Timer tick, asking Buildbucket for the build status
[10:50:40.696] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:40.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:163:0) after 1m0s
[10:51:40.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:163:0)
[10:51:40.724] Timer tick, asking Buildbucket for the build status
[10:51:40.790] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:40.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:164:0) after 1m0s
[10:52:40.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:164:0)
[10:52:40.828] Timer tick, asking Buildbucket for the build status
[10:52:41.331] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:41.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:165:0) after 1m0s
[10:53:41.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:165:0)
[10:53:41.356] Timer tick, asking Buildbucket for the build status
[10:53:41.424] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:41.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:166:0) after 1m0s
[10:54:41.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:166:0)
[10:54:41.452] Timer tick, asking Buildbucket for the build status
[10:54:41.534] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:41.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:167:0) after 1m0s
[10:55:41.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:167:0)
[10:55:41.562] Timer tick, asking Buildbucket for the build status
[10:55:41.750] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:41.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:168:0) after 1m0s
[10:56:41.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:168:0)
[10:56:41.740] Timer tick, asking Buildbucket for the build status
[10:56:41.807] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:41.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:169:0) after 1m0s
[10:57:41.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:169:0)
[10:57:41.892] Timer tick, asking Buildbucket for the build status
[10:57:42.000] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:42.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:170:0) after 1m0s
[10:58:42.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:170:0)
[10:58:42.015] Timer tick, asking Buildbucket for the build status
[10:58:42.201] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:42.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:171:0) after 1m0s
[10:59:42.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:171:0)
[10:59:42.359] Timer tick, asking Buildbucket for the build status
[10:59:42.420] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:42.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:172:0) after 1m0s
[11:00:42.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:172:0)
[11:00:42.566] Timer tick, asking Buildbucket for the build status
[11:00:43.027] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:43.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:173:0) after 1m0s
[11:01:43.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:173:0)
[11:01:43.035] Timer tick, asking Buildbucket for the build status
[11:01:43.101] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:43.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:174:0) after 1m0s
[11:02:43.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:174:0)
[11:02:43.186] Timer tick, asking Buildbucket for the build status
[11:02:43.257] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:43.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:175:0) after 1m0s
[11:03:43.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:175:0)
[11:03:43.268] Timer tick, asking Buildbucket for the build status
[11:03:43.321] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:43.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:176:0) after 1m0s
[11:04:43.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:176:0)
[11:04:43.364] Timer tick, asking Buildbucket for the build status
[11:04:43.969] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:43.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:177:0) after 1m0s
[11:05:44.055] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:177:0)
[11:05:44.055] Timer tick, asking Buildbucket for the build status
[11:05:44.413] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:44.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:178:0) after 1m0s
[11:06:44.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:178:0)
[11:06:44.473] Timer tick, asking Buildbucket for the build status
[11:06:44.570] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:44.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:179:0) after 1m0s
[11:07:44.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:179:0)
[11:07:44.615] Timer tick, asking Buildbucket for the build status
[11:07:44.672] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:44.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:180:0) after 1m0s
[11:08:44.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:180:0)
[11:08:44.823] Timer tick, asking Buildbucket for the build status
[11:08:45.198] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:45.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:181:0) after 1m0s
[11:09:45.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:181:0)
[11:09:45.255] Timer tick, asking Buildbucket for the build status
[11:09:45.487] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:45.487] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:182:0) after 1m0s
[11:10:45.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:182:0)
[11:10:45.586] Timer tick, asking Buildbucket for the build status
[11:10:45.691] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:45.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:183:0) after 1m0s
[11:11:45.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:183:0)
[11:11:45.822] Timer tick, asking Buildbucket for the build status
[11:11:45.914] Build 8869378874652512160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:45.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9034808936792111008:184:0) after 1m0s
[11:12:08.129] Received PubSub notification, asking Buildbucket for the build status
[11:12:08.188] Build 8869378874652512160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:12:08.188] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1599909127532150",
    "created_by": "project:infra",
    "created_ts": "1599898186975282",
    "id": "8869378874652512160",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4262.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"65cd3143-f0e1-49a3-bb97-1b801a53ee0d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4262.0\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-kbq3\"], \"image\": [\"chrome-xenial-20090600-f98a2a06cf8\"], \"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\": [\"5518-246c570\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1599898227292948",
    "status": "COMPLETED",
    "status_changed_ts": "1599909127827356",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9034808936792111008",
      "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/8869378874652512160/+/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:4e9a2e824afab810",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1599909127844662",
    "url": "https://ci.chromium.org/b/8869378874652512160",
    "utcnow_ts": "1599909128179865"
  }
}
[11:12:08.188] Invocation finished in 3h2m22.636494121s with status SUCCEEDED