Status: FAILED
Triggered by: -
Duration: 1 hour
Actions:

Definition (rev 35d21627f955de090fe1b76c0480f8a1d2bd46d5)

buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.celab.ci"
  builder: "Linux"
>

Incoming triggers

Incoming properties

{
  "branch": "refs/heads/master",
  "repository": "https://chromium.googlesource.com/enterprise/cel.git",
  "revision": "49a41388829dc842d0fca7d200be88d47f5468f3"
}

Incoming tags

  • buildset:commit/git/49a41388829dc842d0fca7d200be88d47f5468f3
  • buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/49a41388829dc842d0fca7d200be88d47f5468f3
  • gitiles_ref:refs/heads/master

Debug log (UTC)

[15:37:02.276] New invocation is queued and will start shortly
[15:37:03.405] Starting the invocation (attempt 1)
[15:37:03.472] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:37:03.472] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:37:03.472] Buildbucket request:
{
  "bucket": "luci.celab.ci",
  "client_operation_id": "9081528831135981072",
  "parameters_json": "{\"builder_name\":\"Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/enterprise/cel.git\",\"revision\":\"49a41388829dc842d0fca7d200be88d47f5468f3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Linux",
    "scheduler_invocation_id:9081528831135981072",
    "scheduler_job_id:celab/Linux",
    "user_agent:luci-scheduler",
    "buildset:commit/git/49a41388829dc842d0fca7d200be88d47f5468f3",
    "buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/49a41388829dc842d0fca7d200be88d47f5468f3",
    "gitiles_ref:refs/heads/master"
  ]
}
[15:37:04.080] Buildbucket response:
{
  "build": {
    "bucket": "luci.celab.ci",
    "canary_preference": "AUTO",
    "created_by": "project:celab",
    "created_ts": "1555342623541154",
    "id": "8916098769136103376",
    "parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"49a41388829dc842d0fca7d200be88d47f5468f3\"}}",
    "project": "celab",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1555342623896545",
    "tags": [
      "builder:Linux",
      "buildset:commit/git/49a41388829dc842d0fca7d200be88d47f5468f3",
      "buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/49a41388829dc842d0fca7d200be88d47f5468f3",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9081528831135981072",
      "scheduler_job_id:celab/Linux",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8916098769136103376/+/annotations",
      "swarming_tag:luci_project:celab",
      "swarming_tag:recipe_name:celab",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1555342623896647",
    "url": "https://ci.chromium.org/b/8916098769136103376",
    "utcnow_ts": "1555342624067127"
  }
}
[15:37:04.080] Task URL: https://ci.chromium.org/b/8916098769136103376
[15:37:04.080] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:2:0) after 1m0s
[15:37:52.830] Received PubSub notification, asking Buildbucket for the build status
[15:37:52.876] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:04.124] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:2:0)
[15:38:04.124] Timer tick, asking Buildbucket for the build status
[15:38:04.179] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:04.179] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:4:0) after 1m0s
[15:39:04.199] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:4:0)
[15:39:04.199] Timer tick, asking Buildbucket for the build status
[15:39:04.587] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:04.587] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:5:0) after 1m0s
[15:40:04.608] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:5:0)
[15:40:04.608] Timer tick, asking Buildbucket for the build status
[15:40:04.748] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:04.748] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:6:0) after 1m0s
[15:41:04.836] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:6:0)
[15:41:04.836] Timer tick, asking Buildbucket for the build status
[15:41:05.299] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:05.299] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:7:0) after 1m0s
[15:42:05.323] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:7:0)
[15:42:05.323] Timer tick, asking Buildbucket for the build status
[15:42:05.392] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:05.392] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:8:0) after 1m0s
[15:43:05.409] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:8:0)
[15:43:05.409] Timer tick, asking Buildbucket for the build status
[15:43:05.513] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:05.513] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:9:0) after 1m0s
[15:44:05.534] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:9:0)
[15:44:05.534] Timer tick, asking Buildbucket for the build status
[15:44:05.622] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:05.622] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:10:0) after 1m0s
[15:45:05.639] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:10:0)
[15:45:05.639] Timer tick, asking Buildbucket for the build status
[15:45:05.721] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:05.721] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:11:0) after 1m0s
[15:46:05.736] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:11:0)
[15:46:05.736] Timer tick, asking Buildbucket for the build status
[15:46:06.723] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:06.723] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:12:0) after 1m0s
[15:47:06.740] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:12:0)
[15:47:06.740] Timer tick, asking Buildbucket for the build status
[15:47:06.796] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:06.796] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:13:0) after 1m0s
[15:48:06.814] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:13:0)
[15:48:06.814] Timer tick, asking Buildbucket for the build status
[15:48:06.866] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:06.866] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:14:0) after 1m0s
[15:49:06.938] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:14:0)
[15:49:06.938] Timer tick, asking Buildbucket for the build status
[15:49:07.119] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:07.119] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:15:0) after 1m0s
[15:50:07.139] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:15:0)
[15:50:07.139] Timer tick, asking Buildbucket for the build status
[15:50:07.215] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:07.215] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:16:0) after 1m0s
[15:51:07.216] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:16:0)
[15:51:07.216] Timer tick, asking Buildbucket for the build status
[15:51:07.385] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:07.385] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:17:0) after 1m0s
[15:52:07.404] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:17:0)
[15:52:07.404] Timer tick, asking Buildbucket for the build status
[15:52:07.516] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:07.516] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:18:0) after 1m0s
[15:53:07.536] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:18:0)
[15:53:07.536] Timer tick, asking Buildbucket for the build status
[15:53:07.604] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:07.604] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:19:0) after 1m0s
[15:54:07.626] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:19:0)
[15:54:07.626] Timer tick, asking Buildbucket for the build status
[15:54:07.686] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:07.686] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:20:0) after 1m0s
[15:55:07.744] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:20:0)
[15:55:07.744] Timer tick, asking Buildbucket for the build status
[15:55:07.857] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:07.857] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:21:0) after 1m0s
[15:56:07.874] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:21:0)
[15:56:07.874] Timer tick, asking Buildbucket for the build status
[15:56:07.985] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:07.985] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:22:0) after 1m0s
[15:57:07.992] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:22:0)
[15:57:07.992] Timer tick, asking Buildbucket for the build status
[15:57:08.056] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:08.056] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:23:0) after 1m0s
[15:58:08.035] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:23:0)
[15:58:08.035] Timer tick, asking Buildbucket for the build status
[15:58:08.149] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:08.149] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:24:0) after 1m0s
[15:59:08.170] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:24:0)
[15:59:08.170] Timer tick, asking Buildbucket for the build status
[15:59:08.232] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:08.232] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:25:0) after 1m0s
[16:00:08.251] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:25:0)
[16:00:08.251] Timer tick, asking Buildbucket for the build status
[16:00:08.368] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:08.368] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:26:0) after 1m0s
[16:01:08.402] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:26:0)
[16:01:08.402] Timer tick, asking Buildbucket for the build status
[16:01:08.475] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:08.475] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:27:0) after 1m0s
[16:02:08.498] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:27:0)
[16:02:08.498] Timer tick, asking Buildbucket for the build status
[16:02:08.546] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:08.546] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:28:0) after 1m0s
[16:03:08.553] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:28:0)
[16:03:08.553] Timer tick, asking Buildbucket for the build status
[16:03:08.616] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:08.616] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:29:0) after 1m0s
[16:04:08.728] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:29:0)
[16:04:08.728] Timer tick, asking Buildbucket for the build status
[16:04:08.858] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:08.858] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:30:0) after 1m0s
[16:05:08.875] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:30:0)
[16:05:08.875] Timer tick, asking Buildbucket for the build status
[16:05:09.035] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:09.035] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:31:0) after 1m0s
[16:06:09.056] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:31:0)
[16:06:09.056] Timer tick, asking Buildbucket for the build status
[16:06:09.116] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:09.116] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:32:0) after 1m0s
[16:07:09.179] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:32:0)
[16:07:09.179] Timer tick, asking Buildbucket for the build status
[16:07:09.236] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:09.236] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:33:0) after 1m0s
[16:08:09.246] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:33:0)
[16:08:09.246] Timer tick, asking Buildbucket for the build status
[16:08:09.293] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:09.293] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:34:0) after 1m0s
[16:09:09.316] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:34:0)
[16:09:09.316] Timer tick, asking Buildbucket for the build status
[16:09:09.401] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:09.401] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:35:0) after 1m0s
[16:10:09.436] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:35:0)
[16:10:09.436] Timer tick, asking Buildbucket for the build status
[16:10:09.477] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:09.477] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:36:0) after 1m0s
[16:11:09.515] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:36:0)
[16:11:09.515] Timer tick, asking Buildbucket for the build status
[16:11:09.606] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:09.606] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:37:0) after 1m0s
[16:12:09.609] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:37:0)
[16:12:09.609] Timer tick, asking Buildbucket for the build status
[16:12:09.678] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:09.678] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:38:0) after 1m0s
[16:13:09.691] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:38:0)
[16:13:09.691] Timer tick, asking Buildbucket for the build status
[16:13:09.796] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:09.796] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:39:0) after 1m0s
[16:14:09.832] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:39:0)
[16:14:09.832] Timer tick, asking Buildbucket for the build status
[16:14:09.918] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:09.918] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:40:0) after 1m0s
[16:15:09.960] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:40:0)
[16:15:09.960] Timer tick, asking Buildbucket for the build status
[16:15:10.002] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:10.002] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:41:0) after 1m0s
[16:16:10.078] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:41:0)
[16:16:10.078] Timer tick, asking Buildbucket for the build status
[16:16:10.210] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:10.210] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:42:0) after 1m0s
[16:17:10.228] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:42:0)
[16:17:10.228] Timer tick, asking Buildbucket for the build status
[16:17:10.397] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:10.397] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:43:0) after 1m0s
[16:18:10.406] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:43:0)
[16:18:10.406] Timer tick, asking Buildbucket for the build status
[16:18:10.660] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:10.660] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:44:0) after 1m0s
[16:19:10.667] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:44:0)
[16:19:10.667] Timer tick, asking Buildbucket for the build status
[16:19:10.794] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:10.794] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:45:0) after 1m0s
[16:20:10.873] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:45:0)
[16:20:10.873] Timer tick, asking Buildbucket for the build status
[16:20:10.940] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:10.940] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:46:0) after 1m0s
[16:21:10.916] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:46:0)
[16:21:10.916] Timer tick, asking Buildbucket for the build status
[16:21:11.018] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:11.018] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:47:0) after 1m0s
[16:22:11.010] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:47:0)
[16:22:11.010] Timer tick, asking Buildbucket for the build status
[16:22:11.177] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:11.177] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:48:0) after 1m0s
[16:23:11.196] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:48:0)
[16:23:11.196] Timer tick, asking Buildbucket for the build status
[16:23:11.315] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:11.315] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:49:0) after 1m0s
[16:24:11.307] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:49:0)
[16:24:11.307] Timer tick, asking Buildbucket for the build status
[16:24:11.362] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:11.362] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:50:0) after 1m0s
[16:25:11.383] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:50:0)
[16:25:11.383] Timer tick, asking Buildbucket for the build status
[16:25:11.468] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:11.468] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:51:0) after 1m0s
[16:26:11.486] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:51:0)
[16:26:11.486] Timer tick, asking Buildbucket for the build status
[16:26:11.566] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:11.566] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:52:0) after 1m0s
[16:27:11.578] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:52:0)
[16:27:11.578] Timer tick, asking Buildbucket for the build status
[16:27:11.660] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:11.660] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:53:0) after 1m0s
[16:28:11.633] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:53:0)
[16:28:11.633] Timer tick, asking Buildbucket for the build status
[16:28:11.787] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:11.787] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:54:0) after 1m0s
[16:29:11.814] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:54:0)
[16:29:11.814] Timer tick, asking Buildbucket for the build status
[16:29:11.878] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:11.878] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:55:0) after 1m0s
[16:30:11.957] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:55:0)
[16:30:11.957] Timer tick, asking Buildbucket for the build status
[16:30:12.010] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:12.010] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:56:0) after 1m0s
[16:31:12.027] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:56:0)
[16:31:12.027] Timer tick, asking Buildbucket for the build status
[16:31:12.340] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:12.340] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:57:0) after 1m0s
[16:32:12.364] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:57:0)
[16:32:12.364] Timer tick, asking Buildbucket for the build status
[16:32:12.441] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:12.441] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:58:0) after 1m0s
[16:33:12.454] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:58:0)
[16:33:12.454] Timer tick, asking Buildbucket for the build status
[16:33:12.524] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:12.524] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:59:0) after 1m0s
[16:34:12.557] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:59:0)
[16:34:12.557] Timer tick, asking Buildbucket for the build status
[16:34:12.697] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:12.697] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:60:0) after 1m0s
[16:35:12.693] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:60:0)
[16:35:12.693] Timer tick, asking Buildbucket for the build status
[16:35:12.850] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:12.850] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:61:0) after 1m0s
[16:36:12.846] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:61:0)
[16:36:12.846] Timer tick, asking Buildbucket for the build status
[16:36:12.917] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:12.917] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:62:0) after 1m0s
[16:37:12.904] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:62:0)
[16:37:12.904] Timer tick, asking Buildbucket for the build status
[16:37:12.963] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:12.963] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:63:0) after 1m0s
[16:38:12.980] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:63:0)
[16:38:12.980] Timer tick, asking Buildbucket for the build status
[16:38:13.028] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:13.028] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:64:0) after 1m0s
[16:39:13.068] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:64:0)
[16:39:13.068] Timer tick, asking Buildbucket for the build status
[16:39:13.201] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:13.202] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:65:0) after 1m0s
[16:40:13.217] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:65:0)
[16:40:13.217] Timer tick, asking Buildbucket for the build status
[16:40:13.280] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:13.280] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:66:0) after 1m0s
[16:41:13.273] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:66:0)
[16:41:13.273] Timer tick, asking Buildbucket for the build status
[16:41:13.324] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:13.324] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:67:0) after 1m0s
[16:42:13.399] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:67:0)
[16:42:13.399] Timer tick, asking Buildbucket for the build status
[16:42:13.445] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:13.445] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:68:0) after 1m0s
[16:43:13.443] Handling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:68:0)
[16:43:13.443] Timer tick, asking Buildbucket for the build status
[16:43:13.659] Build 8916098769136103376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:13.659] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9081528831135981072:69:0) after 1m0s
[16:43:36.625] Received PubSub notification, asking Buildbucket for the build status
[16:43:36.672] Build 8916098769136103376: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:43:36.672] Buildbucket build:
{
  "build": {
    "bucket": "luci.celab.ci",
    "canary_preference": "AUTO",
    "completed_ts": "1555346616189655",
    "created_by": "project:celab",
    "created_ts": "1555342623541154",
    "failure_reason": "BUILD_FAILURE",
    "id": "8916098769136103376",
    "parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"49a41388829dc842d0fca7d200be88d47f5468f3\"}}",
    "project": "celab",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('run all tests') failed with return_code 1\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/mnt/data/b/s/w/ir/cache\", \"temp_dir\": \"/mnt/data/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"vm78-m1\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux\", \"got_revision\": \"49a41388829dc842d0fca7d200be88d47f5468f3\", \"path_config\": \"generic\", \"recipe\": \"celab\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"49a41388829dc842d0fca7d200be88d47f5468f3\", \"target_cpu\": \"x64\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2670\"], \"gce\": [\"0\"], \"gpu\": [\"none\"], \"id\": [\"vm78-m1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.ISO8859-1\"], \"machine_type\": [\"n1-highcpu-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4253-3d44409\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-1\"]}}, \"ui\": {\"info\": \"Step('run all tests') failed with return_code 1\"}}",
    "service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1555342672073021",
    "status": "COMPLETED",
    "status_changed_ts": "1555342672599357",
    "tags": [
      "builder:Linux",
      "buildset:commit/git/49a41388829dc842d0fca7d200be88d47f5468f3",
      "buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/49a41388829dc842d0fca7d200be88d47f5468f3",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9081528831135981072",
      "scheduler_job_id:celab/Linux",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8916098769136103376/+/annotations",
      "swarming_tag:luci_project:celab",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:celab",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:443a768f11cc9710",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1555346616250280",
    "url": "https://ci.chromium.org/b/8916098769136103376",
    "utcnow_ts": "1555346616663949"
  }
}
[16:43:36.673] Invocation finished in 1h6m34.422979973s with status FAILED