Status: FAILED
Triggered by: chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
Duration: 24 minutes
Actions:

Definition (rev ef1333c7bf2cf95d15a0ce92acf83d24bcd6c5ac)

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

Incoming triggers

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

Incoming properties

{
  "version": "78.0.3899.0"
}

Incoming tags

  • parent_buildername:publish_tarball
  • user_agent:recipe

Debug log (UTC)

[14:14:14.882] New invocation is queued and will start shortly
[14:14:14.882] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[14:14:16.096] Starting the invocation (attempt 1)
[14:14:16.137] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:14:16.139] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:14:16.139] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9069031658454098992",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"78.0.3899.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"73b0c3d6-dc39-4f69-bf13-6cfc4dcc39c0\",\"title\":\"publish_tarball/0\"}]},\"version\":\"78.0.3899.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:9069031658454098992",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[14:14:17.134] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:infra",
    "created_ts": "1567260856308001",
    "id": "8903601596294663392",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"78.0.3899.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"73b0c3d6-dc39-4f69-bf13-6cfc4dcc39c0\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"78.0.3899.0\"}}",
    "project": "infra",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1567260856819323",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9069031658454098992",
      "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/8903601596294663392/+/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": "1567260856820774",
    "url": "https://ci.chromium.org/b/8903601596294663392",
    "utcnow_ts": "1567260857115700"
  }
}
[14:14:17.134] Task URL: https://ci.chromium.org/b/8903601596294663392
[14:14:17.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:2:0) after 1m0s
[14:14:36.457] Received PubSub notification, asking Buildbucket for the build status
[14:14:36.574] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:17.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:2:0)
[14:15:17.262] Timer tick, asking Buildbucket for the build status
[14:15:17.422] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:17.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:4:0) after 1m0s
[14:16:17.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:4:0)
[14:16:17.452] Timer tick, asking Buildbucket for the build status
[14:16:17.624] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:17.624] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:5:0) after 1m0s
[14:17:17.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:5:0)
[14:17:17.715] Timer tick, asking Buildbucket for the build status
[14:17:18.005] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:18.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:6:0) after 1m0s
[14:18:18.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:6:0)
[14:18:18.026] Timer tick, asking Buildbucket for the build status
[14:18:18.132] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:18.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:7:0) after 1m0s
[14:19:18.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:7:0)
[14:19:18.152] Timer tick, asking Buildbucket for the build status
[14:19:18.653] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:18.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:8:0) after 1m0s
[14:20:18.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:8:0)
[14:20:18.735] Timer tick, asking Buildbucket for the build status
[14:20:19.048] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:19.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:9:0) after 1m0s
[14:21:19.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:9:0)
[14:21:19.088] Timer tick, asking Buildbucket for the build status
[14:21:19.206] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:19.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:10:0) after 1m0s
[14:22:19.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:10:0)
[14:22:19.277] Timer tick, asking Buildbucket for the build status
[14:22:19.377] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:19.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:11:0) after 1m0s
[14:23:19.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:11:0)
[14:23:19.590] Timer tick, asking Buildbucket for the build status
[14:23:19.707] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:19.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:12:0) after 1m0s
[14:24:19.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:12:0)
[14:24:19.730] Timer tick, asking Buildbucket for the build status
[14:24:20.038] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:20.038] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:13:0) after 1m0s
[14:25:20.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:13:0)
[14:25:20.046] Timer tick, asking Buildbucket for the build status
[14:25:20.240] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:20.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:14:0) after 1m0s
[14:26:20.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:14:0)
[14:26:20.262] Timer tick, asking Buildbucket for the build status
[14:26:20.367] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:20.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:15:0) after 1m0s
[14:27:20.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:15:0)
[14:27:20.362] Timer tick, asking Buildbucket for the build status
[14:27:20.483] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:20.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:16:0) after 1m0s
[14:28:20.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:16:0)
[14:28:20.555] Timer tick, asking Buildbucket for the build status
[14:28:20.744] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:20.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:17:0) after 1m0s
[14:29:20.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:17:0)
[14:29:20.823] Timer tick, asking Buildbucket for the build status
[14:29:21.124] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:21.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:18:0) after 1m0s
[14:30:21.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:18:0)
[14:30:21.160] Timer tick, asking Buildbucket for the build status
[14:30:21.334] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:21.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:19:0) after 1m0s
[14:31:21.357] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:19:0)
[14:31:21.357] Timer tick, asking Buildbucket for the build status
[14:31:21.526] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:21.526] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:20:0) after 1m0s
[14:32:21.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:20:0)
[14:32:21.626] Timer tick, asking Buildbucket for the build status
[14:32:21.754] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:21.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:21:0) after 1m0s
[14:33:21.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:21:0)
[14:33:21.825] Timer tick, asking Buildbucket for the build status
[14:33:21.995] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:21.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:22:0) after 1m0s
[14:34:22.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:22:0)
[14:34:22.048] Timer tick, asking Buildbucket for the build status
[14:34:23.312] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:23.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:23:0) after 1m0s
[14:35:23.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:23:0)
[14:35:23.381] Timer tick, asking Buildbucket for the build status
[14:35:23.494] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:23.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:24:0) after 1m0s
[14:36:23.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:24:0)
[14:36:23.530] Timer tick, asking Buildbucket for the build status
[14:36:23.661] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:23.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:25:0) after 1m0s
[14:37:23.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:25:0)
[14:37:23.716] Timer tick, asking Buildbucket for the build status
[14:37:23.808] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:23.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:26:0) after 1m0s
[14:38:23.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:26:0)
[14:38:23.886] Timer tick, asking Buildbucket for the build status
[14:38:24.208] Build 8903601596294663392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:24.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9069031658454098992:27:0) after 1m0s
[14:39:05.354] Received PubSub notification, asking Buildbucket for the build status
[14:39:05.490] Build 8903601596294663392: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:39:05.490] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1567262344684257",
    "created_by": "project:infra",
    "created_ts": "1567260856308001",
    "failure_reason": "BUILD_FAILURE",
    "id": "8903601596294663392",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"78.0.3899.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"73b0c3d6-dc39-4f69-bf13-6cfc4dcc39c0\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"78.0.3899.0\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('Download nodejs.') (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\": \"78.0.3899.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"73b0c3d6-dc39-4f69-bf13-6cfc4dcc39c0\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-5n7h\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"78.0.3899.0\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-5n7h\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"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\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Download nodejs.') (retcode: 1)\"}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1567260875111194",
    "status": "COMPLETED",
    "status_changed_ts": "1567260875980583",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9069031658454098992",
      "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/8903601596294663392/+/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:4700d85bd264e610",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1567262344767430",
    "url": "https://ci.chromium.org/b/8903601596294663392",
    "utcnow_ts": "1567262345474491"
  }
}
[14:39:05.490] Invocation finished in 24m50.624457268s with status FAILED