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

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

{
  "version": "72.0.3615.0"
}

• parent_buildername:publish_tarball
• user_agent:recipe

[10:44:42.229] New invocation is queued and will start shortly
[10:44:42.229] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:44:43.489] Starting the invocation (attempt 1)
[10:44:43.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:44:43.522] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:44:43.522] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9094864977262169664",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"72.0.3615.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:9094864977262169664",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[10:44:45.322] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1542624283623626",
    "id": "8929434915133491680",
    "lease_expiration_ts": "1542743085135205",
    "lease_key": "1986206121",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3615.0\"}}",
    "project": "infra",
    "result_details_json": "null",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1542624285138017",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9094864977262169664",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_dimension:builder:Build From Tarball",
      "swarming_dimension:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.infra.cron",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:buildbucket_bucket:infra/cron",
      "swarming_tag:buildbucket_build_id:8929434915133491680",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:da5491be675c79d05750cef9051038aa905ddaff",
      "swarming_tag:builder:Build From Tarball",
      "swarming_tag:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8929434915133491680/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:parent_buildername:publish_tarball",
      "swarming_tag:pool:luci.infra.cron",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_tag:scheduler_invocation_id:9094864977262169664",
      "swarming_tag:scheduler_job_id:infra/Build From Tarball",
      "swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:7f5ce4d7633892d1ef2fcf6c79071652be2c4013",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:414464171bd78810",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1542624285138198",
    "url": "https://ci.chromium.org/b/8929434915133491680",
    "utcnow_ts": "1542624285309487"
  }
}
[10:44:45.322] Task URL: https://ci.chromium.org/b/8929434915133491680
[10:44:45.322] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:2:0) after 1m0s
[10:45:35.908] Received PubSub notification, asking Buildbucket for the build status
[10:45:35.953] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:45.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:2:0)
[10:45:45.344] Timer tick, asking Buildbucket for the build status
[10:45:45.431] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:45.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:4:0) after 1m0s
[10:46:45.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:4:0)
[10:46:45.450] Timer tick, asking Buildbucket for the build status
[10:46:45.509] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:45.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:5:0) after 1m0s
[10:47:45.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:5:0)
[10:47:45.524] Timer tick, asking Buildbucket for the build status
[10:47:45.650] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:45.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:6:0) after 1m0s
[10:48:45.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:6:0)
[10:48:45.678] Timer tick, asking Buildbucket for the build status
[10:48:45.813] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:45.813] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:7:0) after 1m0s
[10:49:45.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:7:0)
[10:49:45.852] Timer tick, asking Buildbucket for the build status
[10:49:45.888] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:45.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:8:0) after 1m0s
[10:50:45.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:8:0)
[10:50:45.906] Timer tick, asking Buildbucket for the build status
[10:50:45.965] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:45.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:9:0) after 1m0s
[10:51:45.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:9:0)
[10:51:45.984] Timer tick, asking Buildbucket for the build status
[10:51:46.064] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:46.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:10:0) after 1m0s
[10:52:46.161] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:10:0)
[10:52:46.161] Timer tick, asking Buildbucket for the build status
[10:52:46.326] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:46.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:11:0) after 1m0s
[10:53:46.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:11:0)
[10:53:46.346] Timer tick, asking Buildbucket for the build status
[10:53:46.437] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:46.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:12:0) after 1m0s
[10:54:46.466] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:12:0)
[10:54:46.466] Timer tick, asking Buildbucket for the build status
[10:54:46.640] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:46.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:13:0) after 1m0s
[10:55:46.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:13:0)
[10:55:46.662] Timer tick, asking Buildbucket for the build status
[10:55:46.697] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:46.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:14:0) after 1m0s
[10:56:46.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:14:0)
[10:56:46.720] Timer tick, asking Buildbucket for the build status
[10:56:46.815] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:46.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:15:0) after 1m0s
[10:57:46.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:15:0)
[10:57:46.837] Timer tick, asking Buildbucket for the build status
[10:57:46.893] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:46.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:16:0) after 1m0s
[10:58:46.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:16:0)
[10:58:46.913] Timer tick, asking Buildbucket for the build status
[10:58:47.028] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:47.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:17:0) after 1m0s
[10:59:47.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:17:0)
[10:59:47.047] Timer tick, asking Buildbucket for the build status
[10:59:47.107] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:47.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:18:0) after 1m0s
[11:00:47.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:18:0)
[11:00:47.131] Timer tick, asking Buildbucket for the build status
[11:00:47.199] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:47.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:19:0) after 1m0s
[11:01:47.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:19:0)
[11:01:47.215] Timer tick, asking Buildbucket for the build status
[11:01:47.441] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:47.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:20:0) after 1m0s
[11:02:47.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:20:0)
[11:02:47.460] Timer tick, asking Buildbucket for the build status
[11:02:47.583] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:47.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:21:0) after 1m0s
[11:03:47.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:21:0)
[11:03:47.609] Timer tick, asking Buildbucket for the build status
[11:03:47.647] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:47.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:22:0) after 1m0s
[11:04:47.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:22:0)
[11:04:47.662] Timer tick, asking Buildbucket for the build status
[11:04:47.860] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:47.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:23:0) after 1m0s
[11:05:47.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:23:0)
[11:05:47.876] Timer tick, asking Buildbucket for the build status
[11:05:47.918] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:47.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:24:0) after 1m0s
[11:06:47.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:24:0)
[11:06:47.940] Timer tick, asking Buildbucket for the build status
[11:06:48.025] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:48.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:25:0) after 1m0s
[11:07:48.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:25:0)
[11:07:48.045] Timer tick, asking Buildbucket for the build status
[11:07:48.078] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:48.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:26:0) after 1m0s
[11:08:48.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:26:0)
[11:08:48.110] Timer tick, asking Buildbucket for the build status
[11:08:48.252] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:48.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:27:0) after 1m0s
[11:09:48.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:27:0)
[11:09:48.269] Timer tick, asking Buildbucket for the build status
[11:09:48.324] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:48.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:28:0) after 1m0s
[11:10:48.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:28:0)
[11:10:48.341] Timer tick, asking Buildbucket for the build status
[11:10:48.381] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:48.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:29:0) after 1m0s
[11:11:48.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:29:0)
[11:11:48.409] Timer tick, asking Buildbucket for the build status
[11:11:48.444] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:48.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:30:0) after 1m0s
[11:12:48.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:30:0)
[11:12:48.465] Timer tick, asking Buildbucket for the build status
[11:12:48.622] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:48.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:31:0) after 1m0s
[11:13:48.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:31:0)
[11:13:48.638] Timer tick, asking Buildbucket for the build status
[11:13:48.677] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:48.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:32:0) after 1m0s
[11:14:48.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:32:0)
[11:14:48.697] Timer tick, asking Buildbucket for the build status
[11:14:48.780] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:48.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:33:0) after 1m0s
[11:15:48.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:33:0)
[11:15:48.800] Timer tick, asking Buildbucket for the build status
[11:15:48.874] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:48.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:34:0) after 1m0s
[11:16:48.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:34:0)
[11:16:48.890] Timer tick, asking Buildbucket for the build status
[11:16:49.012] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:49.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:35:0) after 1m0s
[11:17:49.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:35:0)
[11:17:49.032] Timer tick, asking Buildbucket for the build status
[11:17:49.088] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:49.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:36:0) after 1m0s
[11:18:49.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:36:0)
[11:18:49.115] Timer tick, asking Buildbucket for the build status
[11:18:49.153] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:49.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:37:0) after 1m0s
[11:19:49.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:37:0)
[11:19:49.175] Timer tick, asking Buildbucket for the build status
[11:19:49.323] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:49.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:38:0) after 1m0s
[11:20:49.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:38:0)
[11:20:49.342] Timer tick, asking Buildbucket for the build status
[11:20:49.380] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:49.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:39:0) after 1m0s
[11:21:49.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:39:0)
[11:21:49.432] Timer tick, asking Buildbucket for the build status
[11:21:49.504] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:49.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:40:0) after 1m0s
[11:22:49.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:40:0)
[11:22:49.529] Timer tick, asking Buildbucket for the build status
[11:22:49.564] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:49.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:41:0) after 1m0s
[11:23:49.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:41:0)
[11:23:49.596] Timer tick, asking Buildbucket for the build status
[11:23:49.708] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:49.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:42:0) after 1m0s
[11:24:49.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:42:0)
[11:24:49.734] Timer tick, asking Buildbucket for the build status
[11:24:49.777] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:49.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:43:0) after 1m0s
[11:25:52.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:43:0)
[11:25:52.126] Timer tick, asking Buildbucket for the build status
[11:25:52.378] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:52.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:44:0) after 1m0s
[11:26:52.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:44:0)
[11:26:52.470] Timer tick, asking Buildbucket for the build status
[11:26:52.518] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:52.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:45:0) after 1m0s
[11:27:52.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:45:0)
[11:27:52.561] Timer tick, asking Buildbucket for the build status
[11:27:52.833] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:52.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:46:0) after 1m0s
[11:28:52.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:46:0)
[11:28:52.862] Timer tick, asking Buildbucket for the build status
[11:28:52.927] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:52.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:47:0) after 1m0s
[11:29:53.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:47:0)
[11:29:53.025] Timer tick, asking Buildbucket for the build status
[11:29:53.089] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:53.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:48:0) after 1m0s
[11:30:53.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:48:0)
[11:30:53.111] Timer tick, asking Buildbucket for the build status
[11:30:53.146] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:53.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:49:0) after 1m0s
[11:31:53.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:49:0)
[11:31:53.188] Timer tick, asking Buildbucket for the build status
[11:31:53.363] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:53.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:50:0) after 1m0s
[11:32:53.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:50:0)
[11:32:53.382] Timer tick, asking Buildbucket for the build status
[11:32:53.451] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:53.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:51:0) after 1m0s
[11:33:53.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:51:0)
[11:33:53.472] Timer tick, asking Buildbucket for the build status
[11:33:53.513] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:53.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:52:0) after 1m0s
[11:34:53.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:52:0)
[11:34:53.549] Timer tick, asking Buildbucket for the build status
[11:34:53.604] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:53.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:53:0) after 1m0s
[11:35:53.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:53:0)
[11:35:53.625] Timer tick, asking Buildbucket for the build status
[11:35:53.702] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:53.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:54:0) after 1m0s
[11:36:53.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:54:0)
[11:36:53.724] Timer tick, asking Buildbucket for the build status
[11:36:53.781] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:53.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:55:0) after 1m0s
[11:37:53.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:55:0)
[11:37:53.803] Timer tick, asking Buildbucket for the build status
[11:37:53.844] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:53.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:56:0) after 1m0s
[11:38:53.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:56:0)
[11:38:53.865] Timer tick, asking Buildbucket for the build status
[11:38:53.919] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:53.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:57:0) after 1m0s
[11:39:53.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:57:0)
[11:39:53.955] Timer tick, asking Buildbucket for the build status
[11:39:54.034] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:54.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:58:0) after 1m0s
[11:40:54.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:58:0)
[11:40:54.058] Timer tick, asking Buildbucket for the build status
[11:40:54.095] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:54.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:59:0) after 1m0s
[11:41:54.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:59:0)
[11:41:54.120] Timer tick, asking Buildbucket for the build status
[11:41:54.318] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:54.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:60:0) after 1m0s
[11:42:54.338] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:60:0)
[11:42:54.338] Timer tick, asking Buildbucket for the build status
[11:42:54.392] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:54.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:61:0) after 1m0s
[11:43:54.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:61:0)
[11:43:54.409] Timer tick, asking Buildbucket for the build status
[11:43:54.537] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:54.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:62:0) after 1m0s
[11:44:54.601] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:62:0)
[11:44:54.601] Timer tick, asking Buildbucket for the build status
[11:44:54.781] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:54.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:63:0) after 1m0s
[11:45:54.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:63:0)
[11:45:54.821] Timer tick, asking Buildbucket for the build status
[11:45:54.857] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:54.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:64:0) after 1m0s
[11:46:54.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:64:0)
[11:46:54.896] Timer tick, asking Buildbucket for the build status
[11:46:54.956] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:54.956] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:65:0) after 1m0s
[11:47:54.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:65:0)
[11:47:54.975] Timer tick, asking Buildbucket for the build status
[11:47:55.025] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:55.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:66:0) after 1m0s
[11:48:55.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:66:0)
[11:48:55.054] Timer tick, asking Buildbucket for the build status
[11:48:55.127] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:55.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:67:0) after 1m0s
[11:49:55.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:67:0)
[11:49:55.148] Timer tick, asking Buildbucket for the build status
[11:49:55.311] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:55.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:68:0) after 1m0s
[11:50:55.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:68:0)
[11:50:55.332] Timer tick, asking Buildbucket for the build status
[11:50:55.402] Build 8929434915133491680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:55.402] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9094864977262169664:69:0) after 1m0s
[11:51:29.697] Received PubSub notification, asking Buildbucket for the build status
[11:51:29.751] Build 8929434915133491680: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:51:29.751] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "completed_ts": "1542628288550218",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1542624283623626",
    "failure_reason": "BUILD_FAILURE",
    "id": "8929434915133491680",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3615.0\"}}",
    "project": "infra",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure Bootstrap gn.\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3844-124e68f\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-east1-b-8hgd\"], \"pool\": [\"luci.infra.cron\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1542624283623626, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9094864977262169664\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8929434915133491680\"}}, \"version\": \"72.0.3615.0\", \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-east1-b-8hgd\"}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1542624335282004",
    "status": "COMPLETED",
    "status_changed_ts": "1542628289500188",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9094864977262169664",
      "scheduler_job_id:infra/Build From Tarball",
      "swarming_dimension:builder:Build From Tarball",
      "swarming_dimension:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.infra.cron",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:buildbucket_bucket:infra/cron",
      "swarming_tag:buildbucket_build_id:8929434915133491680",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:da5491be675c79d05750cef9051038aa905ddaff",
      "swarming_tag:builder:Build From Tarball",
      "swarming_tag:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8929434915133491680/+/annotations",
      "swarming_tag:luci_project:infra",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:parent_buildername:publish_tarball",
      "swarming_tag:pool:luci.infra.cron",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:build_from_tarball",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
      "swarming_tag:scheduler_invocation_id:9094864977262169664",
      "swarming_tag:scheduler_job_id:infra/Build From Tarball",
      "swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:7f5ce4d7633892d1ef2fcf6c79071652be2c4013",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:414464171bd78810",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1542628289501335",
    "url": "https://ci.chromium.org/b/8929434915133491680",
    "utcnow_ts": "1542628289732752"
  }
}
[11:51:29.751] Invocation finished in 1h6m47.541486068s with status FAILED