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

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

{
  "version": "91.0.4442.2"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[22:53:24.151] New invocation is queued and will start shortly
[22:53:24.151] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:53:25.239] Starting the invocation (attempt 1)
[22:53:25.277] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:53:25.277] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:53:25.277] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9018536485792125120",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"91.0.4442.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"ec705d0d-fff1-4a05-81d6-fa2a25522a22\",\"title\":\"publish_tarball/0\"}]},\"version\":\"91.0.4442.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:9018536485792125120",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[22:53:25.673] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1615416805385533",
    "id": "8853106423835207904",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4442.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ec705d0d-fff1-4a05-81d6-fa2a25522a22\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4442.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1615416805548444",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9018536485792125120",
      "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/8853106423835207904/+/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": "1615416805548547",
    "url": "https://ci.chromium.org/b/8853106423835207904",
    "utcnow_ts": "1615416805667072"
  }
}
[22:53:25.674] Task URL: https://ci.chromium.org/b/8853106423835207904
[22:53:25.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:2:0) after 1m0s
[22:54:25.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:2:0)
[22:54:25.692] Timer tick, asking Buildbucket for the build status
[22:54:25.806] Build 8853106423835207904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:25.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:3:0) after 1m0s
[22:55:25.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:3:0)
[22:55:25.860] Timer tick, asking Buildbucket for the build status
[22:55:25.917] Build 8853106423835207904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:25.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:4:0) after 1m0s
[22:55:40.170] Received PubSub notification, asking Buildbucket for the build status
[22:55:40.254] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:25.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:4:0)
[22:56:25.937] Timer tick, asking Buildbucket for the build status
[22:56:26.068] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:26.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:6:0) after 1m0s
[22:57:26.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:6:0)
[22:57:26.106] Timer tick, asking Buildbucket for the build status
[22:57:26.176] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:26.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:7:0) after 1m0s
[22:58:26.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:7:0)
[22:58:26.271] Timer tick, asking Buildbucket for the build status
[22:58:26.332] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:26.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:8:0) after 1m0s
[22:59:26.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:8:0)
[22:59:26.377] Timer tick, asking Buildbucket for the build status
[22:59:26.463] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:26.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:9:0) after 1m0s
[23:00:26.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:9:0)
[23:00:26.473] Timer tick, asking Buildbucket for the build status
[23:00:26.570] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:26.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:10:0) after 1m0s
[23:01:26.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:10:0)
[23:01:26.651] Timer tick, asking Buildbucket for the build status
[23:01:26.780] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:26.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:11:0) after 1m0s
[23:02:26.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:11:0)
[23:02:26.871] Timer tick, asking Buildbucket for the build status
[23:02:26.985] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:26.985] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:12:0) after 1m0s
[23:03:27.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:12:0)
[23:03:27.193] Timer tick, asking Buildbucket for the build status
[23:03:27.310] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:27.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:13:0) after 1m0s
[23:04:27.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:13:0)
[23:04:27.422] Timer tick, asking Buildbucket for the build status
[23:04:27.672] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:27.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:14:0) after 1m0s
[23:05:27.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:14:0)
[23:05:27.769] Timer tick, asking Buildbucket for the build status
[23:05:27.853] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:27.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:15:0) after 1m0s
[23:06:27.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:15:0)
[23:06:27.917] Timer tick, asking Buildbucket for the build status
[23:06:27.980] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:27.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:16:0) after 1m0s
[23:07:28.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:16:0)
[23:07:28.035] Timer tick, asking Buildbucket for the build status
[23:07:28.158] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:28.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:17:0) after 1m0s
[23:08:28.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:17:0)
[23:08:28.267] Timer tick, asking Buildbucket for the build status
[23:08:28.339] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:28.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:18:0) after 1m0s
[23:09:28.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:18:0)
[23:09:28.424] Timer tick, asking Buildbucket for the build status
[23:09:28.551] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:28.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:19:0) after 1m0s
[23:10:28.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:19:0)
[23:10:28.645] Timer tick, asking Buildbucket for the build status
[23:10:28.728] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:28.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:20:0) after 1m0s
[23:11:28.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:20:0)
[23:11:28.811] Timer tick, asking Buildbucket for the build status
[23:11:29.006] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:29.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:21:0) after 1m0s
[23:12:29.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:21:0)
[23:12:29.029] Timer tick, asking Buildbucket for the build status
[23:12:29.107] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:29.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:22:0) after 1m0s
[23:13:29.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:22:0)
[23:13:29.134] Timer tick, asking Buildbucket for the build status
[23:13:29.198] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:29.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:23:0) after 1m0s
[23:14:29.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:23:0)
[23:14:29.204] Timer tick, asking Buildbucket for the build status
[23:14:29.314] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:29.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:24:0) after 1m0s
[23:15:29.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:24:0)
[23:15:29.335] Timer tick, asking Buildbucket for the build status
[23:15:29.531] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:29.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:25:0) after 1m0s
[23:16:29.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:25:0)
[23:16:29.593] Timer tick, asking Buildbucket for the build status
[23:16:29.691] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:29.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:26:0) after 1m0s
[23:17:29.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:26:0)
[23:17:29.708] Timer tick, asking Buildbucket for the build status
[23:17:29.964] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:29.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:27:0) after 1m0s
[23:18:30.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:27:0)
[23:18:30.084] Timer tick, asking Buildbucket for the build status
[23:18:30.265] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:30.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:28:0) after 1m0s
[23:19:30.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:28:0)
[23:19:30.335] Timer tick, asking Buildbucket for the build status
[23:19:30.707] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:30.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:29:0) after 1m0s
[23:20:30.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:29:0)
[23:20:30.750] Timer tick, asking Buildbucket for the build status
[23:20:30.860] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:30.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:30:0) after 1m0s
[23:21:30.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:30:0)
[23:21:30.918] Timer tick, asking Buildbucket for the build status
[23:21:31.006] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:31.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:31:0) after 1m0s
[23:22:31.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:31:0)
[23:22:31.021] Timer tick, asking Buildbucket for the build status
[23:22:31.094] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:31.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:32:0) after 1m0s
[23:23:31.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:32:0)
[23:23:31.145] Timer tick, asking Buildbucket for the build status
[23:23:31.236] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:31.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:33:0) after 1m0s
[23:24:31.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:33:0)
[23:24:31.268] Timer tick, asking Buildbucket for the build status
[23:24:31.372] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:31.372] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:34:0) after 1m0s
[23:25:31.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:34:0)
[23:25:31.491] Timer tick, asking Buildbucket for the build status
[23:25:31.579] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:31.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:35:0) after 1m0s
[23:26:31.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:35:0)
[23:26:31.654] Timer tick, asking Buildbucket for the build status
[23:26:31.720] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:31.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:36:0) after 1m0s
[23:27:31.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:36:0)
[23:27:31.797] Timer tick, asking Buildbucket for the build status
[23:27:31.920] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:31.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:37:0) after 1m0s
[23:28:32.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:37:0)
[23:28:32.014] Timer tick, asking Buildbucket for the build status
[23:28:32.095] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:32.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:38:0) after 1m0s
[23:29:32.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:38:0)
[23:29:32.115] Timer tick, asking Buildbucket for the build status
[23:29:32.182] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:32.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:39:0) after 1m0s
[23:30:32.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:39:0)
[23:30:32.527] Timer tick, asking Buildbucket for the build status
[23:30:32.845] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:32.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:40:0) after 1m0s
[23:31:32.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:40:0)
[23:31:32.941] Timer tick, asking Buildbucket for the build status
[23:31:33.044] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:33.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:41:0) after 1m0s
[23:32:33.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:41:0)
[23:32:33.150] Timer tick, asking Buildbucket for the build status
[23:32:33.254] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:33.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:42:0) after 1m0s
[23:33:33.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:42:0)
[23:33:33.419] Timer tick, asking Buildbucket for the build status
[23:33:33.641] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:33.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:43:0) after 1m0s
[23:34:33.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:43:0)
[23:34:33.868] Timer tick, asking Buildbucket for the build status
[23:34:33.930] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:33.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:44:0) after 1m0s
[23:35:34.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:44:0)
[23:35:34.098] Timer tick, asking Buildbucket for the build status
[23:35:34.163] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:34.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:45:0) after 1m0s
[23:36:34.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:45:0)
[23:36:34.337] Timer tick, asking Buildbucket for the build status
[23:36:34.618] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:34.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:46:0) after 1m0s
[23:37:34.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:46:0)
[23:37:34.730] Timer tick, asking Buildbucket for the build status
[23:37:34.789] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:34.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:47:0) after 1m0s
[23:38:34.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:47:0)
[23:38:34.820] Timer tick, asking Buildbucket for the build status
[23:38:35.034] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:35.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:48:0) after 1m0s
[23:39:35.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:48:0)
[23:39:35.044] Timer tick, asking Buildbucket for the build status
[23:39:35.234] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:35.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:49:0) after 1m0s
[23:40:35.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:49:0)
[23:40:35.388] Timer tick, asking Buildbucket for the build status
[23:40:35.453] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:35.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:50:0) after 1m0s
[23:41:35.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:50:0)
[23:41:35.521] Timer tick, asking Buildbucket for the build status
[23:41:35.631] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:35.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:51:0) after 1m0s
[23:42:35.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:51:0)
[23:42:35.763] Timer tick, asking Buildbucket for the build status
[23:42:35.825] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:35.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:52:0) after 1m0s
[23:43:35.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:52:0)
[23:43:35.858] Timer tick, asking Buildbucket for the build status
[23:43:36.416] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:36.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:53:0) after 1m0s
[23:44:36.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:53:0)
[23:44:36.449] Timer tick, asking Buildbucket for the build status
[23:44:36.528] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:36.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:54:0) after 1m0s
[23:45:36.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:54:0)
[23:45:36.546] Timer tick, asking Buildbucket for the build status
[23:45:36.799] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:36.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:55:0) after 1m0s
[23:46:36.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:55:0)
[23:46:36.768] Timer tick, asking Buildbucket for the build status
[23:46:36.828] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:36.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:56:0) after 1m0s
[23:47:36.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:56:0)
[23:47:36.847] Timer tick, asking Buildbucket for the build status
[23:47:36.916] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:36.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:57:0) after 1m0s
[23:48:36.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:57:0)
[23:48:36.977] Timer tick, asking Buildbucket for the build status
[23:48:37.054] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:37.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:58:0) after 1m0s
[23:49:37.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:58:0)
[23:49:37.386] Timer tick, asking Buildbucket for the build status
[23:49:37.490] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:37.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:59:0) after 1m0s
[23:50:37.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:59:0)
[23:50:37.543] Timer tick, asking Buildbucket for the build status
[23:50:37.633] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:37.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:60:0) after 1m0s
[23:51:37.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:60:0)
[23:51:37.820] Timer tick, asking Buildbucket for the build status
[23:51:37.880] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:37.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:61:0) after 1m0s
[23:52:37.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:61:0)
[23:52:37.937] Timer tick, asking Buildbucket for the build status
[23:52:38.031] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:38.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:62:0) after 1m0s
[23:53:38.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:62:0)
[23:53:38.144] Timer tick, asking Buildbucket for the build status
[23:53:38.223] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:38.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:63:0) after 1m0s
[23:54:38.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:63:0)
[23:54:38.322] Timer tick, asking Buildbucket for the build status
[23:54:38.408] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:38.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:64:0) after 1m0s
[23:55:38.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:64:0)
[23:55:38.480] Timer tick, asking Buildbucket for the build status
[23:55:38.593] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:38.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:65:0) after 1m0s
[23:56:38.655] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:65:0)
[23:56:38.655] Timer tick, asking Buildbucket for the build status
[23:56:38.708] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:38.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:66:0) after 1m0s
[23:57:38.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:66:0)
[23:57:38.807] Timer tick, asking Buildbucket for the build status
[23:57:40.967] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:40.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:67:0) after 1m0s
[23:58:41.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:67:0)
[23:58:41.011] Timer tick, asking Buildbucket for the build status
[23:58:41.112] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:41.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:68:0) after 1m0s
[23:59:41.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:68:0)
[23:59:41.221] Timer tick, asking Buildbucket for the build status
[23:59:42.234] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:42.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:69:0) after 1m0s
[00:00:42.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:69:0)
[00:00:42.248] Timer tick, asking Buildbucket for the build status
[00:00:42.561] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:42.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:70:0) after 1m0s
[00:01:42.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:70:0)
[00:01:42.619] Timer tick, asking Buildbucket for the build status
[00:01:42.693] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:42.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:71:0) after 1m0s
[00:02:42.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:71:0)
[00:02:42.750] Timer tick, asking Buildbucket for the build status
[00:02:42.836] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:42.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:72:0) after 1m0s
[00:03:42.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:72:0)
[00:03:42.994] Timer tick, asking Buildbucket for the build status
[00:03:43.146] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:43.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:73:0) after 1m0s
[00:04:43.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:73:0)
[00:04:43.125] Timer tick, asking Buildbucket for the build status
[00:04:43.209] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:43.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:74:0) after 1m0s
[00:05:43.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:74:0)
[00:05:43.252] Timer tick, asking Buildbucket for the build status
[00:05:43.327] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:43.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:75:0) after 1m0s
[00:06:43.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:75:0)
[00:06:43.389] Timer tick, asking Buildbucket for the build status
[00:06:43.545] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:43.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:76:0) after 1m0s
[00:07:43.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:76:0)
[00:07:43.660] Timer tick, asking Buildbucket for the build status
[00:07:43.729] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:43.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:77:0) after 1m0s
[00:08:43.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:77:0)
[00:08:43.781] Timer tick, asking Buildbucket for the build status
[00:08:43.860] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:43.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:78:0) after 1m0s
[00:09:43.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:78:0)
[00:09:43.913] Timer tick, asking Buildbucket for the build status
[00:09:43.994] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:43.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:79:0) after 1m0s
[00:10:44.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:79:0)
[00:10:44.085] Timer tick, asking Buildbucket for the build status
[00:10:44.393] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:44.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:80:0) after 1m0s
[00:11:44.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:80:0)
[00:11:44.397] Timer tick, asking Buildbucket for the build status
[00:11:44.532] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:44.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:81:0) after 1m0s
[00:12:44.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:81:0)
[00:12:44.648] Timer tick, asking Buildbucket for the build status
[00:12:44.714] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:44.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:82:0) after 1m0s
[00:13:44.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:82:0)
[00:13:44.824] Timer tick, asking Buildbucket for the build status
[00:13:44.942] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:44.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:83:0) after 1m0s
[00:14:45.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:83:0)
[00:14:45.084] Timer tick, asking Buildbucket for the build status
[00:14:45.158] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:45.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:84:0) after 1m0s
[00:15:45.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:84:0)
[00:15:45.315] Timer tick, asking Buildbucket for the build status
[00:15:45.526] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:45.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:85:0) after 1m0s
[00:16:45.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:85:0)
[00:16:45.645] Timer tick, asking Buildbucket for the build status
[00:16:45.756] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:45.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:86:0) after 1m0s
[00:17:45.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:86:0)
[00:17:45.857] Timer tick, asking Buildbucket for the build status
[00:17:45.918] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:45.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:87:0) after 1m0s
[00:18:46.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:87:0)
[00:18:46.003] Timer tick, asking Buildbucket for the build status
[00:18:46.181] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:46.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:88:0) after 1m0s
[00:19:46.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:88:0)
[00:19:46.232] Timer tick, asking Buildbucket for the build status
[00:19:46.350] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:46.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:89:0) after 1m0s
[00:20:46.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:89:0)
[00:20:46.373] Timer tick, asking Buildbucket for the build status
[00:20:46.460] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:46.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:90:0) after 1m0s
[00:21:46.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:90:0)
[00:21:46.472] Timer tick, asking Buildbucket for the build status
[00:21:46.536] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:46.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:91:0) after 1m0s
[00:22:46.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:91:0)
[00:22:46.545] Timer tick, asking Buildbucket for the build status
[00:22:46.617] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:46.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:92:0) after 1m0s
[00:23:46.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:92:0)
[00:23:46.809] Timer tick, asking Buildbucket for the build status
[00:23:47.117] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:47.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:93:0) after 1m0s
[00:24:47.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:93:0)
[00:24:47.163] Timer tick, asking Buildbucket for the build status
[00:24:47.230] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:47.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:94:0) after 1m0s
[00:25:47.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:94:0)
[00:25:47.350] Timer tick, asking Buildbucket for the build status
[00:25:47.553] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:47.553] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:95:0) after 1m0s
[00:26:47.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:95:0)
[00:26:47.617] Timer tick, asking Buildbucket for the build status
[00:26:47.707] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:47.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:96:0) after 1m0s
[00:27:47.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:96:0)
[00:27:47.782] Timer tick, asking Buildbucket for the build status
[00:27:47.860] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:47.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:97:0) after 1m0s
[00:28:47.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:97:0)
[00:28:47.915] Timer tick, asking Buildbucket for the build status
[00:28:48.007] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:48.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:98:0) after 1m0s
[00:29:48.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:98:0)
[00:29:48.027] Timer tick, asking Buildbucket for the build status
[00:29:48.410] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:48.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:99:0) after 1m0s
[00:30:48.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:99:0)
[00:30:48.444] Timer tick, asking Buildbucket for the build status
[00:30:48.556] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:48.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:100:0) after 1m0s
[00:31:48.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:100:0)
[00:31:48.560] Timer tick, asking Buildbucket for the build status
[00:31:48.628] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:48.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:101:0) after 1m0s
[00:32:48.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:101:0)
[00:32:48.682] Timer tick, asking Buildbucket for the build status
[00:32:48.743] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:48.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:102:0) after 1m0s
[00:33:48.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:102:0)
[00:33:48.768] Timer tick, asking Buildbucket for the build status
[00:33:48.845] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:48.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:103:0) after 1m0s
[00:34:48.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:103:0)
[00:34:48.885] Timer tick, asking Buildbucket for the build status
[00:34:48.950] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:48.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:104:0) after 1m0s
[00:35:49.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:104:0)
[00:35:49.056] Timer tick, asking Buildbucket for the build status
[00:35:49.127] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:49.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:105:0) after 1m0s
[00:36:49.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:105:0)
[00:36:49.199] Timer tick, asking Buildbucket for the build status
[00:36:49.302] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:49.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:106:0) after 1m0s
[00:37:49.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:106:0)
[00:37:49.402] Timer tick, asking Buildbucket for the build status
[00:37:49.472] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:49.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:107:0) after 1m0s
[00:38:49.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:107:0)
[00:38:49.496] Timer tick, asking Buildbucket for the build status
[00:38:49.604] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:49.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:108:0) after 1m0s
[00:39:49.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:108:0)
[00:39:49.693] Timer tick, asking Buildbucket for the build status
[00:39:49.769] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:49.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:109:0) after 1m0s
[00:40:49.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:109:0)
[00:40:49.835] Timer tick, asking Buildbucket for the build status
[00:40:49.920] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:49.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:110:0) after 1m0s
[00:41:50.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:110:0)
[00:41:50.030] Timer tick, asking Buildbucket for the build status
[00:41:50.209] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:50.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:111:0) after 1m0s
[00:42:50.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:111:0)
[00:42:50.317] Timer tick, asking Buildbucket for the build status
[00:42:50.395] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:50.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:112:0) after 1m0s
[00:43:51.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:112:0)
[00:43:51.178] Timer tick, asking Buildbucket for the build status
[00:43:51.364] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:51.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:113:0) after 1m0s
[00:44:51.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:113:0)
[00:44:51.665] Timer tick, asking Buildbucket for the build status
[00:44:51.723] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:51.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:114:0) after 1m0s
[00:45:51.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:114:0)
[00:45:51.843] Timer tick, asking Buildbucket for the build status
[00:45:51.934] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:51.934] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:115:0) after 1m0s
[00:46:52.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:115:0)
[00:46:52.538] Timer tick, asking Buildbucket for the build status
[00:46:52.638] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:52.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:116:0) after 1m0s
[00:47:52.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:116:0)
[00:47:52.691] Timer tick, asking Buildbucket for the build status
[00:47:52.811] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:52.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:117:0) after 1m0s
[00:48:52.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:117:0)
[00:48:52.925] Timer tick, asking Buildbucket for the build status
[00:48:53.153] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:53.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:118:0) after 1m0s
[00:49:53.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:118:0)
[00:49:53.402] Timer tick, asking Buildbucket for the build status
[00:49:53.543] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:53.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:119:0) after 1m0s
[00:50:53.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:119:0)
[00:50:53.576] Timer tick, asking Buildbucket for the build status
[00:50:53.665] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:53.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:120:0) after 1m0s
[00:51:53.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:120:0)
[00:51:53.818] Timer tick, asking Buildbucket for the build status
[00:51:53.895] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:53.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:121:0) after 1m0s
[00:52:54.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:121:0)
[00:52:54.052] Timer tick, asking Buildbucket for the build status
[00:52:54.179] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:54.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:122:0) after 1m0s
[00:53:54.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:122:0)
[00:53:54.228] Timer tick, asking Buildbucket for the build status
[00:53:54.350] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:54.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:123:0) after 1m0s
[00:54:54.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:123:0)
[00:54:54.456] Timer tick, asking Buildbucket for the build status
[00:54:54.701] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:54.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:124:0) after 1m0s
[00:55:54.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:124:0)
[00:55:54.726] Timer tick, asking Buildbucket for the build status
[00:55:54.798] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:54.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:125:0) after 1m0s
[00:56:54.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:125:0)
[00:56:54.856] Timer tick, asking Buildbucket for the build status
[00:56:54.951] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:54.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:126:0) after 1m0s
[00:57:54.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:126:0)
[00:57:54.954] Timer tick, asking Buildbucket for the build status
[00:57:55.037] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:55.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:127:0) after 1m0s
[00:58:55.083] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:127:0)
[00:58:55.083] Timer tick, asking Buildbucket for the build status
[00:58:55.246] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:55.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:128:0) after 1m0s
[00:59:55.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:128:0)
[00:59:55.288] Timer tick, asking Buildbucket for the build status
[00:59:55.367] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:55.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:129:0) after 1m0s
[01:00:55.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:129:0)
[01:00:55.391] Timer tick, asking Buildbucket for the build status
[01:00:55.463] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:55.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:130:0) after 1m0s
[01:01:55.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:130:0)
[01:01:55.513] Timer tick, asking Buildbucket for the build status
[01:01:55.651] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:55.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:131:0) after 1m0s
[01:02:55.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:131:0)
[01:02:55.729] Timer tick, asking Buildbucket for the build status
[01:02:55.953] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:55.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:132:0) after 1m0s
[01:03:56.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:132:0)
[01:03:56.004] Timer tick, asking Buildbucket for the build status
[01:03:56.274] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:56.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:133:0) after 1m0s
[01:04:56.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:133:0)
[01:04:56.290] Timer tick, asking Buildbucket for the build status
[01:04:56.550] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:56.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:134:0) after 1m0s
[01:05:56.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:134:0)
[01:05:56.640] Timer tick, asking Buildbucket for the build status
[01:05:56.931] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:56.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:135:0) after 1m0s
[01:06:56.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:135:0)
[01:06:56.992] Timer tick, asking Buildbucket for the build status
[01:06:57.304] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:57.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:136:0) after 1m0s
[01:07:57.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:136:0)
[01:07:57.341] Timer tick, asking Buildbucket for the build status
[01:07:57.515] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:57.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:137:0) after 1m0s
[01:08:57.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:137:0)
[01:08:57.564] Timer tick, asking Buildbucket for the build status
[01:08:57.651] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:57.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:138:0) after 1m0s
[01:09:57.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:138:0)
[01:09:57.694] Timer tick, asking Buildbucket for the build status
[01:09:58.042] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:58.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:139:0) after 1m0s
[01:10:58.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:139:0)
[01:10:58.068] Timer tick, asking Buildbucket for the build status
[01:10:58.251] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:58.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:140:0) after 1m0s
[01:11:58.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:140:0)
[01:11:58.285] Timer tick, asking Buildbucket for the build status
[01:11:58.417] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:58.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:141:0) after 1m0s
[01:12:58.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:141:0)
[01:12:58.501] Timer tick, asking Buildbucket for the build status
[01:12:58.666] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:58.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:142:0) after 1m0s
[01:13:58.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:142:0)
[01:13:58.684] Timer tick, asking Buildbucket for the build status
[01:13:58.776] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:58.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:143:0) after 1m0s
[01:14:58.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:143:0)
[01:14:58.802] Timer tick, asking Buildbucket for the build status
[01:14:58.889] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:58.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:144:0) after 1m0s
[01:15:58.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:144:0)
[01:15:58.938] Timer tick, asking Buildbucket for the build status
[01:15:59.008] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:59.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:145:0) after 1m0s
[01:16:59.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:145:0)
[01:16:59.064] Timer tick, asking Buildbucket for the build status
[01:16:59.252] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:59.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:146:0) after 1m0s
[01:17:59.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:146:0)
[01:17:59.280] Timer tick, asking Buildbucket for the build status
[01:17:59.373] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:59.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:147:0) after 1m0s
[01:18:59.421] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:147:0)
[01:18:59.421] Timer tick, asking Buildbucket for the build status
[01:18:59.674] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:59.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:148:0) after 1m0s
[01:19:59.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:148:0)
[01:19:59.662] Timer tick, asking Buildbucket for the build status
[01:19:59.736] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:59.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:149:0) after 1m0s
[01:20:59.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:149:0)
[01:20:59.804] Timer tick, asking Buildbucket for the build status
[01:20:59.868] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:59.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:150:0) after 1m0s
[01:21:59.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:150:0)
[01:21:59.993] Timer tick, asking Buildbucket for the build status
[01:22:00.252] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:00.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:151:0) after 1m0s
[01:23:00.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:151:0)
[01:23:00.373] Timer tick, asking Buildbucket for the build status
[01:23:00.648] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:00.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:152:0) after 1m0s
[01:24:00.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:152:0)
[01:24:00.984] Timer tick, asking Buildbucket for the build status
[01:24:01.078] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:01.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:153:0) after 1m0s
[01:25:01.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:153:0)
[01:25:01.187] Timer tick, asking Buildbucket for the build status
[01:25:01.249] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:01.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:154:0) after 1m0s
[01:26:01.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:154:0)
[01:26:01.309] Timer tick, asking Buildbucket for the build status
[01:26:01.486] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:01.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:155:0) after 1m0s
[01:27:01.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:155:0)
[01:27:01.516] Timer tick, asking Buildbucket for the build status
[01:27:01.826] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:01.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:156:0) after 1m0s
[01:28:01.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:156:0)
[01:28:01.874] Timer tick, asking Buildbucket for the build status
[01:28:02.112] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:02.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:157:0) after 1m0s
[01:29:02.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:157:0)
[01:29:02.219] Timer tick, asking Buildbucket for the build status
[01:29:02.457] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:02.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:158:0) after 1m0s
[01:30:02.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:158:0)
[01:30:02.517] Timer tick, asking Buildbucket for the build status
[01:30:02.681] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:02.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:159:0) after 1m0s
[01:31:02.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:159:0)
[01:31:02.719] Timer tick, asking Buildbucket for the build status
[01:31:02.784] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:02.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:160:0) after 1m0s
[01:32:02.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:160:0)
[01:32:02.877] Timer tick, asking Buildbucket for the build status
[01:32:02.982] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:02.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:161:0) after 1m0s
[01:33:03.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:161:0)
[01:33:03.084] Timer tick, asking Buildbucket for the build status
[01:33:03.194] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:03.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:162:0) after 1m0s
[01:34:03.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:162:0)
[01:34:03.299] Timer tick, asking Buildbucket for the build status
[01:34:03.412] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:03.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:163:0) after 1m0s
[01:35:03.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:163:0)
[01:35:03.433] Timer tick, asking Buildbucket for the build status
[01:35:03.561] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:03.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:164:0) after 1m0s
[01:36:03.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:164:0)
[01:36:03.632] Timer tick, asking Buildbucket for the build status
[01:36:03.750] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:03.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:165:0) after 1m0s
[01:37:03.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:165:0)
[01:37:03.852] Timer tick, asking Buildbucket for the build status
[01:37:03.957] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:03.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:166:0) after 1m0s
[01:38:04.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:166:0)
[01:38:04.007] Timer tick, asking Buildbucket for the build status
[01:38:04.174] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:04.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:167:0) after 1m0s
[01:39:04.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:167:0)
[01:39:04.289] Timer tick, asking Buildbucket for the build status
[01:39:04.547] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:04.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:168:0) after 1m0s
[01:40:04.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:168:0)
[01:40:04.603] Timer tick, asking Buildbucket for the build status
[01:40:04.720] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:04.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:169:0) after 1m0s
[01:41:04.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:169:0)
[01:41:04.752] Timer tick, asking Buildbucket for the build status
[01:41:04.826] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:04.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:170:0) after 1m0s
[01:42:04.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:170:0)
[01:42:04.899] Timer tick, asking Buildbucket for the build status
[01:42:04.977] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:04.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:171:0) after 1m0s
[01:43:05.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:171:0)
[01:43:05.021] Timer tick, asking Buildbucket for the build status
[01:43:05.131] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:05.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:172:0) after 1m0s
[01:44:05.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:172:0)
[01:44:05.182] Timer tick, asking Buildbucket for the build status
[01:44:05.255] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:05.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:173:0) after 1m0s
[01:45:05.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:173:0)
[01:45:05.280] Timer tick, asking Buildbucket for the build status
[01:45:05.362] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:05.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:174:0) after 1m0s
[01:46:05.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:174:0)
[01:46:05.439] Timer tick, asking Buildbucket for the build status
[01:46:05.504] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:05.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:175:0) after 1m0s
[01:47:05.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:175:0)
[01:47:05.583] Timer tick, asking Buildbucket for the build status
[01:47:05.658] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:05.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:176:0) after 1m0s
[01:48:05.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:176:0)
[01:48:05.677] Timer tick, asking Buildbucket for the build status
[01:48:05.751] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:05.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:177:0) after 1m0s
[01:49:05.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:177:0)
[01:49:05.804] Timer tick, asking Buildbucket for the build status
[01:49:05.889] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:05.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:178:0) after 1m0s
[01:50:05.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:178:0)
[01:50:05.993] Timer tick, asking Buildbucket for the build status
[01:50:06.198] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:06.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:179:0) after 1m0s
[01:51:06.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:179:0)
[01:51:06.278] Timer tick, asking Buildbucket for the build status
[01:51:06.476] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:06.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:180:0) after 1m0s
[01:52:06.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:180:0)
[01:52:06.529] Timer tick, asking Buildbucket for the build status
[01:52:06.722] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:06.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:181:0) after 1m0s
[01:53:06.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:181:0)
[01:53:06.777] Timer tick, asking Buildbucket for the build status
[01:53:06.917] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:06.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:182:0) after 1m0s
[01:54:06.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:182:0)
[01:54:06.944] Timer tick, asking Buildbucket for the build status
[01:54:07.024] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:07.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:183:0) after 1m0s
[01:55:07.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:183:0)
[01:55:07.075] Timer tick, asking Buildbucket for the build status
[01:55:07.191] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:07.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:184:0) after 1m0s
[01:56:07.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:184:0)
[01:56:07.373] Timer tick, asking Buildbucket for the build status
[01:56:07.452] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:07.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:185:0) after 1m0s
[01:57:07.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:185:0)
[01:57:07.586] Timer tick, asking Buildbucket for the build status
[01:57:07.733] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:07.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:186:0) after 1m0s
[01:58:07.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:186:0)
[01:58:07.809] Timer tick, asking Buildbucket for the build status
[01:58:07.950] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:07.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:187:0) after 1m0s
[01:59:08.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:187:0)
[01:59:08.014] Timer tick, asking Buildbucket for the build status
[01:59:08.116] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:08.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:188:0) after 1m0s
[02:00:08.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:188:0)
[02:00:08.146] Timer tick, asking Buildbucket for the build status
[02:00:08.252] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:08.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:189:0) after 1m0s
[02:01:08.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:189:0)
[02:01:08.371] Timer tick, asking Buildbucket for the build status
[02:01:08.457] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:08.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:190:0) after 1m0s
[02:02:08.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:190:0)
[02:02:08.510] Timer tick, asking Buildbucket for the build status
[02:02:09.169] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:09.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:191:0) after 1m0s
[02:03:09.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:191:0)
[02:03:09.401] Timer tick, asking Buildbucket for the build status
[02:03:09.491] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:09.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:192:0) after 1m0s
[02:04:09.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:192:0)
[02:04:09.600] Timer tick, asking Buildbucket for the build status
[02:04:09.861] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:09.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:193:0) after 1m0s
[02:05:09.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:193:0)
[02:05:09.892] Timer tick, asking Buildbucket for the build status
[02:05:09.956] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:09.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:194:0) after 1m0s
[02:06:10.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:194:0)
[02:06:10.141] Timer tick, asking Buildbucket for the build status
[02:06:10.204] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:10.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:195:0) after 1m0s
[02:07:10.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:195:0)
[02:07:10.330] Timer tick, asking Buildbucket for the build status
[02:07:10.414] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:10.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:196:0) after 1m0s
[02:08:10.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:196:0)
[02:08:10.545] Timer tick, asking Buildbucket for the build status
[02:08:10.688] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:10.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:197:0) after 1m0s
[02:09:10.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:197:0)
[02:09:10.792] Timer tick, asking Buildbucket for the build status
[02:09:11.047] Build 8853106423835207904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:11.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9018536485792125120:198:0) after 1m0s
[02:09:35.894] Received PubSub notification, asking Buildbucket for the build status
[02:09:35.984] Build 8853106423835207904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:09:35.984] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1615428575591897",
    "created_by": "project:infra",
    "created_ts": "1615416805385533",
    "id": "8853106423835207904",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4442.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ec705d0d-fff1-4a05-81d6-fa2a25522a22\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4442.2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1615416940000930",
    "status": "COMPLETED",
    "status_changed_ts": "1615416805548444",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9018536485792125120",
      "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/8853106423835207904/+/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:523729d229575910",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1615428575592381",
    "url": "https://ci.chromium.org/b/8853106423835207904",
    "utcnow_ts": "1615428575975710"
  }
}
[02:09:35.984] Invocation finished in 3h16m11.848624682s with status SUCCEEDED