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.4245.2"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[13:02:08.634] New invocation is queued and will start shortly
[13:02:08.634] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[13:02:09.695] Starting the invocation (attempt 1)
[13:02:09.731] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:02:09.731] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:02:09.732] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9036240093019635632",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"87.0.4245.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"985ca716-17a1-4aa6-bae0-0c4502bdff1b\",\"title\":\"publish_tarball/0\"}]},\"version\":\"87.0.4245.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:9036240093019635632",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[13:02:10.106] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1598533329827391",
    "id": "8870810031101270624",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4245.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"985ca716-17a1-4aa6-bae0-0c4502bdff1b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4245.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1598533329991913",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036240093019635632",
      "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/8870810031101270624/+/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": "1598533329992108",
    "url": "https://ci.chromium.org/b/8870810031101270624",
    "utcnow_ts": "1598533330099730"
  }
}
[13:02:10.106] Task URL: https://ci.chromium.org/b/8870810031101270624
[13:02:10.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:2:0) after 1m0s
[13:02:32.488] Received PubSub notification, asking Buildbucket for the build status
[13:02:32.571] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:10.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:2:0)
[13:03:10.209] Timer tick, asking Buildbucket for the build status
[13:03:10.456] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:10.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:4:0) after 1m0s
[13:04:10.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:4:0)
[13:04:10.475] Timer tick, asking Buildbucket for the build status
[13:04:10.578] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:10.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:5:0) after 1m0s
[13:05:10.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:5:0)
[13:05:10.670] Timer tick, asking Buildbucket for the build status
[13:05:10.795] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:10.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:6:0) after 1m0s
[13:06:10.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:6:0)
[13:06:10.860] Timer tick, asking Buildbucket for the build status
[13:06:11.167] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:11.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:7:0) after 1m0s
[13:07:11.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:7:0)
[13:07:11.281] Timer tick, asking Buildbucket for the build status
[13:07:11.367] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:11.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:8:0) after 1m0s
[13:08:11.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:8:0)
[13:08:11.368] Timer tick, asking Buildbucket for the build status
[13:08:11.473] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:11.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:9:0) after 1m0s
[13:09:11.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:9:0)
[13:09:11.512] Timer tick, asking Buildbucket for the build status
[13:09:11.882] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:11.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:10:0) after 1m0s
[13:10:11.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:10:0)
[13:10:11.948] Timer tick, asking Buildbucket for the build status
[13:10:12.222] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:12.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:11:0) after 1m0s
[13:11:12.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:11:0)
[13:11:12.308] Timer tick, asking Buildbucket for the build status
[13:11:12.362] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:12.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:12:0) after 1m0s
[13:12:12.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:12:0)
[13:12:12.494] Timer tick, asking Buildbucket for the build status
[13:12:12.684] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:12.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:13:0) after 1m0s
[13:13:12.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:13:0)
[13:13:12.738] Timer tick, asking Buildbucket for the build status
[13:13:13.361] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:13.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:14:0) after 1m0s
[13:14:13.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:14:0)
[13:14:13.393] Timer tick, asking Buildbucket for the build status
[13:14:13.573] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:13.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:15:0) after 1m0s
[13:15:13.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:15:0)
[13:15:13.560] Timer tick, asking Buildbucket for the build status
[13:15:13.645] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:13.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:16:0) after 1m0s
[13:16:13.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:16:0)
[13:16:13.696] Timer tick, asking Buildbucket for the build status
[13:16:13.786] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:13.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:17:0) after 1m0s
[13:17:13.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:17:0)
[13:17:13.828] Timer tick, asking Buildbucket for the build status
[13:17:14.252] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:14.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:18:0) after 1m0s
[13:18:14.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:18:0)
[13:18:14.323] Timer tick, asking Buildbucket for the build status
[13:18:14.415] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:14.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:19:0) after 1m0s
[13:19:14.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:19:0)
[13:19:14.434] Timer tick, asking Buildbucket for the build status
[13:19:14.757] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:14.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:20:0) after 1m0s
[13:20:14.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:20:0)
[13:20:14.861] Timer tick, asking Buildbucket for the build status
[13:20:15.008] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:15.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:21:0) after 1m0s
[13:21:15.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:21:0)
[13:21:15.063] Timer tick, asking Buildbucket for the build status
[13:21:15.182] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:15.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:22:0) after 1m0s
[13:22:15.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:22:0)
[13:22:15.209] Timer tick, asking Buildbucket for the build status
[13:22:15.883] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:15.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:23:0) after 1m0s
[13:23:15.974] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:23:0)
[13:23:15.974] Timer tick, asking Buildbucket for the build status
[13:23:16.042] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:16.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:24:0) after 1m0s
[13:24:16.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:24:0)
[13:24:16.078] Timer tick, asking Buildbucket for the build status
[13:24:16.420] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:16.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:25:0) after 1m0s
[13:25:16.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:25:0)
[13:25:16.508] Timer tick, asking Buildbucket for the build status
[13:25:16.625] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:16.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:26:0) after 1m0s
[13:26:16.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:26:0)
[13:26:16.651] Timer tick, asking Buildbucket for the build status
[13:26:16.714] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:16.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:27:0) after 1m0s
[13:27:16.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:27:0)
[13:27:16.771] Timer tick, asking Buildbucket for the build status
[13:27:16.895] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:16.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:28:0) after 1m0s
[13:28:16.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:28:0)
[13:28:16.916] Timer tick, asking Buildbucket for the build status
[13:28:17.000] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:17.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:29:0) after 1m0s
[13:29:17.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:29:0)
[13:29:17.092] Timer tick, asking Buildbucket for the build status
[13:29:17.151] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:17.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:30:0) after 1m0s
[13:30:17.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:30:0)
[13:30:17.197] Timer tick, asking Buildbucket for the build status
[13:30:17.385] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:17.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:31:0) after 1m0s
[13:31:17.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:31:0)
[13:31:17.394] Timer tick, asking Buildbucket for the build status
[13:31:17.469] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:17.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:32:0) after 1m0s
[13:32:17.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:32:0)
[13:32:17.561] Timer tick, asking Buildbucket for the build status
[13:32:17.734] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:17.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:33:0) after 1m0s
[13:33:17.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:33:0)
[13:33:17.756] Timer tick, asking Buildbucket for the build status
[13:33:17.830] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:17.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:34:0) after 1m0s
[13:34:17.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:34:0)
[13:34:17.853] Timer tick, asking Buildbucket for the build status
[13:34:17.921] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:17.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:35:0) after 1m0s
[13:35:17.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:35:0)
[13:35:17.952] Timer tick, asking Buildbucket for the build status
[13:35:18.243] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:18.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:36:0) after 1m0s
[13:36:18.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:36:0)
[13:36:18.262] Timer tick, asking Buildbucket for the build status
[13:36:18.327] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:18.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:37:0) after 1m0s
[13:37:18.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:37:0)
[13:37:18.383] Timer tick, asking Buildbucket for the build status
[13:37:18.514] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:18.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:38:0) after 1m0s
[13:38:18.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:38:0)
[13:38:18.530] Timer tick, asking Buildbucket for the build status
[13:38:18.636] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:18.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:39:0) after 1m0s
[13:39:18.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:39:0)
[13:39:18.669] Timer tick, asking Buildbucket for the build status
[13:39:18.742] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:18.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:40:0) after 1m0s
[13:40:18.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:40:0)
[13:40:18.766] Timer tick, asking Buildbucket for the build status
[13:40:18.822] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:18.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:41:0) after 1m0s
[13:41:18.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:41:0)
[13:41:18.845] Timer tick, asking Buildbucket for the build status
[13:41:19.168] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:19.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:42:0) after 1m0s
[13:42:19.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:42:0)
[13:42:19.232] Timer tick, asking Buildbucket for the build status
[13:42:19.417] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:19.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:43:0) after 1m0s
[13:43:19.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:43:0)
[13:43:19.559] Timer tick, asking Buildbucket for the build status
[13:43:19.832] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:19.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:44:0) after 1m0s
[13:44:19.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:44:0)
[13:44:19.969] Timer tick, asking Buildbucket for the build status
[13:44:20.048] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:20.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:45:0) after 1m0s
[13:45:20.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:45:0)
[13:45:20.134] Timer tick, asking Buildbucket for the build status
[13:45:20.209] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:20.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:46:0) after 1m0s
[13:46:20.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:46:0)
[13:46:20.234] Timer tick, asking Buildbucket for the build status
[13:46:20.304] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:20.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:47:0) after 1m0s
[13:47:20.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:47:0)
[13:47:20.348] Timer tick, asking Buildbucket for the build status
[13:47:20.551] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:20.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:48:0) after 1m0s
[13:48:20.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:48:0)
[13:48:20.684] Timer tick, asking Buildbucket for the build status
[13:48:20.741] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:20.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:49:0) after 1m0s
[13:49:20.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:49:0)
[13:49:20.952] Timer tick, asking Buildbucket for the build status
[13:49:21.025] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:21.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:50:0) after 1m0s
[13:50:21.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:50:0)
[13:50:21.184] Timer tick, asking Buildbucket for the build status
[13:50:21.247] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:21.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:51:0) after 1m0s
[13:51:21.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:51:0)
[13:51:21.610] Timer tick, asking Buildbucket for the build status
[13:51:21.671] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:21.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:52:0) after 1m0s
[13:52:21.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:52:0)
[13:52:21.943] Timer tick, asking Buildbucket for the build status
[13:52:22.068] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:22.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:53:0) after 1m0s
[13:53:22.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:53:0)
[13:53:22.369] Timer tick, asking Buildbucket for the build status
[13:53:22.474] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:22.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:54:0) after 1m0s
[13:54:22.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:54:0)
[13:54:22.709] Timer tick, asking Buildbucket for the build status
[13:54:22.768] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:22.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:55:0) after 1m0s
[13:55:22.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:55:0)
[13:55:22.903] Timer tick, asking Buildbucket for the build status
[13:55:23.172] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:23.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:56:0) after 1m0s
[13:56:23.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:56:0)
[13:56:23.688] Timer tick, asking Buildbucket for the build status
[13:56:23.912] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:23.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:57:0) after 1m0s
[13:57:23.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:57:0)
[13:57:23.996] Timer tick, asking Buildbucket for the build status
[13:57:24.162] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:24.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:58:0) after 1m0s
[13:58:24.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:58:0)
[13:58:24.225] Timer tick, asking Buildbucket for the build status
[13:58:24.294] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:24.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:59:0) after 1m0s
[13:59:24.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:59:0)
[13:59:24.362] Timer tick, asking Buildbucket for the build status
[13:59:24.595] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:24.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:60:0) after 1m0s
[14:00:24.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:60:0)
[14:00:24.686] Timer tick, asking Buildbucket for the build status
[14:00:24.771] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:24.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:61:0) after 1m0s
[14:01:24.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:61:0)
[14:01:24.900] Timer tick, asking Buildbucket for the build status
[14:01:24.960] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:24.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:62:0) after 1m0s
[14:02:25.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:62:0)
[14:02:25.033] Timer tick, asking Buildbucket for the build status
[14:02:25.238] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:25.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:63:0) after 1m0s
[14:03:25.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:63:0)
[14:03:25.376] Timer tick, asking Buildbucket for the build status
[14:03:27.835] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:27.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:64:0) after 1m0s
[14:04:27.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:64:0)
[14:04:27.973] Timer tick, asking Buildbucket for the build status
[14:04:28.424] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:28.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:65:0) after 1m0s
[14:05:28.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:65:0)
[14:05:28.484] Timer tick, asking Buildbucket for the build status
[14:05:28.552] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:28.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:66:0) after 1m0s
[14:06:28.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:66:0)
[14:06:28.645] Timer tick, asking Buildbucket for the build status
[14:06:28.755] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:28.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:67:0) after 1m0s
[14:07:28.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:67:0)
[14:07:28.806] Timer tick, asking Buildbucket for the build status
[14:07:28.922] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:28.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:68:0) after 1m0s
[14:08:28.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:68:0)
[14:08:28.992] Timer tick, asking Buildbucket for the build status
[14:08:29.402] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:29.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:69:0) after 1m0s
[14:09:29.556] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:69:0)
[14:09:29.556] Timer tick, asking Buildbucket for the build status
[14:09:29.658] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:29.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:70:0) after 1m0s
[14:10:29.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:70:0)
[14:10:29.702] Timer tick, asking Buildbucket for the build status
[14:10:29.861] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:29.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:71:0) after 1m0s
[14:11:29.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:71:0)
[14:11:29.932] Timer tick, asking Buildbucket for the build status
[14:11:30.027] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:30.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:72:0) after 1m0s
[14:12:30.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:72:0)
[14:12:30.081] Timer tick, asking Buildbucket for the build status
[14:12:30.207] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:30.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:73:0) after 1m0s
[14:13:30.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:73:0)
[14:13:30.255] Timer tick, asking Buildbucket for the build status
[14:13:30.341] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:30.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:74:0) after 1m0s
[14:14:30.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:74:0)
[14:14:30.371] Timer tick, asking Buildbucket for the build status
[14:14:30.464] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:30.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:75:0) after 1m0s
[14:15:30.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:75:0)
[14:15:30.529] Timer tick, asking Buildbucket for the build status
[14:15:30.602] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:30.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:76:0) after 1m0s
[14:16:30.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:76:0)
[14:16:30.647] Timer tick, asking Buildbucket for the build status
[14:16:30.735] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:30.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:77:0) after 1m0s
[14:17:30.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:77:0)
[14:17:30.769] Timer tick, asking Buildbucket for the build status
[14:17:30.907] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:30.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:78:0) after 1m0s
[14:18:30.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:78:0)
[14:18:30.919] Timer tick, asking Buildbucket for the build status
[14:18:31.158] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:31.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:79:0) after 1m0s
[14:19:31.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:79:0)
[14:19:31.178] Timer tick, asking Buildbucket for the build status
[14:19:31.256] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:31.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:80:0) after 1m0s
[14:20:31.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:80:0)
[14:20:31.304] Timer tick, asking Buildbucket for the build status
[14:20:31.379] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:31.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:81:0) after 1m0s
[14:21:31.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:81:0)
[14:21:31.386] Timer tick, asking Buildbucket for the build status
[14:21:31.449] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:31.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:82:0) after 1m0s
[14:22:31.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:82:0)
[14:22:31.506] Timer tick, asking Buildbucket for the build status
[14:22:31.919] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:31.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:83:0) after 1m0s
[14:23:32.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:83:0)
[14:23:32.142] Timer tick, asking Buildbucket for the build status
[14:23:32.355] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:32.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:84:0) after 1m0s
[14:24:32.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:84:0)
[14:24:32.386] Timer tick, asking Buildbucket for the build status
[14:24:32.460] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:32.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:85:0) after 1m0s
[14:25:32.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:85:0)
[14:25:32.520] Timer tick, asking Buildbucket for the build status
[14:25:32.601] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:32.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:86:0) after 1m0s
[14:26:32.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:86:0)
[14:26:32.634] Timer tick, asking Buildbucket for the build status
[14:26:32.712] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:32.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:87:0) after 1m0s
[14:27:32.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:87:0)
[14:27:32.751] Timer tick, asking Buildbucket for the build status
[14:27:32.866] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:32.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:88:0) after 1m0s
[14:28:32.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:88:0)
[14:28:32.993] Timer tick, asking Buildbucket for the build status
[14:28:33.105] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:33.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:89:0) after 1m0s
[14:29:33.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:89:0)
[14:29:33.138] Timer tick, asking Buildbucket for the build status
[14:29:33.223] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:33.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:90:0) after 1m0s
[14:30:33.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:90:0)
[14:30:33.322] Timer tick, asking Buildbucket for the build status
[14:30:33.422] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:33.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:91:0) after 1m0s
[14:31:33.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:91:0)
[14:31:33.514] Timer tick, asking Buildbucket for the build status
[14:31:33.585] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:33.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:92:0) after 1m0s
[14:32:33.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:92:0)
[14:32:33.610] Timer tick, asking Buildbucket for the build status
[14:32:33.931] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:33.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:93:0) after 1m0s
[14:33:34.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:93:0)
[14:33:34.029] Timer tick, asking Buildbucket for the build status
[14:33:34.601] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:34.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:94:0) after 1m0s
[14:34:35.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:94:0)
[14:34:35.093] Timer tick, asking Buildbucket for the build status
[14:34:35.156] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:35.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:95:0) after 1m0s
[14:35:35.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:95:0)
[14:35:35.268] Timer tick, asking Buildbucket for the build status
[14:35:35.389] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:35.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:96:0) after 1m0s
[14:36:35.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:96:0)
[14:36:35.410] Timer tick, asking Buildbucket for the build status
[14:36:35.900] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:35.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:97:0) after 1m0s
[14:37:36.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:97:0)
[14:37:36.109] Timer tick, asking Buildbucket for the build status
[14:37:36.248] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:36.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:98:0) after 1m0s
[14:38:36.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:98:0)
[14:38:36.341] Timer tick, asking Buildbucket for the build status
[14:38:36.536] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:36.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:99:0) after 1m0s
[14:39:36.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:99:0)
[14:39:36.671] Timer tick, asking Buildbucket for the build status
[14:39:36.761] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:36.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:100:0) after 1m0s
[14:40:36.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:100:0)
[14:40:36.912] Timer tick, asking Buildbucket for the build status
[14:40:36.979] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:36.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:101:0) after 1m0s
[14:41:37.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:101:0)
[14:41:37.097] Timer tick, asking Buildbucket for the build status
[14:41:37.176] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:37.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:102:0) after 1m0s
[14:42:37.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:102:0)
[14:42:37.231] Timer tick, asking Buildbucket for the build status
[14:42:37.364] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:37.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:103:0) after 1m0s
[14:43:37.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:103:0)
[14:43:37.451] Timer tick, asking Buildbucket for the build status
[14:43:37.514] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:37.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:104:0) after 1m0s
[14:44:37.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:104:0)
[14:44:37.590] Timer tick, asking Buildbucket for the build status
[14:44:37.650] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:37.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:105:0) after 1m0s
[14:45:37.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:105:0)
[14:45:37.675] Timer tick, asking Buildbucket for the build status
[14:45:37.769] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:37.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:106:0) after 1m0s
[14:46:37.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:106:0)
[14:46:37.843] Timer tick, asking Buildbucket for the build status
[14:46:37.918] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:37.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:107:0) after 1m0s
[14:47:38.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:107:0)
[14:47:38.013] Timer tick, asking Buildbucket for the build status
[14:47:38.248] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:38.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:108:0) after 1m0s
[14:48:38.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:108:0)
[14:48:38.381] Timer tick, asking Buildbucket for the build status
[14:48:39.100] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:39.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:109:0) after 1m0s
[14:49:39.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:109:0)
[14:49:39.104] Timer tick, asking Buildbucket for the build status
[14:49:39.578] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:39.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:110:0) after 1m0s
[14:50:39.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:110:0)
[14:50:39.715] Timer tick, asking Buildbucket for the build status
[14:50:39.776] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:39.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:111:0) after 1m0s
[14:51:39.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:111:0)
[14:51:39.756] Timer tick, asking Buildbucket for the build status
[14:51:39.849] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:39.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:112:0) after 1m0s
[14:52:39.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:112:0)
[14:52:39.946] Timer tick, asking Buildbucket for the build status
[14:52:40.222] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:40.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:113:0) after 1m0s
[14:53:40.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:113:0)
[14:53:40.316] Timer tick, asking Buildbucket for the build status
[14:53:40.428] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:40.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:114:0) after 1m0s
[14:54:40.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:114:0)
[14:54:40.453] Timer tick, asking Buildbucket for the build status
[14:54:40.746] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:40.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:115:0) after 1m0s
[14:55:40.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:115:0)
[14:55:40.742] Timer tick, asking Buildbucket for the build status
[14:55:41.138] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:41.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:116:0) after 1m0s
[14:56:41.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:116:0)
[14:56:41.178] Timer tick, asking Buildbucket for the build status
[14:56:41.250] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:41.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:117:0) after 1m0s
[14:57:41.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:117:0)
[14:57:41.349] Timer tick, asking Buildbucket for the build status
[14:57:41.502] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:41.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:118:0) after 1m0s
[14:58:41.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:118:0)
[14:58:41.532] Timer tick, asking Buildbucket for the build status
[14:58:41.670] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:41.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:119:0) after 1m0s
[14:59:41.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:119:0)
[14:59:41.698] Timer tick, asking Buildbucket for the build status
[14:59:41.818] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:41.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:120:0) after 1m0s
[15:00:41.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:120:0)
[15:00:41.846] Timer tick, asking Buildbucket for the build status
[15:00:41.930] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:41.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:121:0) after 1m0s
[15:01:42.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:121:0)
[15:01:42.007] Timer tick, asking Buildbucket for the build status
[15:01:42.144] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:42.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:122:0) after 1m0s
[15:02:42.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:122:0)
[15:02:42.180] Timer tick, asking Buildbucket for the build status
[15:02:42.644] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:42.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:123:0) after 1m0s
[15:03:42.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:123:0)
[15:03:42.762] Timer tick, asking Buildbucket for the build status
[15:03:42.915] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:42.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:124:0) after 1m0s
[15:04:42.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:124:0)
[15:04:42.982] Timer tick, asking Buildbucket for the build status
[15:04:43.065] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:43.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:125:0) after 1m0s
[15:05:43.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:125:0)
[15:05:43.124] Timer tick, asking Buildbucket for the build status
[15:05:43.285] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:43.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:126:0) after 1m0s
[15:06:43.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:126:0)
[15:06:43.401] Timer tick, asking Buildbucket for the build status
[15:06:43.513] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:43.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:127:0) after 1m0s
[15:07:43.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:127:0)
[15:07:43.544] Timer tick, asking Buildbucket for the build status
[15:07:44.162] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:44.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:128:0) after 1m0s
[15:08:44.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:128:0)
[15:08:44.264] Timer tick, asking Buildbucket for the build status
[15:08:44.339] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:44.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:129:0) after 1m0s
[15:09:44.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:129:0)
[15:09:44.514] Timer tick, asking Buildbucket for the build status
[15:09:44.601] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:44.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:130:0) after 1m0s
[15:10:44.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:130:0)
[15:10:44.931] Timer tick, asking Buildbucket for the build status
[15:10:45.005] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:45.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:131:0) after 1m0s
[15:11:45.140] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:131:0)
[15:11:45.141] Timer tick, asking Buildbucket for the build status
[15:11:45.283] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:45.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:132:0) after 1m0s
[15:12:45.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:132:0)
[15:12:45.388] Timer tick, asking Buildbucket for the build status
[15:12:45.575] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:45.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:133:0) after 1m0s
[15:13:45.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:133:0)
[15:13:45.665] Timer tick, asking Buildbucket for the build status
[15:13:45.755] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:45.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:134:0) after 1m0s
[15:14:45.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:134:0)
[15:14:45.923] Timer tick, asking Buildbucket for the build status
[15:14:45.997] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:45.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:135:0) after 1m0s
[15:15:46.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:135:0)
[15:15:46.105] Timer tick, asking Buildbucket for the build status
[15:15:46.181] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:46.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:136:0) after 1m0s
[15:16:46.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:136:0)
[15:16:46.524] Timer tick, asking Buildbucket for the build status
[15:16:46.587] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:46.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:137:0) after 1m0s
[15:17:46.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:137:0)
[15:17:46.718] Timer tick, asking Buildbucket for the build status
[15:17:46.812] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:46.812] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:138:0) after 1m0s
[15:18:46.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:138:0)
[15:18:46.889] Timer tick, asking Buildbucket for the build status
[15:18:47.099] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:47.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:139:0) after 1m0s
[15:19:47.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:139:0)
[15:19:47.178] Timer tick, asking Buildbucket for the build status
[15:19:47.329] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:47.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:140:0) after 1m0s
[15:20:47.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:140:0)
[15:20:47.407] Timer tick, asking Buildbucket for the build status
[15:20:47.522] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:47.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:141:0) after 1m0s
[15:21:47.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:141:0)
[15:21:47.571] Timer tick, asking Buildbucket for the build status
[15:21:47.681] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:47.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:142:0) after 1m0s
[15:22:47.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:142:0)
[15:22:47.711] Timer tick, asking Buildbucket for the build status
[15:22:47.830] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:47.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:143:0) after 1m0s
[15:23:47.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:143:0)
[15:23:47.940] Timer tick, asking Buildbucket for the build status
[15:23:48.034] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:48.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:144:0) after 1m0s
[15:24:48.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:144:0)
[15:24:48.103] Timer tick, asking Buildbucket for the build status
[15:24:48.176] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:48.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:145:0) after 1m0s
[15:25:48.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:145:0)
[15:25:48.343] Timer tick, asking Buildbucket for the build status
[15:25:48.442] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:48.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:146:0) after 1m0s
[15:26:48.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:146:0)
[15:26:48.616] Timer tick, asking Buildbucket for the build status
[15:26:48.720] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:48.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:147:0) after 1m0s
[15:27:48.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:147:0)
[15:27:48.900] Timer tick, asking Buildbucket for the build status
[15:27:49.046] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:49.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:148:0) after 1m0s
[15:28:49.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:148:0)
[15:28:49.267] Timer tick, asking Buildbucket for the build status
[15:28:49.523] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:49.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:149:0) after 1m0s
[15:29:49.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:149:0)
[15:29:49.601] Timer tick, asking Buildbucket for the build status
[15:29:49.703] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:49.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:150:0) after 1m0s
[15:30:49.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:150:0)
[15:30:49.842] Timer tick, asking Buildbucket for the build status
[15:30:50.024] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:50.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:151:0) after 1m0s
[15:31:50.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:151:0)
[15:31:50.043] Timer tick, asking Buildbucket for the build status
[15:31:50.110] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:50.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:152:0) after 1m0s
[15:32:50.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:152:0)
[15:32:50.153] Timer tick, asking Buildbucket for the build status
[15:32:50.634] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:50.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:153:0) after 1m0s
[15:33:50.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:153:0)
[15:33:50.719] Timer tick, asking Buildbucket for the build status
[15:33:50.777] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:50.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:154:0) after 1m0s
[15:34:50.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:154:0)
[15:34:50.794] Timer tick, asking Buildbucket for the build status
[15:34:50.879] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:50.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:155:0) after 1m0s
[15:35:50.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:155:0)
[15:35:50.959] Timer tick, asking Buildbucket for the build status
[15:35:51.097] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:51.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:156:0) after 1m0s
[15:36:51.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:156:0)
[15:36:51.168] Timer tick, asking Buildbucket for the build status
[15:36:51.266] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:51.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:157:0) after 1m0s
[15:37:51.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:157:0)
[15:37:51.318] Timer tick, asking Buildbucket for the build status
[15:37:51.506] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:51.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:158:0) after 1m0s
[15:38:51.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:158:0)
[15:38:51.558] Timer tick, asking Buildbucket for the build status
[15:38:51.890] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:51.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:159:0) after 1m0s
[15:39:52.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:159:0)
[15:39:52.597] Timer tick, asking Buildbucket for the build status
[15:39:52.662] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:52.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:160:0) after 1m0s
[15:40:53.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:160:0)
[15:40:53.423] Timer tick, asking Buildbucket for the build status
[15:40:53.516] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:53.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:161:0) after 1m0s
[15:41:53.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:161:0)
[15:41:53.970] Timer tick, asking Buildbucket for the build status
[15:41:54.148] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:54.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:162:0) after 1m0s
[15:42:54.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:162:0)
[15:42:54.599] Timer tick, asking Buildbucket for the build status
[15:42:54.762] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:54.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:163:0) after 1m0s
[15:43:54.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:163:0)
[15:43:54.763] Timer tick, asking Buildbucket for the build status
[15:43:54.846] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:54.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:164:0) after 1m0s
[15:44:55.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:164:0)
[15:44:55.018] Timer tick, asking Buildbucket for the build status
[15:44:55.108] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:55.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:165:0) after 1m0s
[15:45:55.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:165:0)
[15:45:55.297] Timer tick, asking Buildbucket for the build status
[15:45:55.382] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:55.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:166:0) after 1m0s
[15:46:55.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:166:0)
[15:46:55.998] Timer tick, asking Buildbucket for the build status
[15:46:56.187] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:56.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:167:0) after 1m0s
[15:47:56.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:167:0)
[15:47:56.198] Timer tick, asking Buildbucket for the build status
[15:47:56.578] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:56.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:168:0) after 1m0s
[15:48:56.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:168:0)
[15:48:56.603] Timer tick, asking Buildbucket for the build status
[15:48:56.764] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:56.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:169:0) after 1m0s
[15:49:56.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:169:0)
[15:49:56.816] Timer tick, asking Buildbucket for the build status
[15:49:56.894] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:56.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:170:0) after 1m0s
[15:50:56.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:170:0)
[15:50:56.981] Timer tick, asking Buildbucket for the build status
[15:50:57.058] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:57.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:171:0) after 1m0s
[15:51:57.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:171:0)
[15:51:57.081] Timer tick, asking Buildbucket for the build status
[15:51:57.250] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:57.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:172:0) after 1m0s
[15:52:57.374] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:172:0)
[15:52:57.374] Timer tick, asking Buildbucket for the build status
[15:52:57.688] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:57.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:173:0) after 1m0s
[15:53:57.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:173:0)
[15:53:57.742] Timer tick, asking Buildbucket for the build status
[15:53:57.952] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:57.952] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:174:0) after 1m0s
[15:54:58.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:174:0)
[15:54:58.053] Timer tick, asking Buildbucket for the build status
[15:54:58.166] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:58.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:175:0) after 1m0s
[15:55:58.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:175:0)
[15:55:58.397] Timer tick, asking Buildbucket for the build status
[15:55:58.512] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:58.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:176:0) after 1m0s
[15:56:58.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:176:0)
[15:56:58.959] Timer tick, asking Buildbucket for the build status
[15:56:59.059] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:59.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:177:0) after 1m0s
[15:57:59.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:177:0)
[15:57:59.312] Timer tick, asking Buildbucket for the build status
[15:57:59.470] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:59.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:178:0) after 1m0s
[15:58:59.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:178:0)
[15:58:59.603] Timer tick, asking Buildbucket for the build status
[15:58:59.702] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:59.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:179:0) after 1m0s
[15:59:59.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:179:0)
[15:59:59.843] Timer tick, asking Buildbucket for the build status
[15:59:59.947] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:59.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:180:0) after 1m0s
[16:01:00.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:180:0)
[16:01:00.114] Timer tick, asking Buildbucket for the build status
[16:01:00.244] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:00.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:181:0) after 1m0s
[16:02:00.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:181:0)
[16:02:00.434] Timer tick, asking Buildbucket for the build status
[16:02:00.671] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:00.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:182:0) after 1m0s
[16:03:01.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:182:0)
[16:03:01.217] Timer tick, asking Buildbucket for the build status
[16:03:01.499] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:01.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:183:0) after 1m0s
[16:04:02.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:183:0)
[16:04:02.438] Timer tick, asking Buildbucket for the build status
[16:04:02.518] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:02.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:184:0) after 1m0s
[16:05:02.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:184:0)
[16:05:02.638] Timer tick, asking Buildbucket for the build status
[16:05:02.704] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:02.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:185:0) after 1m0s
[16:06:02.854] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:185:0)
[16:06:02.854] Timer tick, asking Buildbucket for the build status
[16:06:02.928] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:02.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:186:0) after 1m0s
[16:07:03.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:186:0)
[16:07:03.084] Timer tick, asking Buildbucket for the build status
[16:07:03.692] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:03.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:187:0) after 1m0s
[16:08:03.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:187:0)
[16:08:03.850] Timer tick, asking Buildbucket for the build status
[16:08:03.987] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:03.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:188:0) after 1m0s
[16:09:04.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:188:0)
[16:09:04.128] Timer tick, asking Buildbucket for the build status
[16:09:04.377] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:04.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:189:0) after 1m0s
[16:10:04.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:189:0)
[16:10:04.423] Timer tick, asking Buildbucket for the build status
[16:10:04.512] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:04.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:190:0) after 1m0s
[16:11:04.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:190:0)
[16:11:04.621] Timer tick, asking Buildbucket for the build status
[16:11:04.700] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:04.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:191:0) after 1m0s
[16:12:04.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:191:0)
[16:12:04.787] Timer tick, asking Buildbucket for the build status
[16:12:04.865] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:04.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:192:0) after 1m0s
[16:13:04.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:192:0)
[16:13:04.939] Timer tick, asking Buildbucket for the build status
[16:13:05.077] Build 8870810031101270624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:05.077] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9036240093019635632:193:0) after 1m0s
[16:13:07.536] Received PubSub notification, asking Buildbucket for the build status
[16:13:07.643] Build 8870810031101270624: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:13:07.643] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1598544785657580",
    "created_by": "project:infra",
    "created_ts": "1598533329827391",
    "id": "8870810031101270624",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"87.0.4245.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"985ca716-17a1-4aa6-bae0-0c4502bdff1b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"87.0.4245.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-i3k5\"], \"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": "1598533351045784",
    "status": "COMPLETED",
    "status_changed_ts": "1598544786128213",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9036240093019635632",
      "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/8870810031101270624/+/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:4e48d46f35f8ab10",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1598544786276232",
    "url": "https://ci.chromium.org/b/8870810031101270624",
    "utcnow_ts": "1598544787632840"
  }
}
[16:13:07.643] Invocation finished in 3h10m59.028568404s with status SUCCEEDED