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

• parent_buildername:publish_tarball
• user_agent:recipe

[06:10:12.360] New invocation is queued and will start shortly
[06:10:12.360] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[06:10:13.643] Starting the invocation (attempt 1)
[06:10:13.682] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:10:13.682] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:10:13.682] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9051033315449439360",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"81.0.4044.66\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"74a792c6-86d1-40fb-9f40-cf53de556406\",\"title\":\"publish_tarball/0\"}]},\"version\":\"81.0.4044.66\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9051033315449439360",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[06:10:15.099] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:infra",
    "created_ts": "1584425413833567",
    "id": "8885603253223019184",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4044.66\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"74a792c6-86d1-40fb-9f40-cf53de556406\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4044.66\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1584425414509654",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9051033315449439360",
      "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/8885603253223019184/+/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": "1584425414510586",
    "url": "https://ci.chromium.org/b/8885603253223019184",
    "utcnow_ts": "1584425415036291"
  }
}
[06:10:15.099] Task URL: https://ci.chromium.org/b/8885603253223019184
[06:10:15.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:2:0) after 1m0s
[06:11:15.255] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:2:0)
[06:11:15.255] Timer tick, asking Buildbucket for the build status
[06:11:15.428] Build 8885603253223019184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:15.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:3:0) after 1m0s
[06:11:16.001] Received PubSub notification, asking Buildbucket for the build status
[06:11:16.845] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:15.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:3:0)
[06:12:15.444] Timer tick, asking Buildbucket for the build status
[06:12:15.587] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:15.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:5:0) after 1m0s
[06:13:15.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:5:0)
[06:13:15.619] Timer tick, asking Buildbucket for the build status
[06:13:15.814] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:15.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:6:0) after 1m0s
[06:14:15.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:6:0)
[06:14:15.837] Timer tick, asking Buildbucket for the build status
[06:14:16.357] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:16.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:7:0) after 1m0s
[06:15:16.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:7:0)
[06:15:16.414] Timer tick, asking Buildbucket for the build status
[06:15:16.595] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:16.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:8:0) after 1m0s
[06:16:16.641] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:8:0)
[06:16:16.641] Timer tick, asking Buildbucket for the build status
[06:16:16.838] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:16.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:9:0) after 1m0s
[06:17:16.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:9:0)
[06:17:16.890] Timer tick, asking Buildbucket for the build status
[06:17:17.092] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:17.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:10:0) after 1m0s
[06:18:17.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:10:0)
[06:18:17.192] Timer tick, asking Buildbucket for the build status
[06:18:17.703] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:17.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:11:0) after 1m0s
[06:19:17.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:11:0)
[06:19:17.959] Timer tick, asking Buildbucket for the build status
[06:19:18.115] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:18.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:12:0) after 1m0s
[06:20:18.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:12:0)
[06:20:18.201] Timer tick, asking Buildbucket for the build status
[06:20:18.413] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:18.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:13:0) after 1m0s
[06:21:18.481] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:13:0)
[06:21:18.481] Timer tick, asking Buildbucket for the build status
[06:21:18.933] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:18.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:14:0) after 1m0s
[06:22:18.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:14:0)
[06:22:18.926] Timer tick, asking Buildbucket for the build status
[06:22:19.045] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:19.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:15:0) after 1m0s
[06:23:19.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:15:0)
[06:23:19.063] Timer tick, asking Buildbucket for the build status
[06:23:19.304] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:19.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:16:0) after 1m0s
[06:24:19.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:16:0)
[06:24:19.346] Timer tick, asking Buildbucket for the build status
[06:24:19.725] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:19.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:17:0) after 1m0s
[06:25:19.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:17:0)
[06:25:19.810] Timer tick, asking Buildbucket for the build status
[06:25:19.924] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:19.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:18:0) after 1m0s
[06:26:19.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:18:0)
[06:26:19.944] Timer tick, asking Buildbucket for the build status
[06:26:20.067] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:20.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:19:0) after 1m0s
[06:27:20.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:19:0)
[06:27:20.079] Timer tick, asking Buildbucket for the build status
[06:27:20.300] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:20.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:20:0) after 1m0s
[06:28:20.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:20:0)
[06:28:20.305] Timer tick, asking Buildbucket for the build status
[06:28:20.549] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:20.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:21:0) after 1m0s
[06:29:20.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:21:0)
[06:29:20.976] Timer tick, asking Buildbucket for the build status
[06:29:25.326] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:25.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:22:0) after 1m0s
[06:30:25.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:22:0)
[06:30:25.345] Timer tick, asking Buildbucket for the build status
[06:30:25.614] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:25.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:23:0) after 1m0s
[06:31:25.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:23:0)
[06:31:25.709] Timer tick, asking Buildbucket for the build status
[06:31:26.056] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:26.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:24:0) after 1m0s
[06:32:26.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:24:0)
[06:32:26.082] Timer tick, asking Buildbucket for the build status
[06:32:26.218] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:26.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:25:0) after 1m0s
[06:33:26.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:25:0)
[06:33:26.262] Timer tick, asking Buildbucket for the build status
[06:33:26.594] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:26.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:26:0) after 1m0s
[06:34:26.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:26:0)
[06:34:26.617] Timer tick, asking Buildbucket for the build status
[06:34:32.916] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:32.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:27:0) after 1m0s
[06:35:32.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:27:0)
[06:35:32.934] Timer tick, asking Buildbucket for the build status
[06:35:33.468] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:33.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:28:0) after 1m0s
[06:36:33.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:28:0)
[06:36:33.491] Timer tick, asking Buildbucket for the build status
[06:36:33.915] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:33.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:29:0) after 1m0s
[06:37:33.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:29:0)
[06:37:33.935] Timer tick, asking Buildbucket for the build status
[06:37:34.101] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:34.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:30:0) after 1m0s
[06:38:34.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:30:0)
[06:38:34.139] Timer tick, asking Buildbucket for the build status
[06:38:34.362] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:34.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:31:0) after 1m0s
[06:39:34.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:31:0)
[06:39:34.424] Timer tick, asking Buildbucket for the build status
[06:39:36.496] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:36.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:32:0) after 1m0s
[06:40:36.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:32:0)
[06:40:36.532] Timer tick, asking Buildbucket for the build status
[06:40:36.618] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:36.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:33:0) after 1m0s
[06:41:36.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:33:0)
[06:41:36.732] Timer tick, asking Buildbucket for the build status
[06:41:37.527] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:37.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:34:0) after 1m0s
[06:42:37.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:34:0)
[06:42:37.565] Timer tick, asking Buildbucket for the build status
[06:42:37.702] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:37.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:35:0) after 1m0s
[06:43:37.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:35:0)
[06:43:37.729] Timer tick, asking Buildbucket for the build status
[06:43:37.932] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:37.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:36:0) after 1m0s
[06:44:37.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:36:0)
[06:44:37.969] Timer tick, asking Buildbucket for the build status
[06:44:39.510] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:39.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:37:0) after 1m0s
[06:45:39.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:37:0)
[06:45:39.572] Timer tick, asking Buildbucket for the build status
[06:45:39.793] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:39.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:38:0) after 1m0s
[06:46:39.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:38:0)
[06:46:39.814] Timer tick, asking Buildbucket for the build status
[06:46:40.065] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:40.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:39:0) after 1m0s
[06:47:40.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:39:0)
[06:47:40.045] Timer tick, asking Buildbucket for the build status
[06:47:40.196] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:40.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:40:0) after 1m0s
[06:48:40.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:40:0)
[06:48:40.200] Timer tick, asking Buildbucket for the build status
[06:48:40.354] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:40.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:41:0) after 1m0s
[06:49:40.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:41:0)
[06:49:40.419] Timer tick, asking Buildbucket for the build status
[06:49:44.371] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:44.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:42:0) after 1m0s
[06:50:44.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:42:0)
[06:50:44.579] Timer tick, asking Buildbucket for the build status
[06:50:44.703] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:44.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:43:0) after 1m0s
[06:51:45.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:43:0)
[06:51:45.187] Timer tick, asking Buildbucket for the build status
[06:51:45.600] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:45.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:44:0) after 1m0s
[06:52:45.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:44:0)
[06:52:45.690] Timer tick, asking Buildbucket for the build status
[06:52:45.845] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:45.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:45:0) after 1m0s
[06:53:46.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:45:0)
[06:53:46.130] Timer tick, asking Buildbucket for the build status
[06:53:46.477] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:46.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:46:0) after 1m0s
[06:54:46.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:46:0)
[06:54:46.749] Timer tick, asking Buildbucket for the build status
[06:54:47.156] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:47.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:47:0) after 1m0s
[06:55:47.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:47:0)
[06:55:47.191] Timer tick, asking Buildbucket for the build status
[06:55:47.377] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:47.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:48:0) after 1m0s
[06:56:47.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:48:0)
[06:56:47.542] Timer tick, asking Buildbucket for the build status
[06:56:47.696] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:47.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:49:0) after 1m0s
[06:57:47.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:49:0)
[06:57:47.846] Timer tick, asking Buildbucket for the build status
[06:57:47.979] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:47.979] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:50:0) after 1m0s
[06:58:48.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:50:0)
[06:58:48.078] Timer tick, asking Buildbucket for the build status
[06:58:48.197] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:48.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:51:0) after 1m0s
[06:59:48.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:51:0)
[06:59:48.328] Timer tick, asking Buildbucket for the build status
[06:59:48.606] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:48.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:52:0) after 1m0s
[07:00:48.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:52:0)
[07:00:48.724] Timer tick, asking Buildbucket for the build status
[07:00:48.867] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:48.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:53:0) after 1m0s
[07:01:48.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:53:0)
[07:01:48.904] Timer tick, asking Buildbucket for the build status
[07:01:49.130] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:49.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:54:0) after 1m0s
[07:02:49.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:54:0)
[07:02:49.170] Timer tick, asking Buildbucket for the build status
[07:02:50.597] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:50.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:55:0) after 1m0s
[07:03:50.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:55:0)
[07:03:50.654] Timer tick, asking Buildbucket for the build status
[07:03:50.840] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:50.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:56:0) after 1m0s
[07:04:50.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:56:0)
[07:04:50.947] Timer tick, asking Buildbucket for the build status
[07:04:51.321] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:51.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:57:0) after 1m0s
[07:05:51.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:57:0)
[07:05:51.372] Timer tick, asking Buildbucket for the build status
[07:05:52.096] Build 8885603253223019184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:52.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051033315449439360:58:0) after 1m0s
[07:06:29.168] Received PubSub notification, asking Buildbucket for the build status
[07:06:29.333] Build 8885603253223019184: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:06:29.333] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1584428788833689",
    "created_by": "project:infra",
    "created_ts": "1584425413833567",
    "failure_reason": "BUILD_FAILURE",
    "id": "8885603253223019184",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"81.0.4044.66\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"74a792c6-86d1-40fb-9f40-cf53de556406\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"81.0.4044.66\"}}",
    "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\": \"81.0.4044.66\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"74a792c6-86d1-40fb-9f40-cf53de556406\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-bqvz\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"81.0.4044.66\"}, \"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-bqvz\"], \"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": "1584425474602434",
    "status": "COMPLETED",
    "status_changed_ts": "1584425475498772",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9051033315449439360",
      "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/8885603253223019184/+/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:4affeeb95aea9010",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1584428788907769",
    "url": "https://ci.chromium.org/b/8885603253223019184",
    "utcnow_ts": "1584428789321763"
  }
}
[07:06:29.333] Invocation finished in 56m17.006893725s with status FAILED