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

• parent_buildername:publish_tarball
• user_agent:recipe

[10:47:32.336] New invocation is queued and will start shortly
[10:47:32.336] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:47:33.621] Starting the invocation (attempt 1)
[10:47:33.658] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:47:33.660] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:47:33.660] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9042590349284620768",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"85.0.4176.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"b7025413-f8c8-4097-92e4-f6a59be2568a\",\"title\":\"publish_tarball/0\"}]},\"version\":\"85.0.4176.0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9042590349284620768",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[10:47:34.309] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1592477253763976",
    "id": "8877160287116550864",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4176.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b7025413-f8c8-4097-92e4-f6a59be2568a\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4176.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1592477254110138",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042590349284620768",
      "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/8877160287116550864/+/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": "1592477254110866",
    "url": "https://ci.chromium.org/b/8877160287116550864",
    "utcnow_ts": "1592477254295274"
  }
}
[10:47:34.309] Task URL: https://ci.chromium.org/b/8877160287116550864
[10:47:34.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:2:0) after 1m0s
[10:48:02.842] Received PubSub notification, asking Buildbucket for the build status
[10:48:03.002] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:34.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:2:0)
[10:48:34.404] Timer tick, asking Buildbucket for the build status
[10:48:36.056] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:36.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:4:0) after 1m0s
[10:49:36.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:4:0)
[10:49:36.043] Timer tick, asking Buildbucket for the build status
[10:49:36.163] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:36.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:5:0) after 1m0s
[10:50:36.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:5:0)
[10:50:36.167] Timer tick, asking Buildbucket for the build status
[10:50:37.133] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:37.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:6:0) after 1m0s
[10:51:37.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:6:0)
[10:51:37.206] Timer tick, asking Buildbucket for the build status
[10:51:37.289] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:37.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:7:0) after 1m0s
[10:52:37.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:7:0)
[10:52:37.306] Timer tick, asking Buildbucket for the build status
[10:52:37.475] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:37.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:8:0) after 1m0s
[10:53:37.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:8:0)
[10:53:37.544] Timer tick, asking Buildbucket for the build status
[10:53:37.946] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:37.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:9:0) after 1m0s
[10:54:37.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:9:0)
[10:54:37.985] Timer tick, asking Buildbucket for the build status
[10:54:38.175] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:38.175] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:10:0) after 1m0s
[10:55:38.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:10:0)
[10:55:38.214] Timer tick, asking Buildbucket for the build status
[10:55:38.337] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:38.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:11:0) after 1m0s
[10:56:38.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:11:0)
[10:56:38.376] Timer tick, asking Buildbucket for the build status
[10:56:38.479] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:38.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:12:0) after 1m0s
[10:57:38.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:12:0)
[10:57:38.462] Timer tick, asking Buildbucket for the build status
[10:57:39.032] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:39.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:13:0) after 1m0s
[10:58:39.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:13:0)
[10:58:39.371] Timer tick, asking Buildbucket for the build status
[10:58:39.514] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:39.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:14:0) after 1m0s
[10:59:39.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:14:0)
[10:59:39.626] Timer tick, asking Buildbucket for the build status
[10:59:39.764] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:39.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:15:0) after 1m0s
[11:00:39.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:15:0)
[11:00:39.823] Timer tick, asking Buildbucket for the build status
[11:00:40.296] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:40.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:16:0) after 1m0s
[11:01:40.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:16:0)
[11:01:40.355] Timer tick, asking Buildbucket for the build status
[11:01:40.532] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:40.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:17:0) after 1m0s
[11:02:40.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:17:0)
[11:02:40.657] Timer tick, asking Buildbucket for the build status
[11:02:41.047] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:41.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:18:0) after 1m0s
[11:03:41.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:18:0)
[11:03:41.164] Timer tick, asking Buildbucket for the build status
[11:03:41.572] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:41.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:19:0) after 1m0s
[11:04:41.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:19:0)
[11:04:41.634] Timer tick, asking Buildbucket for the build status
[11:04:41.930] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:41.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:20:0) after 1m0s
[11:05:42.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:20:0)
[11:05:42.017] Timer tick, asking Buildbucket for the build status
[11:05:42.170] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:42.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:21:0) after 1m0s
[11:06:42.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:21:0)
[11:06:42.210] Timer tick, asking Buildbucket for the build status
[11:06:42.674] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:42.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:22:0) after 1m0s
[11:07:42.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:22:0)
[11:07:42.751] Timer tick, asking Buildbucket for the build status
[11:07:42.920] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:42.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:23:0) after 1m0s
[11:08:42.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:23:0)
[11:08:42.946] Timer tick, asking Buildbucket for the build status
[11:08:45.419] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:45.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:24:0) after 1m0s
[11:09:45.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:24:0)
[11:09:45.446] Timer tick, asking Buildbucket for the build status
[11:09:45.599] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:45.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:25:0) after 1m0s
[11:10:45.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:25:0)
[11:10:45.625] Timer tick, asking Buildbucket for the build status
[11:10:45.913] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:45.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:26:0) after 1m0s
[11:11:45.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:26:0)
[11:11:45.949] Timer tick, asking Buildbucket for the build status
[11:11:46.465] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:46.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:27:0) after 1m0s
[11:12:46.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:27:0)
[11:12:46.507] Timer tick, asking Buildbucket for the build status
[11:12:46.757] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:46.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:28:0) after 1m0s
[11:13:46.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:28:0)
[11:13:46.811] Timer tick, asking Buildbucket for the build status
[11:13:47.011] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:47.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:29:0) after 1m0s
[11:14:47.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:29:0)
[11:14:47.068] Timer tick, asking Buildbucket for the build status
[11:14:47.131] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:47.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:30:0) after 1m0s
[11:15:47.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:30:0)
[11:15:47.255] Timer tick, asking Buildbucket for the build status
[11:15:47.424] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:47.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:31:0) after 1m0s
[11:16:47.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:31:0)
[11:16:47.510] Timer tick, asking Buildbucket for the build status
[11:16:47.649] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:47.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:32:0) after 1m0s
[11:17:47.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:32:0)
[11:17:47.719] Timer tick, asking Buildbucket for the build status
[11:17:47.870] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:47.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:33:0) after 1m0s
[11:18:47.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:33:0)
[11:18:47.926] Timer tick, asking Buildbucket for the build status
[11:18:48.167] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:48.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:34:0) after 1m0s
[11:19:48.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:34:0)
[11:19:48.192] Timer tick, asking Buildbucket for the build status
[11:19:48.492] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:48.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:35:0) after 1m0s
[11:20:48.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:35:0)
[11:20:48.563] Timer tick, asking Buildbucket for the build status
[11:20:48.713] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:48.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:36:0) after 1m0s
[11:21:48.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:36:0)
[11:21:48.712] Timer tick, asking Buildbucket for the build status
[11:21:49.415] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:49.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:37:0) after 1m0s
[11:22:49.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:37:0)
[11:22:49.433] Timer tick, asking Buildbucket for the build status
[11:22:49.676] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:49.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:38:0) after 1m0s
[11:23:49.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:38:0)
[11:23:49.733] Timer tick, asking Buildbucket for the build status
[11:23:52.614] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:52.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:39:0) after 1m0s
[11:24:52.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:39:0)
[11:24:52.709] Timer tick, asking Buildbucket for the build status
[11:24:52.907] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:52.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:40:0) after 1m0s
[11:25:52.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:40:0)
[11:25:52.960] Timer tick, asking Buildbucket for the build status
[11:25:53.073] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:53.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:41:0) after 1m0s
[11:26:53.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:41:0)
[11:26:53.109] Timer tick, asking Buildbucket for the build status
[11:26:53.260] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:53.260] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:42:0) after 1m0s
[11:27:53.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:42:0)
[11:27:53.287] Timer tick, asking Buildbucket for the build status
[11:27:53.486] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:53.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:43:0) after 1m0s
[11:28:53.527] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:43:0)
[11:28:53.527] Timer tick, asking Buildbucket for the build status
[11:28:59.693] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:59.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:44:0) after 1m0s
[11:29:59.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:44:0)
[11:29:59.729] Timer tick, asking Buildbucket for the build status
[11:30:00.213] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:00.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:45:0) after 1m0s
[11:31:00.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:45:0)
[11:31:00.334] Timer tick, asking Buildbucket for the build status
[11:31:00.571] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:00.571] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:46:0) after 1m0s
[11:32:00.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:46:0)
[11:32:00.722] Timer tick, asking Buildbucket for the build status
[11:32:00.947] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:00.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:47:0) after 1m0s
[11:33:01.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:47:0)
[11:33:01.045] Timer tick, asking Buildbucket for the build status
[11:33:01.364] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:01.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:48:0) after 1m0s
[11:34:02.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:48:0)
[11:34:02.324] Timer tick, asking Buildbucket for the build status
[11:34:02.540] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:02.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:49:0) after 1m0s
[11:35:02.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:49:0)
[11:35:02.583] Timer tick, asking Buildbucket for the build status
[11:35:03.119] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:03.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:50:0) after 1m0s
[11:36:03.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:50:0)
[11:36:03.149] Timer tick, asking Buildbucket for the build status
[11:36:04.814] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:04.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:51:0) after 1m0s
[11:37:04.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:51:0)
[11:37:04.900] Timer tick, asking Buildbucket for the build status
[11:37:05.053] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:05.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:52:0) after 1m0s
[11:38:05.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:52:0)
[11:38:05.095] Timer tick, asking Buildbucket for the build status
[11:38:05.287] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:05.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:53:0) after 1m0s
[11:39:05.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:53:0)
[11:39:05.312] Timer tick, asking Buildbucket for the build status
[11:39:05.873] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:05.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:54:0) after 1m0s
[11:40:05.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:54:0)
[11:40:05.935] Timer tick, asking Buildbucket for the build status
[11:40:06.565] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:06.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:55:0) after 1m0s
[11:41:06.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:55:0)
[11:41:06.633] Timer tick, asking Buildbucket for the build status
[11:41:07.337] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:07.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:56:0) after 1m0s
[11:42:07.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:56:0)
[11:42:07.360] Timer tick, asking Buildbucket for the build status
[11:42:07.518] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:07.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:57:0) after 1m0s
[11:43:07.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:57:0)
[11:43:07.546] Timer tick, asking Buildbucket for the build status
[11:43:07.881] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:07.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:58:0) after 1m0s
[11:44:07.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:58:0)
[11:44:07.876] Timer tick, asking Buildbucket for the build status
[11:44:09.720] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:09.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:59:0) after 1m0s
[11:45:09.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:59:0)
[11:45:09.748] Timer tick, asking Buildbucket for the build status
[11:45:09.885] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:09.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:60:0) after 1m0s
[11:46:09.910] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:60:0)
[11:46:09.910] Timer tick, asking Buildbucket for the build status
[11:46:10.041] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:10.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:61:0) after 1m0s
[11:47:10.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:61:0)
[11:47:10.113] Timer tick, asking Buildbucket for the build status
[11:47:10.503] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:10.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:62:0) after 1m0s
[11:48:10.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:62:0)
[11:48:10.524] Timer tick, asking Buildbucket for the build status
[11:48:11.177] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:11.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:63:0) after 1m0s
[11:49:11.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:63:0)
[11:49:11.201] Timer tick, asking Buildbucket for the build status
[11:49:11.299] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:11.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:64:0) after 1m0s
[11:50:11.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:64:0)
[11:50:11.277] Timer tick, asking Buildbucket for the build status
[11:50:11.440] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:11.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:65:0) after 1m0s
[11:51:11.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:65:0)
[11:51:11.565] Timer tick, asking Buildbucket for the build status
[11:51:12.121] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:12.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:66:0) after 1m0s
[11:52:12.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:66:0)
[11:52:12.196] Timer tick, asking Buildbucket for the build status
[11:52:12.392] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:12.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:67:0) after 1m0s
[11:53:12.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:67:0)
[11:53:12.485] Timer tick, asking Buildbucket for the build status
[11:53:12.876] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:12.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:68:0) after 1m0s
[11:54:12.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:68:0)
[11:54:12.908] Timer tick, asking Buildbucket for the build status
[11:54:13.195] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:13.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:69:0) after 1m0s
[11:55:13.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:69:0)
[11:55:13.286] Timer tick, asking Buildbucket for the build status
[11:55:13.826] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:13.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:70:0) after 1m0s
[11:56:13.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:70:0)
[11:56:13.850] Timer tick, asking Buildbucket for the build status
[11:56:14.061] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:14.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:71:0) after 1m0s
[11:57:14.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:71:0)
[11:57:14.064] Timer tick, asking Buildbucket for the build status
[11:57:14.261] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:14.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:72:0) after 1m0s
[11:58:14.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:72:0)
[11:58:14.298] Timer tick, asking Buildbucket for the build status
[11:58:14.405] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:14.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:73:0) after 1m0s
[11:59:14.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:73:0)
[11:59:14.403] Timer tick, asking Buildbucket for the build status
[11:59:14.909] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:14.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:74:0) after 1m0s
[12:00:14.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:74:0)
[12:00:14.964] Timer tick, asking Buildbucket for the build status
[12:00:15.143] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:15.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:75:0) after 1m0s
[12:01:15.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:75:0)
[12:01:15.200] Timer tick, asking Buildbucket for the build status
[12:01:15.413] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:15.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:76:0) after 1m0s
[12:02:15.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:76:0)
[12:02:15.495] Timer tick, asking Buildbucket for the build status
[12:02:15.633] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:15.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:77:0) after 1m0s
[12:03:15.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:77:0)
[12:03:15.668] Timer tick, asking Buildbucket for the build status
[12:03:16.060] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:16.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:78:0) after 1m0s
[12:04:16.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:78:0)
[12:04:16.133] Timer tick, asking Buildbucket for the build status
[12:04:16.271] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:16.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:79:0) after 1m0s
[12:05:16.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:79:0)
[12:05:16.335] Timer tick, asking Buildbucket for the build status
[12:05:16.470] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:16.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:80:0) after 1m0s
[12:06:16.548] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:80:0)
[12:06:16.548] Timer tick, asking Buildbucket for the build status
[12:06:16.877] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:16.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:81:0) after 1m0s
[12:07:16.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:81:0)
[12:07:16.949] Timer tick, asking Buildbucket for the build status
[12:07:17.096] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:17.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:82:0) after 1m0s
[12:08:17.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:82:0)
[12:08:17.110] Timer tick, asking Buildbucket for the build status
[12:08:17.461] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:17.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:83:0) after 1m0s
[12:09:17.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:83:0)
[12:09:17.544] Timer tick, asking Buildbucket for the build status
[12:09:17.741] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:17.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:84:0) after 1m0s
[12:10:17.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:84:0)
[12:10:17.790] Timer tick, asking Buildbucket for the build status
[12:10:18.131] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:18.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:85:0) after 1m0s
[12:11:18.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:85:0)
[12:11:18.175] Timer tick, asking Buildbucket for the build status
[12:11:18.285] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:18.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:86:0) after 1m0s
[12:12:18.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:86:0)
[12:12:18.327] Timer tick, asking Buildbucket for the build status
[12:12:18.450] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:18.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:87:0) after 1m0s
[12:13:18.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:87:0)
[12:13:18.513] Timer tick, asking Buildbucket for the build status
[12:13:18.676] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:18.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:88:0) after 1m0s
[12:14:18.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:88:0)
[12:14:18.789] Timer tick, asking Buildbucket for the build status
[12:14:19.005] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:19.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:89:0) after 1m0s
[12:15:19.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:89:0)
[12:15:19.042] Timer tick, asking Buildbucket for the build status
[12:15:19.157] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:19.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:90:0) after 1m0s
[12:16:19.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:90:0)
[12:16:19.185] Timer tick, asking Buildbucket for the build status
[12:16:19.365] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:19.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:91:0) after 1m0s
[12:17:19.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:91:0)
[12:17:19.457] Timer tick, asking Buildbucket for the build status
[12:17:19.613] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:19.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:92:0) after 1m0s
[12:18:19.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:92:0)
[12:18:19.634] Timer tick, asking Buildbucket for the build status
[12:18:19.966] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:19.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:93:0) after 1m0s
[12:19:19.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:93:0)
[12:19:19.945] Timer tick, asking Buildbucket for the build status
[12:19:20.082] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:20.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:94:0) after 1m0s
[12:20:20.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:94:0)
[12:20:20.146] Timer tick, asking Buildbucket for the build status
[12:20:20.315] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:20.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:95:0) after 1m0s
[12:21:20.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:95:0)
[12:21:20.341] Timer tick, asking Buildbucket for the build status
[12:21:20.521] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:20.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:96:0) after 1m0s
[12:22:20.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:96:0)
[12:22:20.564] Timer tick, asking Buildbucket for the build status
[12:22:20.686] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:20.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:97:0) after 1m0s
[12:23:20.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:97:0)
[12:23:20.795] Timer tick, asking Buildbucket for the build status
[12:23:21.174] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:21.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:98:0) after 1m0s
[12:24:21.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:98:0)
[12:24:21.268] Timer tick, asking Buildbucket for the build status
[12:24:21.629] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:21.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:99:0) after 1m0s
[12:25:21.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:99:0)
[12:25:21.673] Timer tick, asking Buildbucket for the build status
[12:25:21.980] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:21.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:100:0) after 1m0s
[12:26:22.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:100:0)
[12:26:22.084] Timer tick, asking Buildbucket for the build status
[12:26:22.410] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:22.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:101:0) after 1m0s
[12:27:22.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:101:0)
[12:27:22.422] Timer tick, asking Buildbucket for the build status
[12:27:22.795] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:22.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:102:0) after 1m0s
[12:28:22.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:102:0)
[12:28:22.908] Timer tick, asking Buildbucket for the build status
[12:28:23.281] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:23.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:103:0) after 1m0s
[12:29:23.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:103:0)
[12:29:23.388] Timer tick, asking Buildbucket for the build status
[12:29:23.725] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:23.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:104:0) after 1m0s
[12:30:23.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:104:0)
[12:30:23.789] Timer tick, asking Buildbucket for the build status
[12:30:23.936] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:23.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:105:0) after 1m0s
[12:31:24.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:105:0)
[12:31:24.020] Timer tick, asking Buildbucket for the build status
[12:31:24.661] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:24.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:106:0) after 1m0s
[12:32:24.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:106:0)
[12:32:24.692] Timer tick, asking Buildbucket for the build status
[12:32:25.453] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:25.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:107:0) after 1m0s
[12:33:25.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:107:0)
[12:33:25.536] Timer tick, asking Buildbucket for the build status
[12:33:26.254] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:26.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:108:0) after 1m0s
[12:34:26.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:108:0)
[12:34:26.326] Timer tick, asking Buildbucket for the build status
[12:34:26.587] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:26.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:109:0) after 1m0s
[12:35:26.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:109:0)
[12:35:26.809] Timer tick, asking Buildbucket for the build status
[12:35:27.052] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:27.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:110:0) after 1m0s
[12:36:27.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:110:0)
[12:36:27.135] Timer tick, asking Buildbucket for the build status
[12:36:27.308] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:27.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:111:0) after 1m0s
[12:37:27.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:111:0)
[12:37:27.387] Timer tick, asking Buildbucket for the build status
[12:37:27.720] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:27.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:112:0) after 1m0s
[12:38:28.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:112:0)
[12:38:28.161] Timer tick, asking Buildbucket for the build status
[12:38:28.543] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:28.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:113:0) after 1m0s
[12:39:28.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:113:0)
[12:39:28.649] Timer tick, asking Buildbucket for the build status
[12:39:28.966] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:28.966] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:114:0) after 1m0s
[12:40:29.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:114:0)
[12:40:29.211] Timer tick, asking Buildbucket for the build status
[12:40:30.013] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:30.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:115:0) after 1m0s
[12:41:30.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:115:0)
[12:41:30.275] Timer tick, asking Buildbucket for the build status
[12:41:30.448] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:30.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:116:0) after 1m0s
[12:42:30.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:116:0)
[12:42:30.501] Timer tick, asking Buildbucket for the build status
[12:42:30.726] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:30.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:117:0) after 1m0s
[12:43:30.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:117:0)
[12:43:30.784] Timer tick, asking Buildbucket for the build status
[12:43:31.061] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:31.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:118:0) after 1m0s
[12:44:31.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:118:0)
[12:44:31.158] Timer tick, asking Buildbucket for the build status
[12:44:31.300] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:31.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:119:0) after 1m0s
[12:45:31.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:119:0)
[12:45:31.333] Timer tick, asking Buildbucket for the build status
[12:45:31.476] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:31.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:120:0) after 1m0s
[12:46:31.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:120:0)
[12:46:31.497] Timer tick, asking Buildbucket for the build status
[12:46:31.795] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:31.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:121:0) after 1m0s
[12:47:31.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:121:0)
[12:47:31.808] Timer tick, asking Buildbucket for the build status
[12:47:31.924] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:31.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:122:0) after 1m0s
[12:48:31.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:122:0)
[12:48:31.950] Timer tick, asking Buildbucket for the build status
[12:48:32.109] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:32.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:123:0) after 1m0s
[12:49:32.132] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:123:0)
[12:49:32.132] Timer tick, asking Buildbucket for the build status
[12:49:32.303] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:32.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:124:0) after 1m0s
[12:50:32.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:124:0)
[12:50:32.418] Timer tick, asking Buildbucket for the build status
[12:50:32.547] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:32.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:125:0) after 1m0s
[12:51:32.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:125:0)
[12:51:32.633] Timer tick, asking Buildbucket for the build status
[12:51:32.799] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:32.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:126:0) after 1m0s
[12:52:33.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:126:0)
[12:52:33.073] Timer tick, asking Buildbucket for the build status
[12:52:33.231] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:33.231] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:127:0) after 1m0s
[12:53:33.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:127:0)
[12:53:33.359] Timer tick, asking Buildbucket for the build status
[12:53:33.861] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:33.861] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:128:0) after 1m0s
[12:54:33.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:128:0)
[12:54:33.883] Timer tick, asking Buildbucket for the build status
[12:54:34.122] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:34.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:129:0) after 1m0s
[12:55:34.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:129:0)
[12:55:34.153] Timer tick, asking Buildbucket for the build status
[12:55:34.330] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:34.331] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:130:0) after 1m0s
[12:56:34.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:130:0)
[12:56:34.559] Timer tick, asking Buildbucket for the build status
[12:56:34.721] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:34.721] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:131:0) after 1m0s
[12:57:35.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:131:0)
[12:57:35.027] Timer tick, asking Buildbucket for the build status
[12:57:35.255] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:35.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:132:0) after 1m0s
[12:58:35.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:132:0)
[12:58:35.324] Timer tick, asking Buildbucket for the build status
[12:58:35.598] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:35.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:133:0) after 1m0s
[12:59:36.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:133:0)
[12:59:36.161] Timer tick, asking Buildbucket for the build status
[12:59:36.579] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:36.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:134:0) after 1m0s
[13:00:36.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:134:0)
[13:00:36.744] Timer tick, asking Buildbucket for the build status
[13:00:37.074] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:37.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:135:0) after 1m0s
[13:01:37.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:135:0)
[13:01:37.268] Timer tick, asking Buildbucket for the build status
[13:01:39.006] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:39.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:136:0) after 1m0s
[13:02:39.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:136:0)
[13:02:39.067] Timer tick, asking Buildbucket for the build status
[13:02:39.234] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:39.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:137:0) after 1m0s
[13:03:40.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:137:0)
[13:03:40.719] Timer tick, asking Buildbucket for the build status
[13:03:45.447] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:45.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:138:0) after 1m0s
[13:04:45.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:138:0)
[13:04:45.479] Timer tick, asking Buildbucket for the build status
[13:04:45.665] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:45.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:139:0) after 1m0s
[13:05:45.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:139:0)
[13:05:45.680] Timer tick, asking Buildbucket for the build status
[13:05:45.838] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:45.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:140:0) after 1m0s
[13:06:45.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:140:0)
[13:06:45.885] Timer tick, asking Buildbucket for the build status
[13:06:46.001] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:46.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:141:0) after 1m0s
[13:07:46.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:141:0)
[13:07:46.093] Timer tick, asking Buildbucket for the build status
[13:07:46.419] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:46.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:142:0) after 1m0s
[13:08:46.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:142:0)
[13:08:46.522] Timer tick, asking Buildbucket for the build status
[13:08:46.709] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:46.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:143:0) after 1m0s
[13:09:46.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:143:0)
[13:09:46.800] Timer tick, asking Buildbucket for the build status
[13:09:47.081] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:47.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:144:0) after 1m0s
[13:10:47.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:144:0)
[13:10:47.190] Timer tick, asking Buildbucket for the build status
[13:10:47.382] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:47.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:145:0) after 1m0s
[13:11:47.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:145:0)
[13:11:47.386] Timer tick, asking Buildbucket for the build status
[13:11:48.120] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:48.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:146:0) after 1m0s
[13:12:48.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:146:0)
[13:12:48.189] Timer tick, asking Buildbucket for the build status
[13:12:48.319] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:48.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:147:0) after 1m0s
[13:13:48.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:147:0)
[13:13:48.367] Timer tick, asking Buildbucket for the build status
[13:13:48.987] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:48.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:148:0) after 1m0s
[13:14:49.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:148:0)
[13:14:49.089] Timer tick, asking Buildbucket for the build status
[13:14:49.273] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:49.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:149:0) after 1m0s
[13:15:49.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:149:0)
[13:15:49.302] Timer tick, asking Buildbucket for the build status
[13:15:49.468] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:49.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:150:0) after 1m0s
[13:16:49.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:150:0)
[13:16:49.561] Timer tick, asking Buildbucket for the build status
[13:16:49.732] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:49.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:151:0) after 1m0s
[13:17:49.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:151:0)
[13:17:49.775] Timer tick, asking Buildbucket for the build status
[13:17:49.927] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:49.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:152:0) after 1m0s
[13:18:50.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:152:0)
[13:18:50.001] Timer tick, asking Buildbucket for the build status
[13:18:51.748] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:51.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:153:0) after 1m0s
[13:19:51.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:153:0)
[13:19:51.798] Timer tick, asking Buildbucket for the build status
[13:19:51.931] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:51.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:154:0) after 1m0s
[13:20:52.074] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:154:0)
[13:20:52.074] Timer tick, asking Buildbucket for the build status
[13:20:52.452] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:52.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:155:0) after 1m0s
[13:21:52.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:155:0)
[13:21:52.621] Timer tick, asking Buildbucket for the build status
[13:21:52.795] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:52.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:156:0) after 1m0s
[13:22:52.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:156:0)
[13:22:52.878] Timer tick, asking Buildbucket for the build status
[13:22:53.005] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:53.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:157:0) after 1m0s
[13:23:53.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:157:0)
[13:23:53.154] Timer tick, asking Buildbucket for the build status
[13:23:53.263] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:53.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:158:0) after 1m0s
[13:24:53.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:158:0)
[13:24:53.329] Timer tick, asking Buildbucket for the build status
[13:24:53.504] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:53.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:159:0) after 1m0s
[13:25:53.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:159:0)
[13:25:53.620] Timer tick, asking Buildbucket for the build status
[13:25:53.901] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:53.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:160:0) after 1m0s
[13:26:54.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:160:0)
[13:26:54.040] Timer tick, asking Buildbucket for the build status
[13:26:54.220] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:54.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:161:0) after 1m0s
[13:27:54.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:161:0)
[13:27:54.345] Timer tick, asking Buildbucket for the build status
[13:27:54.462] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:54.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:162:0) after 1m0s
[13:28:54.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:162:0)
[13:28:54.611] Timer tick, asking Buildbucket for the build status
[13:28:54.986] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:54.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:163:0) after 1m0s
[13:29:55.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:163:0)
[13:29:55.015] Timer tick, asking Buildbucket for the build status
[13:29:55.174] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:55.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:164:0) after 1m0s
[13:30:55.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:164:0)
[13:30:55.221] Timer tick, asking Buildbucket for the build status
[13:30:55.627] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:55.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:165:0) after 1m0s
[13:31:55.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:165:0)
[13:31:55.672] Timer tick, asking Buildbucket for the build status
[13:31:55.845] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:55.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:166:0) after 1m0s
[13:32:55.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:166:0)
[13:32:55.895] Timer tick, asking Buildbucket for the build status
[13:32:56.259] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:56.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:167:0) after 1m0s
[13:33:56.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:167:0)
[13:33:56.298] Timer tick, asking Buildbucket for the build status
[13:33:58.013] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:58.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:168:0) after 1m0s
[13:34:58.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:168:0)
[13:34:58.091] Timer tick, asking Buildbucket for the build status
[13:34:58.235] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:58.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:169:0) after 1m0s
[13:35:58.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:169:0)
[13:35:58.284] Timer tick, asking Buildbucket for the build status
[13:35:58.618] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:58.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:170:0) after 1m0s
[13:36:58.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:170:0)
[13:36:58.695] Timer tick, asking Buildbucket for the build status
[13:36:58.924] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:58.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:171:0) after 1m0s
[13:37:59.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:171:0)
[13:37:59.018] Timer tick, asking Buildbucket for the build status
[13:37:59.214] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:59.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:172:0) after 1m0s
[13:38:59.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:172:0)
[13:38:59.305] Timer tick, asking Buildbucket for the build status
[13:38:59.477] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:59.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:173:0) after 1m0s
[13:39:59.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:173:0)
[13:39:59.504] Timer tick, asking Buildbucket for the build status
[13:39:59.638] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:59.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:174:0) after 1m0s
[13:40:59.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:174:0)
[13:40:59.666] Timer tick, asking Buildbucket for the build status
[13:40:59.845] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:59.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:175:0) after 1m0s
[13:41:59.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:175:0)
[13:41:59.882] Timer tick, asking Buildbucket for the build status
[13:42:00.315] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:00.315] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:176:0) after 1m0s
[13:43:00.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:176:0)
[13:43:00.375] Timer tick, asking Buildbucket for the build status
[13:43:00.823] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:00.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:177:0) after 1m0s
[13:44:00.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:177:0)
[13:44:00.904] Timer tick, asking Buildbucket for the build status
[13:44:01.255] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:01.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:178:0) after 1m0s
[13:45:01.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:178:0)
[13:45:01.428] Timer tick, asking Buildbucket for the build status
[13:45:01.660] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:01.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:179:0) after 1m0s
[13:46:01.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:179:0)
[13:46:01.686] Timer tick, asking Buildbucket for the build status
[13:46:01.796] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:01.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:180:0) after 1m0s
[13:47:01.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:180:0)
[13:47:01.872] Timer tick, asking Buildbucket for the build status
[13:47:02.341] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:02.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:181:0) after 1m0s
[13:48:02.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:181:0)
[13:48:02.392] Timer tick, asking Buildbucket for the build status
[13:48:02.532] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:02.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:182:0) after 1m0s
[13:49:02.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:182:0)
[13:49:02.580] Timer tick, asking Buildbucket for the build status
[13:49:02.725] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:02.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:183:0) after 1m0s
[13:50:03.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:183:0)
[13:50:03.139] Timer tick, asking Buildbucket for the build status
[13:50:03.492] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:03.492] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:184:0) after 1m0s
[13:51:03.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:184:0)
[13:51:03.520] Timer tick, asking Buildbucket for the build status
[13:51:03.895] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:03.895] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:185:0) after 1m0s
[13:52:04.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:185:0)
[13:52:04.068] Timer tick, asking Buildbucket for the build status
[13:52:04.226] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:04.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:186:0) after 1m0s
[13:53:04.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:186:0)
[13:53:04.332] Timer tick, asking Buildbucket for the build status
[13:53:04.808] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:04.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:187:0) after 1m0s
[13:54:04.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:187:0)
[13:54:04.851] Timer tick, asking Buildbucket for the build status
[13:54:05.062] Build 8877160287116550864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:05.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9042590349284620768:188:0) after 1m0s
[13:54:10.656] Received PubSub notification, asking Buildbucket for the build status
[13:54:10.890] Build 8877160287116550864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:54:10.890] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1592488449745542",
    "created_by": "project:infra",
    "created_ts": "1592477253763976",
    "id": "8877160287116550864",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"85.0.4176.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"b7025413-f8c8-4097-92e4-f6a59be2568a\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"85.0.4176.0\"}}",
    "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-fiyq\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1592477282052127",
    "status": "COMPLETED",
    "status_changed_ts": "1592488450286441",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9042590349284620768",
      "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/8877160287116550864/+/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:4cdfdc0bc4227410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1592488450302149",
    "url": "https://ci.chromium.org/b/8877160287116550864",
    "utcnow_ts": "1592488450867177"
  }
}
[13:54:10.890] Invocation finished in 3h6m38.578040936s with status SUCCEEDED