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

• parent_buildername:publish_tarball
• user_agent:recipe

[23:56:28.380] New invocation is queued and will start shortly
[23:56:28.380] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[23:56:29.531] Starting the invocation (attempt 1)
[23:56:29.567] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:56:29.567] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:56:29.568] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9017898338981638544",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"91.0.4449.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"73cbbb83-c287-4f48-b2e8-62f20e95d07b\",\"title\":\"publish_tarball/0\"}]},\"version\":\"91.0.4449.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:9017898338981638544",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[23:56:30.082] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1616025389642122",
    "id": "8852468276989424208",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4449.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"73cbbb83-c287-4f48-b2e8-62f20e95d07b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4449.2\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1616025389900803",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9017898338981638544",
      "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/8852468276989424208/+/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": "1616025389900908",
    "url": "https://ci.chromium.org/b/8852468276989424208",
    "utcnow_ts": "1616025390068877"
  }
}
[23:56:30.082] Task URL: https://ci.chromium.org/b/8852468276989424208
[23:56:30.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:2:0) after 1m0s
[23:57:30.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:2:0)
[23:57:30.102] Timer tick, asking Buildbucket for the build status
[23:57:30.182] Build 8852468276989424208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:30.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:3:0) after 1m0s
[23:57:34.833] Received PubSub notification, asking Buildbucket for the build status
[23:57:34.917] Build status: STARTED
[23:58:30.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:3:0)
[23:58:30.214] Timer tick, asking Buildbucket for the build status
[23:58:30.279] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:30.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:5:0) after 1m0s
[23:59:30.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:5:0)
[23:59:30.332] Build status: STARTED
[23:59:30.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:6:0) after 2m30s
[00:02:00.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:6:0)
[00:02:00.356] Timer tick, asking Buildbucket for the build status
[00:02:00.498] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:00.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:7:0) after 1m0s
[00:03:00.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:7:0)
[00:03:00.674] Build status: STARTED
[00:03:00.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:8:0) after 6m40s
[00:09:40.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:8:0)
[00:09:40.792] Timer tick, asking Buildbucket for the build status
[00:09:40.921] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:40.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:9:0) after 1m0s
[00:10:40.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:9:0)
[00:10:40.935] Timer tick, asking Buildbucket for the build status
[00:10:41.209] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:41.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:10:0) after 1m0s
[00:11:41.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:10:0)
[00:11:41.300] Build status: STARTED
[00:11:41.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:11:0) after 3m45s
[00:15:26.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:11:0)
[00:15:26.403] Timer tick, asking Buildbucket for the build status
[00:15:26.489] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:26.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:12:0) after 1m0s
[00:16:26.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:12:0)
[00:16:26.539] Build status: STARTED
[00:16:26.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:13:0) after 4m8s
[00:20:34.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:13:0)
[00:20:34.700] Build status: STARTED
[00:20:34.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:14:0) after 8m36s
[00:29:10.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:14:0)
[00:29:10.811] Build status: STARTED
[00:29:10.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:15:0) after 8m39s
[00:37:49.839] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:15:0)
[00:37:49.886] Build status: STARTED
[00:37:49.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:16:0) after 8m19s
[00:46:08.930] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:16:0)
[00:46:08.983] Build status: STARTED
[00:46:08.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:17:0) after 4m41s
[00:50:50.005] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:17:0)
[00:50:50.005] Timer tick, asking Buildbucket for the build status
[00:50:50.172] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:50.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:18:0) after 1m0s
[00:51:50.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:18:0)
[00:51:50.232] Timer tick, asking Buildbucket for the build status
[00:51:50.327] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:50.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:19:0) after 1m0s
[00:52:50.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:19:0)
[00:52:50.372] Timer tick, asking Buildbucket for the build status
[00:52:50.496] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:50.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:20:0) after 1m0s
[00:53:50.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:20:0)
[00:53:50.526] Timer tick, asking Buildbucket for the build status
[00:53:50.778] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:50.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:21:0) after 1m0s
[00:54:50.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:21:0)
[00:54:50.862] Timer tick, asking Buildbucket for the build status
[00:54:51.050] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:51.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:22:0) after 1m0s
[00:55:51.086] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:22:0)
[00:55:51.086] Timer tick, asking Buildbucket for the build status
[00:55:51.277] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:51.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:23:0) after 1m0s
[00:56:51.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:23:0)
[00:56:51.338] Build status: STARTED
[00:56:51.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:24:0) after 9m52s
[01:06:43.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:24:0)
[01:06:43.393] Build status: STARTED
[01:06:43.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:25:0) after 8m33s
[01:15:16.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:25:0)
[01:15:16.440] Timer tick, asking Buildbucket for the build status
[01:15:16.806] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:16.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:26:0) after 1m0s
[01:16:16.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:26:0)
[01:16:16.867] Build status: STARTED
[01:16:16.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:27:0) after 6m44s
[01:23:00.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:27:0)
[01:23:00.920] Timer tick, asking Buildbucket for the build status
[01:23:01.224] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:01.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:28:0) after 1m0s
[01:24:01.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:28:0)
[01:24:01.247] Timer tick, asking Buildbucket for the build status
[01:24:01.494] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:01.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:29:0) after 1m0s
[01:25:01.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:29:0)
[01:25:01.554] Build status: STARTED
[01:25:01.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:30:0) after 9m20s
[01:34:21.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:30:0)
[01:34:21.693] Build status: STARTED
[01:34:21.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:31:0) after 3m0s
[01:37:21.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:31:0)
[01:37:21.722] Build status: STARTED
[01:37:21.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:32:0) after 1m47s
[01:39:08.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:32:0)
[01:39:08.765] Build status: STARTED
[01:39:08.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:33:0) after 6m26s
[01:45:34.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:33:0)
[01:45:34.783] Timer tick, asking Buildbucket for the build status
[01:45:34.924] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:34.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:34:0) after 1m0s
[01:46:34.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:34:0)
[01:46:34.984] Timer tick, asking Buildbucket for the build status
[01:46:35.062] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:35.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:35:0) after 1m0s
[01:47:35.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:35:0)
[01:47:35.185] Timer tick, asking Buildbucket for the build status
[01:47:35.262] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:35.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:36:0) after 1m0s
[01:48:35.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:36:0)
[01:48:35.344] Timer tick, asking Buildbucket for the build status
[01:48:35.418] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:35.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:37:0) after 1m0s
[01:49:35.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:37:0)
[01:49:35.434] Timer tick, asking Buildbucket for the build status
[01:49:35.511] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:35.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:38:0) after 1m0s
[01:50:35.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:38:0)
[01:50:35.559] Timer tick, asking Buildbucket for the build status
[01:50:35.633] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:35.633] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:39:0) after 1m0s
[01:51:35.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:39:0)
[01:51:35.705] Timer tick, asking Buildbucket for the build status
[01:51:35.918] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:35.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:40:0) after 1m0s
[01:52:35.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:40:0)
[01:52:35.946] Build status: STARTED
[01:52:35.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:41:0) after 7m37s
[02:00:13.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:41:0)
[02:00:13.132] Build status: STARTED
[02:00:13.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:42:0) after 4m18s
[02:04:31.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:42:0)
[02:04:31.322] Build status: STARTED
[02:04:31.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:43:0) after 1m37s
[02:06:08.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:43:0)
[02:06:08.343] Build status: STARTED
[02:06:08.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:44:0) after 6m2s
[02:12:10.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:44:0)
[02:12:10.363] Timer tick, asking Buildbucket for the build status
[02:12:10.486] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:10.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:45:0) after 1m0s
[02:13:10.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:45:0)
[02:13:10.507] Timer tick, asking Buildbucket for the build status
[02:13:10.578] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:10.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:46:0) after 1m0s
[02:14:10.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:46:0)
[02:14:10.594] Timer tick, asking Buildbucket for the build status
[02:14:10.661] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:10.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:47:0) after 1m0s
[02:15:10.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:47:0)
[02:15:10.681] Timer tick, asking Buildbucket for the build status
[02:15:11.009] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:11.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:48:0) after 1m0s
[02:16:11.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:48:0)
[02:16:11.031] Timer tick, asking Buildbucket for the build status
[02:16:11.275] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:11.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:49:0) after 1m0s
[02:17:11.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:49:0)
[02:17:11.425] Build status: STARTED
[02:17:11.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:50:0) after 3m0s
[02:20:11.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:50:0)
[02:20:11.626] Build status: STARTED
[02:20:11.626] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:51:0) after 5m38s
[02:25:50.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:51:0)
[02:25:50.900] Build status: STARTED
[02:25:50.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:52:0) after 6m44s
[02:32:34.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:52:0)
[02:32:34.957] Build status: STARTED
[02:32:34.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:53:0) after 1m35s
[02:34:10.163] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:53:0)
[02:34:10.163] Timer tick, asking Buildbucket for the build status
[02:34:10.481] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:10.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:54:0) after 1m0s
[02:35:10.737] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:54:0)
[02:35:10.737] Timer tick, asking Buildbucket for the build status
[02:35:10.971] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:10.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:55:0) after 1m0s
[02:36:11.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:55:0)
[02:36:11.129] Build status: STARTED
[02:36:11.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:56:0) after 3m42s
[02:39:53.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:56:0)
[02:39:53.204] Timer tick, asking Buildbucket for the build status
[02:39:53.296] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:53.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:57:0) after 1m0s
[02:40:53.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:57:0)
[02:40:53.445] Build status: STARTED
[02:40:53.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:58:0) after 3m57s
[02:44:50.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:58:0)
[02:44:50.491] Build status: STARTED
[02:44:50.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:59:0) after 2m17s
[02:47:07.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:59:0)
[02:47:07.563] Timer tick, asking Buildbucket for the build status
[02:47:07.655] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:07.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:60:0) after 1m0s
[02:48:07.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:60:0)
[02:48:07.712] Build status: STARTED
[02:48:07.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:61:0) after 8m6s
[02:56:13.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:61:0)
[02:56:13.738] Timer tick, asking Buildbucket for the build status
[02:56:13.878] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:13.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:62:0) after 1m0s
[02:57:13.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:62:0)
[02:57:13.946] Timer tick, asking Buildbucket for the build status
[02:57:14.082] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:14.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:63:0) after 1m0s
[02:58:14.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:63:0)
[02:58:14.230] Build status: STARTED
[02:58:14.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:64:0) after 6m40s
[03:04:54.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:64:0)
[03:04:54.309] Build status: STARTED
[03:04:54.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:65:0) after 6m40s
[03:11:34.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:65:0)
[03:11:34.402] Timer tick, asking Buildbucket for the build status
[03:11:34.475] Build 8852468276989424208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:34.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:66:0) after 1m0s
[03:12:34.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:66:0)
[03:12:34.627] Build status: STARTED
[03:12:34.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017898338981638544:67:0) after 7m27s
[03:16:04.867] Received PubSub notification, asking Buildbucket for the build status
[03:16:05.041] Build 8852468276989424208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:16:05.041] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1616037364240783",
    "created_by": "project:infra",
    "created_ts": "1616025389642122",
    "id": "8852468276989424208",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4449.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"73cbbb83-c287-4f48-b2e8-62f20e95d07b\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4449.2\"}}",
    "project": "infra",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-4z28\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1616025453643864",
    "status": "COMPLETED",
    "status_changed_ts": "1616025454178107",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9017898338981638544",
      "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/8852468276989424208/+/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:525b701463d5f410",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1616037364241410",
    "url": "https://ci.chromium.org/b/8852468276989424208",
    "utcnow_ts": "1616037365029120"
  }
}
[03:16:05.041] Invocation finished in 3h19m36.683441523s with status SUCCEEDED