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

• parent_buildername:publish_tarball
• user_agent:recipe

[02:23:28.197] New invocation is queued and will start shortly
[02:23:28.197] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[02:23:29.475] Starting the invocation (attempt 1)
[02:23:29.511] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:23:29.511] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:23:29.511] Buildbucket request:
{
  "bucket": "luci.infra.cron",
  "client_operation_id": "9095349497002463504",
  "parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"72.0.3608.5\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Build From Tarball",
    "scheduler_invocation_id:9095349497002463504",
    "scheduler_job_id:infra/Build From Tarball",
    "user_agent:luci-scheduler",
    "parent_buildername:publish_tarball",
    "user_agent:recipe"
  ]
}
[02:23:31.188] Buildbucket response:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1542162209670173",
    "id": "8929919434791104000",
    "lease_expiration_ts": "1542281011077107",
    "lease_key": "953034075",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3608.5\"}}",
    "project": "infra",
    "result_details_json": "null",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1542162211077912",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9095349497002463504",
      "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:8929919434791104000",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:392c1547cd3d35e7fd3a5e1012873ccdb987dbae",
      "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/8929919434791104000/+/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:9095349497002463504",
      "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:cc717e324fb9f1e62aafe03a86d1b8c4ef9cf063",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4128d9670837c010",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1542162211077979",
    "url": "https://ci.chromium.org/b/8929919434791104000",
    "utcnow_ts": "1542162211167824"
  }
}
[02:23:31.189] Task URL: https://ci.chromium.org/b/8929919434791104000
[02:23:31.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:2:0) after 1m0s
[02:24:24.537] Received PubSub notification, asking Buildbucket for the build status
[02:24:24.745] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:31.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:2:0)
[02:24:31.205] Timer tick, asking Buildbucket for the build status
[02:24:31.266] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:31.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:4:0) after 1m0s
[02:25:31.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:4:0)
[02:25:31.294] Timer tick, asking Buildbucket for the build status
[02:25:31.354] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:31.354] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:5:0) after 1m0s
[02:26:31.370] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:5:0)
[02:26:31.370] Timer tick, asking Buildbucket for the build status
[02:26:31.409] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:31.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:6:0) after 1m0s
[02:27:31.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:6:0)
[02:27:31.430] Timer tick, asking Buildbucket for the build status
[02:27:31.618] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:31.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:7:0) after 1m0s
[02:28:31.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:7:0)
[02:28:31.634] Timer tick, asking Buildbucket for the build status
[02:28:31.672] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:31.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:8:0) after 1m0s
[02:29:31.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:8:0)
[02:29:31.807] Timer tick, asking Buildbucket for the build status
[02:29:31.889] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:31.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:9:0) after 1m0s
[02:30:32.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:9:0)
[02:30:32.097] Timer tick, asking Buildbucket for the build status
[02:30:32.170] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:32.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:10:0) after 1m0s
[02:31:32.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:10:0)
[02:31:32.235] Timer tick, asking Buildbucket for the build status
[02:31:32.295] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:32.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:11:0) after 1m0s
[02:32:32.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:11:0)
[02:32:32.359] Timer tick, asking Buildbucket for the build status
[02:32:32.401] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:32.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:12:0) after 1m0s
[02:33:32.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:12:0)
[02:33:32.477] Timer tick, asking Buildbucket for the build status
[02:33:32.570] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:32.570] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:13:0) after 1m0s
[02:34:32.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:13:0)
[02:34:32.593] Timer tick, asking Buildbucket for the build status
[02:34:32.863] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:32.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:14:0) after 1m0s
[02:35:32.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:14:0)
[02:35:32.897] Timer tick, asking Buildbucket for the build status
[02:35:32.967] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:32.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:15:0) after 1m0s
[02:36:32.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:15:0)
[02:36:32.984] Timer tick, asking Buildbucket for the build status
[02:36:33.021] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:33.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:16:0) after 1m0s
[02:37:33.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:16:0)
[02:37:33.048] Timer tick, asking Buildbucket for the build status
[02:37:33.208] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:33.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:17:0) after 1m0s
[02:38:33.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:17:0)
[02:38:33.240] Timer tick, asking Buildbucket for the build status
[02:38:33.319] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:33.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:18:0) after 1m0s
[02:39:33.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:18:0)
[02:39:33.336] Timer tick, asking Buildbucket for the build status
[02:39:35.566] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:35.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:19:0) after 1m0s
[02:40:35.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:19:0)
[02:40:35.585] Timer tick, asking Buildbucket for the build status
[02:40:35.651] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:35.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:20:0) after 1m0s
[02:41:35.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:20:0)
[02:41:35.669] Timer tick, asking Buildbucket for the build status
[02:41:35.726] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:35.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:21:0) after 1m0s
[02:42:35.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:21:0)
[02:42:35.742] Timer tick, asking Buildbucket for the build status
[02:42:35.822] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:35.822] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:22:0) after 1m0s
[02:43:35.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:22:0)
[02:43:35.839] Timer tick, asking Buildbucket for the build status
[02:43:35.949] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:35.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:23:0) after 1m0s
[02:44:35.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:23:0)
[02:44:35.969] Timer tick, asking Buildbucket for the build status
[02:44:36.030] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:36.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:24:0) after 1m0s
[02:45:36.076] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:24:0)
[02:45:36.076] Timer tick, asking Buildbucket for the build status
[02:45:36.131] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:36.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:25:0) after 1m0s
[02:46:36.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:25:0)
[02:46:36.147] Timer tick, asking Buildbucket for the build status
[02:46:36.226] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:36.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:26:0) after 1m0s
[02:47:36.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:26:0)
[02:47:36.244] Timer tick, asking Buildbucket for the build status
[02:47:36.319] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:36.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:27:0) after 1m0s
[02:48:36.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:27:0)
[02:48:36.361] Timer tick, asking Buildbucket for the build status
[02:48:36.400] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:36.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:28:0) after 1m0s
[02:49:36.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:28:0)
[02:49:36.416] Timer tick, asking Buildbucket for the build status
[02:49:36.524] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:36.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:29:0) after 1m0s
[02:50:36.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:29:0)
[02:50:36.542] Timer tick, asking Buildbucket for the build status
[02:50:36.617] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:36.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:30:0) after 1m0s
[02:51:36.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:30:0)
[02:51:36.635] Timer tick, asking Buildbucket for the build status
[02:51:36.703] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:36.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:31:0) after 1m0s
[02:52:36.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:31:0)
[02:52:36.887] Timer tick, asking Buildbucket for the build status
[02:52:36.923] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:36.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:32:0) after 1m0s
[02:53:36.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:32:0)
[02:53:36.942] Timer tick, asking Buildbucket for the build status
[02:53:37.008] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:37.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:33:0) after 1m0s
[02:54:37.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:33:0)
[02:54:37.027] Timer tick, asking Buildbucket for the build status
[02:54:37.058] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:37.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:34:0) after 1m0s
[02:55:37.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:34:0)
[02:55:37.079] Timer tick, asking Buildbucket for the build status
[02:55:37.138] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:37.139] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:35:0) after 1m0s
[02:56:37.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:35:0)
[02:56:37.178] Timer tick, asking Buildbucket for the build status
[02:56:37.233] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:37.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:36:0) after 1m0s
[02:57:37.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:36:0)
[02:57:37.263] Timer tick, asking Buildbucket for the build status
[02:57:37.341] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:37.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:37:0) after 1m0s
[02:58:37.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:37:0)
[02:58:37.360] Timer tick, asking Buildbucket for the build status
[02:58:37.397] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:37.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:38:0) after 1m0s
[02:59:37.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:38:0)
[02:59:37.451] Timer tick, asking Buildbucket for the build status
[02:59:37.516] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:37.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:39:0) after 1m0s
[03:00:37.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:39:0)
[03:00:37.545] Timer tick, asking Buildbucket for the build status
[03:00:37.653] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:37.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:40:0) after 1m0s
[03:01:37.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:40:0)
[03:01:37.670] Timer tick, asking Buildbucket for the build status
[03:01:37.702] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:37.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:41:0) after 1m0s
[03:02:37.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:41:0)
[03:02:37.760] Timer tick, asking Buildbucket for the build status
[03:02:37.824] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:37.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:42:0) after 1m0s
[03:03:37.851] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:42:0)
[03:03:37.851] Timer tick, asking Buildbucket for the build status
[03:03:37.890] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:37.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:43:0) after 1m0s
[03:04:37.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:43:0)
[03:04:37.919] Timer tick, asking Buildbucket for the build status
[03:04:37.980] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:37.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:44:0) after 1m0s
[03:05:37.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:44:0)
[03:05:37.998] Timer tick, asking Buildbucket for the build status
[03:05:38.034] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:38.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:45:0) after 1m0s
[03:06:38.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:45:0)
[03:06:38.054] Timer tick, asking Buildbucket for the build status
[03:06:38.141] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:38.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:46:0) after 1m0s
[03:07:38.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:46:0)
[03:07:38.203] Timer tick, asking Buildbucket for the build status
[03:07:38.273] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:38.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:47:0) after 1m0s
[03:08:38.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:47:0)
[03:08:38.311] Timer tick, asking Buildbucket for the build status
[03:08:38.445] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:38.445] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:48:0) after 1m0s
[03:09:38.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:48:0)
[03:09:38.471] Timer tick, asking Buildbucket for the build status
[03:09:38.530] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:38.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:49:0) after 1m0s
[03:10:38.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:49:0)
[03:10:38.551] Timer tick, asking Buildbucket for the build status
[03:10:38.599] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:38.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:50:0) after 1m0s
[03:11:38.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:50:0)
[03:11:38.662] Timer tick, asking Buildbucket for the build status
[03:11:38.907] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:38.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:51:0) after 1m0s
[03:12:38.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:51:0)
[03:12:38.929] Timer tick, asking Buildbucket for the build status
[03:12:39.094] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:39.094] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:52:0) after 1m0s
[03:13:39.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:52:0)
[03:13:39.124] Timer tick, asking Buildbucket for the build status
[03:13:39.176] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:39.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:53:0) after 1m0s
[03:14:39.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:53:0)
[03:14:39.197] Timer tick, asking Buildbucket for the build status
[03:14:39.267] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:39.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:54:0) after 1m0s
[03:15:39.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:54:0)
[03:15:39.287] Timer tick, asking Buildbucket for the build status
[03:15:39.452] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:39.452] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:55:0) after 1m0s
[03:16:39.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:55:0)
[03:16:39.482] Timer tick, asking Buildbucket for the build status
[03:16:39.648] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:39.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:56:0) after 1m0s
[03:17:39.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:56:0)
[03:17:39.668] Timer tick, asking Buildbucket for the build status
[03:17:39.752] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:39.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:57:0) after 1m0s
[03:18:39.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:57:0)
[03:18:39.772] Timer tick, asking Buildbucket for the build status
[03:18:39.810] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:39.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:58:0) after 1m0s
[03:19:39.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:58:0)
[03:19:39.826] Timer tick, asking Buildbucket for the build status
[03:19:39.870] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:39.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:59:0) after 1m0s
[03:20:39.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:59:0)
[03:20:39.888] Timer tick, asking Buildbucket for the build status
[03:20:40.044] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:40.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:60:0) after 1m0s
[03:21:40.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:60:0)
[03:21:40.063] Timer tick, asking Buildbucket for the build status
[03:21:40.170] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:40.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:61:0) after 1m0s
[03:22:40.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:61:0)
[03:22:40.204] Timer tick, asking Buildbucket for the build status
[03:22:40.282] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:40.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:62:0) after 1m0s
[03:23:40.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:62:0)
[03:23:40.302] Timer tick, asking Buildbucket for the build status
[03:23:40.443] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:40.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:63:0) after 1m0s
[03:24:40.462] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:63:0)
[03:24:40.462] Timer tick, asking Buildbucket for the build status
[03:24:40.515] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:40.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:64:0) after 1m0s
[03:25:40.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:64:0)
[03:25:40.536] Timer tick, asking Buildbucket for the build status
[03:25:40.805] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:40.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:65:0) after 1m0s
[03:26:40.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:65:0)
[03:26:40.846] Timer tick, asking Buildbucket for the build status
[03:26:40.993] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:40.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:66:0) after 1m0s
[03:27:41.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:66:0)
[03:27:41.015] Timer tick, asking Buildbucket for the build status
[03:27:41.157] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:41.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:67:0) after 1m0s
[03:28:41.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:67:0)
[03:28:41.183] Timer tick, asking Buildbucket for the build status
[03:28:41.328] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:41.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:68:0) after 1m0s
[03:29:41.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:68:0)
[03:29:41.403] Timer tick, asking Buildbucket for the build status
[03:29:41.448] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:41.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:69:0) after 1m0s
[03:30:41.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:69:0)
[03:30:41.484] Timer tick, asking Buildbucket for the build status
[03:30:41.663] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:41.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:70:0) after 1m0s
[03:31:41.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:70:0)
[03:31:41.687] Timer tick, asking Buildbucket for the build status
[03:31:41.831] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:41.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:71:0) after 1m0s
[03:32:41.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:71:0)
[03:32:41.853] Timer tick, asking Buildbucket for the build status
[03:32:41.887] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:41.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:72:0) after 1m0s
[03:33:41.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:72:0)
[03:33:41.911] Timer tick, asking Buildbucket for the build status
[03:33:41.975] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:41.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:73:0) after 1m0s
[03:34:41.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:73:0)
[03:34:41.995] Timer tick, asking Buildbucket for the build status
[03:34:42.079] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:42.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:74:0) after 1m0s
[03:35:42.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:74:0)
[03:35:42.153] Timer tick, asking Buildbucket for the build status
[03:35:42.213] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:42.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:75:0) after 1m0s
[03:36:42.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:75:0)
[03:36:42.288] Timer tick, asking Buildbucket for the build status
[03:36:42.359] Build 8929919434791104000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:42.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9095349497002463504:76:0) after 1m0s
[03:37:07.439] Received PubSub notification, asking Buildbucket for the build status
[03:37:07.519] Build 8929919434791104000: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:37:07.519] Buildbucket build:
{
  "build": {
    "bucket": "luci.infra.cron",
    "canary_preference": "AUTO",
    "completed_ts": "1542166626114084",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1542162209670173",
    "failure_reason": "BUILD_FAILURE",
    "id": "8929919434791104000",
    "parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3608.5\"}}",
    "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-west\", \"us-west1\", \"us-west1-c\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-c-hnkl\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"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\": 1542162209670173, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9095349497002463504\", \"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\": \"8929919434791104000\"}}, \"version\": \"72.0.3608.5\", \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-c-hnkl\"}}",
    "service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1542162263328465",
    "status": "COMPLETED",
    "status_changed_ts": "1542166626833877",
    "tags": [
      "builder:Build From Tarball",
      "parent_buildername:publish_tarball",
      "scheduler_invocation_id:9095349497002463504",
      "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:8929919434791104000",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:392c1547cd3d35e7fd3a5e1012873ccdb987dbae",
      "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/8929919434791104000/+/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:9095349497002463504",
      "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:cc717e324fb9f1e62aafe03a86d1b8c4ef9cf063",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:user_agent:recipe",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4128d9670837c010",
      "user_agent:luci-scheduler",
      "user_agent:recipe"
    ],
    "updated_ts": "1542166626835323",
    "url": "https://ci.chromium.org/b/8929919434791104000",
    "utcnow_ts": "1542166627509838"
  }
}
[03:37:07.519] Invocation finished in 1h13m39.341433958s with status FAILED