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

• parent_buildername:publish_tarball
• user_agent:recipe

[22:49:34.153] New invocation is queued and will start shortly
[22:49:34.153] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:49:35.344] Starting the invocation (attempt 1)
[22:49:35.387] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:49:35.387] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:49:35.388] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9050698650034524400",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"80.0.3987.158\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"2a393f59-56b0-4c3d-8490-4878c72eeeae\",\"title\":\"publish_tarball/0\"}]},\"version\":\"80.0.3987.158\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9050698650034524400",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[22:49:35.911] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "created_by": "project:infra",
    "created_ts": "1584744575491223",
    "id": "8885268587967980528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.158\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"2a393f59-56b0-4c3d-8490-4878c72eeeae\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"80.0.3987.158\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1584744575734038",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9050698650034524400",
      "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/8885268587967980528/+/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": "1584744575734209",
    "url": "https://ci.chromium.org/b/8885268587967980528",
    "utcnow_ts": "1584744575902725"
  }
}
[22:49:35.911] Task URL: https://ci.chromium.org/b/8885268587967980528
[22:49:35.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:2:0) after 1m0s
[22:49:56.065] Received PubSub notification, asking Buildbucket for the build status
[22:49:56.487] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:35.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:2:0)
[22:50:35.942] Timer tick, asking Buildbucket for the build status
[22:50:36.198] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:36.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:4:0) after 1m0s
[22:51:36.219] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:4:0)
[22:51:36.219] Timer tick, asking Buildbucket for the build status
[22:51:36.491] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:36.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:5:0) after 1m0s
[22:52:36.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:5:0)
[22:52:36.551] Timer tick, asking Buildbucket for the build status
[22:52:38.339] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:38.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:6:0) after 1m0s
[22:53:38.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:6:0)
[22:53:38.390] Timer tick, asking Buildbucket for the build status
[22:53:38.557] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:38.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:7:0) after 1m0s
[22:54:38.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:7:0)
[22:54:38.593] Timer tick, asking Buildbucket for the build status
[22:54:39.072] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:39.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:8:0) after 1m0s
[22:55:39.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:8:0)
[22:55:39.126] Timer tick, asking Buildbucket for the build status
[22:55:39.698] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:39.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:9:0) after 1m0s
[22:56:39.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:9:0)
[22:56:39.800] Timer tick, asking Buildbucket for the build status
[22:56:40.732] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:40.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:10:0) after 1m0s
[22:57:40.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:10:0)
[22:57:40.834] Timer tick, asking Buildbucket for the build status
[22:57:41.161] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:41.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:11:0) after 1m0s
[22:58:41.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:11:0)
[22:58:41.246] Timer tick, asking Buildbucket for the build status
[22:58:41.489] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:41.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:12:0) after 1m0s
[22:59:41.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:12:0)
[22:59:41.515] Timer tick, asking Buildbucket for the build status
[22:59:41.738] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:41.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:13:0) after 1m0s
[23:00:41.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:13:0)
[23:00:41.840] Timer tick, asking Buildbucket for the build status
[23:00:42.206] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:42.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:14:0) after 1m0s
[23:01:42.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:14:0)
[23:01:42.387] Timer tick, asking Buildbucket for the build status
[23:01:42.632] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:42.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:15:0) after 1m0s
[23:02:42.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:15:0)
[23:02:42.683] Timer tick, asking Buildbucket for the build status
[23:02:43.225] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:43.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:16:0) after 1m0s
[23:03:43.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:16:0)
[23:03:43.263] Timer tick, asking Buildbucket for the build status
[23:03:44.309] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:44.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:17:0) after 1m0s
[23:04:44.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:17:0)
[23:04:44.326] Timer tick, asking Buildbucket for the build status
[23:04:44.695] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:44.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:18:0) after 1m0s
[23:05:44.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:18:0)
[23:05:44.749] Timer tick, asking Buildbucket for the build status
[23:05:45.303] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:45.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:19:0) after 1m0s
[23:06:45.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:19:0)
[23:06:45.350] Timer tick, asking Buildbucket for the build status
[23:06:48.718] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:48.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:20:0) after 1m0s
[23:07:48.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:20:0)
[23:07:48.796] Timer tick, asking Buildbucket for the build status
[23:07:49.712] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:49.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:21:0) after 1m0s
[23:08:49.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:21:0)
[23:08:49.764] Timer tick, asking Buildbucket for the build status
[23:08:50.087] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:50.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:22:0) after 1m0s
[23:09:50.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:22:0)
[23:09:50.150] Timer tick, asking Buildbucket for the build status
[23:09:50.346] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:50.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:23:0) after 1m0s
[23:10:50.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:23:0)
[23:10:50.402] Timer tick, asking Buildbucket for the build status
[23:10:50.558] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:50.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:24:0) after 1m0s
[23:11:50.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:24:0)
[23:11:50.607] Timer tick, asking Buildbucket for the build status
[23:11:50.798] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:50.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:25:0) after 1m0s
[23:12:50.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:25:0)
[23:12:50.846] Timer tick, asking Buildbucket for the build status
[23:12:52.005] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:52.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:26:0) after 1m0s
[23:13:52.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:26:0)
[23:13:52.107] Timer tick, asking Buildbucket for the build status
[23:13:52.396] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:52.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:27:0) after 1m0s
[23:14:52.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:27:0)
[23:14:52.434] Timer tick, asking Buildbucket for the build status
[23:14:54.093] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:54.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:28:0) after 1m0s
[23:15:54.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:28:0)
[23:15:54.148] Timer tick, asking Buildbucket for the build status
[23:15:54.267] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:54.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:29:0) after 1m0s
[23:16:54.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:29:0)
[23:16:54.367] Timer tick, asking Buildbucket for the build status
[23:16:54.750] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:54.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:30:0) after 1m0s
[23:17:54.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:30:0)
[23:17:54.840] Timer tick, asking Buildbucket for the build status
[23:17:55.150] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:55.150] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:31:0) after 1m0s
[23:18:55.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:31:0)
[23:18:55.172] Timer tick, asking Buildbucket for the build status
[23:18:55.396] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:55.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:32:0) after 1m0s
[23:19:55.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:32:0)
[23:19:55.383] Timer tick, asking Buildbucket for the build status
[23:19:55.972] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:55.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:33:0) after 1m0s
[23:20:56.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:33:0)
[23:20:56.000] Timer tick, asking Buildbucket for the build status
[23:20:56.367] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:56.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:34:0) after 1m0s
[23:21:56.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:34:0)
[23:21:56.399] Timer tick, asking Buildbucket for the build status
[23:21:56.631] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:56.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:35:0) after 1m0s
[23:22:56.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:35:0)
[23:22:56.727] Timer tick, asking Buildbucket for the build status
[23:22:57.387] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:57.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:36:0) after 1m0s
[23:23:57.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:36:0)
[23:23:57.388] Timer tick, asking Buildbucket for the build status
[23:23:58.018] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:58.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:37:0) after 1m0s
[23:24:58.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:37:0)
[23:24:58.068] Timer tick, asking Buildbucket for the build status
[23:24:58.209] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:58.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:38:0) after 1m0s
[23:25:58.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:38:0)
[23:25:58.286] Timer tick, asking Buildbucket for the build status
[23:25:58.481] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:58.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:39:0) after 1m0s
[23:26:58.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:39:0)
[23:26:58.618] Timer tick, asking Buildbucket for the build status
[23:26:58.697] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:58.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:40:0) after 1m0s
[23:27:58.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:40:0)
[23:27:58.765] Timer tick, asking Buildbucket for the build status
[23:27:59.115] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:59.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:41:0) after 1m0s
[23:28:59.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:41:0)
[23:28:59.161] Timer tick, asking Buildbucket for the build status
[23:29:00.794] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:00.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:42:0) after 1m0s
[23:30:00.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:42:0)
[23:30:00.845] Timer tick, asking Buildbucket for the build status
[23:30:01.352] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:01.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:43:0) after 1m0s
[23:31:01.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:43:0)
[23:31:01.550] Timer tick, asking Buildbucket for the build status
[23:31:02.547] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:02.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:44:0) after 1m0s
[23:32:02.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:44:0)
[23:32:02.747] Timer tick, asking Buildbucket for the build status
[23:32:03.239] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:03.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:45:0) after 1m0s
[23:33:03.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:45:0)
[23:33:03.336] Timer tick, asking Buildbucket for the build status
[23:33:03.430] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:03.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:46:0) after 1m0s
[23:34:03.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:46:0)
[23:34:03.478] Timer tick, asking Buildbucket for the build status
[23:34:04.095] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:04.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:47:0) after 1m0s
[23:35:04.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:47:0)
[23:35:04.108] Timer tick, asking Buildbucket for the build status
[23:35:04.323] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:04.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:48:0) after 1m0s
[23:36:04.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:48:0)
[23:36:04.358] Timer tick, asking Buildbucket for the build status
[23:36:04.559] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:04.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:49:0) after 1m0s
[23:37:04.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:49:0)
[23:37:04.575] Timer tick, asking Buildbucket for the build status
[23:37:04.743] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:04.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:50:0) after 1m0s
[23:38:04.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:50:0)
[23:38:04.835] Timer tick, asking Buildbucket for the build status
[23:38:05.234] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:05.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:51:0) after 1m0s
[23:39:05.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:51:0)
[23:39:05.296] Timer tick, asking Buildbucket for the build status
[23:39:05.705] Build 8885268587967980528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:05.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9050698650034524400:52:0) after 1m0s
[23:39:13.291] Received PubSub notification, asking Buildbucket for the build status
[23:39:13.404] Build 8885268587967980528: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:39:13.404] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "PROD",
    "completed_ts": "1584747552739995",
    "created_by": "project:infra",
    "created_ts": "1584744575491223",
    "failure_reason": "BUILD_FAILURE",
    "id": "8885268587967980528",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.158\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"2a393f59-56b0-4c3d-8490-4878c72eeeae\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"80.0.3987.158\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('Build chrome.') (retcode: 1)\"}, \"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.158\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"2a393f59-56b0-4c3d-8490-4878c72eeeae\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-c49g\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"80.0.3987.158\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"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-c49g\"], \"image\": [\"chrome-xenial-20031500-6b26172a85c\"], \"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\": [\"4874-3598771\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Build chrome.') (retcode: 1)\"}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1584744595401953",
    "status": "COMPLETED",
    "status_changed_ts": "1584744595800812",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9050698650034524400",
      "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/8885268587967980528/+/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:4b12f4ee0a336210",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1584747552895655",
    "url": "https://ci.chromium.org/b/8885268587967980528",
    "utcnow_ts": "1584747553392483"
  }
}
[23:39:13.404] Invocation finished in 49m39.267030725s with status FAILED