[08:28:20.076] New invocation is queued and will start shortly
[08:28:24.536] Starting the invocation (attempt 1)
[08:28:24.584] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:28:24.584] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:28:24.585] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031274486461236480",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1ae2f731448b4f5efa69167fbc5fa977bf68a7a6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9031274486461236480",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/1ae2f731448b4f5efa69167fbc5fa977bf68a7a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ae2f731448b4f5efa69167fbc5fa977bf68a7a6",
"gitiles_ref:refs/heads/master"
]
}
[08:28:26.398] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603268904727368",
"id": "8865844420914897792",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ae2f731448b4f5efa69167fbc5fa977bf68a7a6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603268905996586",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42140",
"builder:linux-chromeos-rel",
"buildset:commit/git/1ae2f731448b4f5efa69167fbc5fa977bf68a7a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ae2f731448b4f5efa69167fbc5fa977bf68a7a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031274486461236480",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865844420914897792/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1603268905996843",
"url": "https://ci.chromium.org/b/8865844420914897792",
"utcnow_ts": "1603268906384773"
}
}
[08:28:26.398] Task URL: https://ci.chromium.org/b/8865844420914897792
[08:28:26.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:2:0) after 1m0s
[08:28:29.941] Received PubSub notification, asking Buildbucket for the build status
[08:28:30.040] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:26.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:2:0)
[08:29:26.558] Timer tick, asking Buildbucket for the build status
[08:29:26.663] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:26.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:4:0) after 1m0s
[08:30:26.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:4:0)
[08:30:26.771] Timer tick, asking Buildbucket for the build status
[08:30:26.849] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:26.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:5:0) after 1m0s
[08:31:26.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:5:0)
[08:31:26.863] Timer tick, asking Buildbucket for the build status
[08:31:27.381] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:27.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:6:0) after 1m0s
[08:32:27.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:6:0)
[08:32:27.469] Timer tick, asking Buildbucket for the build status
[08:32:27.572] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:27.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:7:0) after 1m0s
[08:33:27.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:7:0)
[08:33:27.636] Timer tick, asking Buildbucket for the build status
[08:33:27.992] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:27.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:8:0) after 1m0s
[08:34:28.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:8:0)
[08:34:28.014] Timer tick, asking Buildbucket for the build status
[08:34:28.378] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:28.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:9:0) after 1m0s
[08:35:28.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:9:0)
[08:35:28.439] Timer tick, asking Buildbucket for the build status
[08:35:28.519] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:28.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:10:0) after 1m0s
[08:36:28.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:10:0)
[08:36:28.553] Timer tick, asking Buildbucket for the build status
[08:36:28.751] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:28.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:11:0) after 1m0s
[08:37:28.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:11:0)
[08:37:28.747] Timer tick, asking Buildbucket for the build status
[08:37:28.822] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:28.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:12:0) after 1m0s
[08:38:28.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:12:0)
[08:38:28.878] Timer tick, asking Buildbucket for the build status
[08:38:28.998] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:28.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:13:0) after 1m0s
[08:39:29.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:13:0)
[08:39:29.069] Timer tick, asking Buildbucket for the build status
[08:39:29.258] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:29.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:14:0) after 1m0s
[08:40:29.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:14:0)
[08:40:29.314] Timer tick, asking Buildbucket for the build status
[08:40:29.442] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:29.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:15:0) after 1m0s
[08:41:29.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:15:0)
[08:41:29.488] Timer tick, asking Buildbucket for the build status
[08:41:29.590] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:29.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:16:0) after 1m0s
[08:42:29.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:16:0)
[08:42:29.651] Timer tick, asking Buildbucket for the build status
[08:42:30.104] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:30.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:17:0) after 1m0s
[08:43:30.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:17:0)
[08:43:30.135] Timer tick, asking Buildbucket for the build status
[08:43:30.313] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:30.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:18:0) after 1m0s
[08:44:30.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:18:0)
[08:44:30.343] Timer tick, asking Buildbucket for the build status
[08:44:30.402] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:30.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:19:0) after 1m0s
[08:45:30.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:19:0)
[08:45:30.466] Timer tick, asking Buildbucket for the build status
[08:45:30.529] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:30.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:20:0) after 1m0s
[08:46:30.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:20:0)
[08:46:30.572] Timer tick, asking Buildbucket for the build status
[08:46:30.636] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:30.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:21:0) after 1m0s
[08:47:30.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:21:0)
[08:47:30.703] Timer tick, asking Buildbucket for the build status
[08:47:30.780] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:30.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:22:0) after 1m0s
[08:48:30.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:22:0)
[08:48:30.807] Timer tick, asking Buildbucket for the build status
[08:48:31.238] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:31.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:23:0) after 1m0s
[08:49:31.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:23:0)
[08:49:31.291] Timer tick, asking Buildbucket for the build status
[08:49:31.356] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:31.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:24:0) after 1m0s
[08:50:31.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:24:0)
[08:50:31.389] Timer tick, asking Buildbucket for the build status
[08:50:31.887] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:31.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:25:0) after 1m0s
[08:51:31.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:25:0)
[08:51:31.882] Timer tick, asking Buildbucket for the build status
[08:51:31.971] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:31.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:26:0) after 1m0s
[08:52:32.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:26:0)
[08:52:32.036] Timer tick, asking Buildbucket for the build status
[08:52:32.126] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:32.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:27:0) after 1m0s
[08:53:32.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:27:0)
[08:53:32.194] Timer tick, asking Buildbucket for the build status
[08:53:32.370] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:32.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:28:0) after 1m0s
[08:54:32.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:28:0)
[08:54:32.398] Timer tick, asking Buildbucket for the build status
[08:54:32.479] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:32.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:29:0) after 1m0s
[08:55:32.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:29:0)
[08:55:32.492] Timer tick, asking Buildbucket for the build status
[08:55:32.620] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:32.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:30:0) after 1m0s
[08:56:32.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:30:0)
[08:56:32.643] Timer tick, asking Buildbucket for the build status
[08:56:32.763] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:32.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:31:0) after 1m0s
[08:57:32.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:31:0)
[08:57:32.803] Timer tick, asking Buildbucket for the build status
[08:57:32.871] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:32.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:32:0) after 1m0s
[08:58:32.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:32:0)
[08:58:32.896] Timer tick, asking Buildbucket for the build status
[08:58:32.999] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:32.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:33:0) after 1m0s
[08:59:33.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:33:0)
[08:59:33.053] Timer tick, asking Buildbucket for the build status
[08:59:33.294] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:33.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:34:0) after 1m0s
[09:00:33.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:34:0)
[09:00:33.328] Timer tick, asking Buildbucket for the build status
[09:00:33.419] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:33.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:35:0) after 1m0s
[09:01:33.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:35:0)
[09:01:33.515] Timer tick, asking Buildbucket for the build status
[09:01:33.603] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:33.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:36:0) after 1m0s
[09:02:33.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:36:0)
[09:02:33.633] Timer tick, asking Buildbucket for the build status
[09:02:33.698] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:33.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:37:0) after 1m0s
[09:03:33.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:37:0)
[09:03:33.757] Timer tick, asking Buildbucket for the build status
[09:03:33.921] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:33.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:38:0) after 1m0s
[09:04:33.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:38:0)
[09:04:33.990] Timer tick, asking Buildbucket for the build status
[09:04:34.107] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:34.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:39:0) after 1m0s
[09:05:34.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:39:0)
[09:05:34.110] Timer tick, asking Buildbucket for the build status
[09:05:34.289] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:34.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:40:0) after 1m0s
[09:06:34.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:40:0)
[09:06:34.349] Timer tick, asking Buildbucket for the build status
[09:06:34.483] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:34.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:41:0) after 1m0s
[09:07:34.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:41:0)
[09:07:34.507] Timer tick, asking Buildbucket for the build status
[09:07:34.566] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:34.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:42:0) after 1m0s
[09:08:34.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:42:0)
[09:08:34.600] Timer tick, asking Buildbucket for the build status
[09:08:34.788] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:34.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:43:0) after 1m0s
[09:09:34.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:43:0)
[09:09:34.855] Timer tick, asking Buildbucket for the build status
[09:09:34.984] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:34.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:44:0) after 1m0s
[09:10:35.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:44:0)
[09:10:35.044] Timer tick, asking Buildbucket for the build status
[09:10:35.205] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:35.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:45:0) after 1m0s
[09:11:35.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:45:0)
[09:11:35.230] Timer tick, asking Buildbucket for the build status
[09:11:35.508] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:35.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:46:0) after 1m0s
[09:12:35.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:46:0)
[09:12:35.534] Timer tick, asking Buildbucket for the build status
[09:12:35.617] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:35.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:47:0) after 1m0s
[09:13:35.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:47:0)
[09:13:35.670] Timer tick, asking Buildbucket for the build status
[09:13:35.836] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:35.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:48:0) after 1m0s
[09:14:35.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:48:0)
[09:14:35.895] Timer tick, asking Buildbucket for the build status
[09:14:36.414] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:36.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:49:0) after 1m0s
[09:15:36.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:49:0)
[09:15:36.477] Timer tick, asking Buildbucket for the build status
[09:15:36.577] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:36.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:50:0) after 1m0s
[09:16:36.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:50:0)
[09:16:36.618] Timer tick, asking Buildbucket for the build status
[09:16:36.828] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:36.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:51:0) after 1m0s
[09:17:36.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:51:0)
[09:17:36.846] Timer tick, asking Buildbucket for the build status
[09:17:37.432] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:37.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:52:0) after 1m0s
[09:18:37.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:52:0)
[09:18:37.526] Timer tick, asking Buildbucket for the build status
[09:18:38.127] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:38.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:53:0) after 1m0s
[09:19:38.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:53:0)
[09:19:38.170] Timer tick, asking Buildbucket for the build status
[09:19:38.322] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:38.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:54:0) after 1m0s
[09:20:38.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:54:0)
[09:20:38.362] Timer tick, asking Buildbucket for the build status
[09:20:38.564] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:38.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:55:0) after 1m0s
[09:21:38.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:55:0)
[09:21:38.631] Timer tick, asking Buildbucket for the build status
[09:21:39.047] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:56:0) after 1m0s
[09:22:39.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:56:0)
[09:22:39.109] Timer tick, asking Buildbucket for the build status
[09:22:39.398] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:39.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:57:0) after 1m0s
[09:23:39.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:57:0)
[09:23:39.462] Timer tick, asking Buildbucket for the build status
[09:23:39.881] Build 8865844420914897792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:39.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031274486461236480:58:0) after 1m0s
[09:24:27.172] Received PubSub notification, asking Buildbucket for the build status
[09:24:27.539] Build 8865844420914897792: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:24:27.539] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603272263102344",
"created_by": "project:chromium",
"created_ts": "1603268904727368",
"failure_reason": "BUILD_FAILURE",
"id": "8865844420914897792",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ae2f731448b4f5efa69167fbc5fa977bf68a7a6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**remoting_unittests** failed because of:\\n\\n- Webrtc/ConnectionTest.FirstCaptureFailed/0\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"1ae2f731448b4f5efa69167fbc5fa977bf68a7a6\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-74-luct\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42140, \"got_angle_revision\": \"e2147a58a2339351b140d251751eb0c3f1804767\", \"got_dawn_revision\": \"cca03ca6bfe936cea2cda29e0c72fb0d0fde2850\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"1ae2f731448b4f5efa69167fbc5fa977bf68a7a6\", \"got_revision_cp\": \"refs/heads/master@{#819285}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"84f5eeb6dd9b225f465f93737fa76aad7de355cf\", \"got_v8_revision\": \"d82ff002381657c44563e830ec337d103b99d565\", \"got_v8_revision_cp\": \"refs/heads/8.8.148@{#1}\", \"got_webrtc_revision\": \"7bc3356fbcb9b95ea2c810ae2f3152930d54577b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32449}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ae2f731448b4f5efa69167fbc5fa977bf68a7a6\", \"swarm_hashes_refs/heads/master(at){#819285}_without_patch\": {\"absl_hardening_tests\": \"db7587b8b114b453b00184152793c3fcae07519e\", \"accessibility_unittests\": \"d17bbd3884dfa6aefbb26620374cb8b3d81154b6\", \"app_list_unittests\": \"d8e6f15ccd411fe182929555da5fdf0f7cf2fc71\", \"app_shell_unittests\": \"8486bfcb066929ffb199ef781b255ad61fbed4d3\", \"ash_unittests\": \"711ca096335ccbd927335255d344e7ce1a1b5c94\", \"aura_unittests\": \"9409a8a40e822bc7c32d743dd3861b0d9aa1f266\", \"base_unittests\": \"4ae558967b4c5c6b60b2b6aaffba225e645ce54b\", \"base_util_unittests\": \"dde9c527ac18ed2b78708f69c7fdc660b4c13ad2\", \"blink_common_unittests\": \"f19972c7718be8cf300819da91b57548272ccb59\", \"blink_fuzzer_unittests\": \"e8af99825ff94df16c1b6d16c31091554aa8010a\", \"blink_heap_unittests\": \"84821f2ce26c672bb3b01b8e21b45d817931e9f9\", \"blink_platform_unittests\": \"bd5255133a0fd42e1b311bcd21d24587eee0f562\", \"blink_unittests\": \"fdaa22e2041356fd5d5c1ca14c721ed475469579\", \"boringssl_crypto_tests\": \"e199f71b19d6168880877572d496cf9db6c648fc\", \"boringssl_ssl_tests\": \"7d1084b05d564be65c062b6c1c091838906e7bdb\", \"browser_tests\": \"7ac0d179c549b7436fb964459adcf48b5cdd8cac\", \"capture_unittests\": \"f1cbdf61f528a2ece9d8aca08e4be67cd340f4c3\", \"cast_unittests\": \"1f7a2c9257163262f2c8f06f3f0b5425899884c6\", \"cc_unittests\": \"0b2651edff21ba5682e939fab7792985e1c91259\", \"chrome_app_unittests\": \"accdb59062ab644cc9e9f0d581916a65dd0c3995\", \"chromedriver_unittests\": \"e35d29e27ee5a962633246c38f042a410abe5f3e\", \"chromeos_components_unittests\": \"6c0c9c2cf5e7b21001d49e723ccdb1734f10a1b6\", \"chromeos_unittests\": \"5b554746dd01db08c815f08373a1bba70f67d273\", \"components_browsertests\": \"3a735c19f8d12b99a2a770def2425eb95cf78e0c\", \"components_unittests\": \"b6389d86d94587ee7b01e8d5c65800b9e6e2d99c\", \"compositor_unittests\": \"41dd87e85bbb4804afa2eb49e52cdf68fa2232e1\", \"content_browsertests\": \"a1c67fd0e207f4d8a664feb6c12b5b42ef81d5fa\", \"content_unittests\": \"0fcb40ccc9a27647c8fa98e019112081399dd7a9\", \"crypto_unittests\": \"3c5fff692edce684f27b456bed918994a3cc5c92\", \"dbus_unittests\": \"130ebb0c825d98b6497145fd32e13ba03cf8bbcb\", \"device_unittests\": \"496482d89ff156e7a085b9f81a19d0d093a963b8\", \"display_unittests\": \"08737c15945dd94528ed78cf929e24199e908572\", \"events_unittests\": \"3aa25d05ac79554c44a5ed007f004dc6de55be21\", \"exo_unittests\": \"4af165b4cdb29fcb48159ec09777e409d61686e2\", \"extensions_browsertests\": \"5d83735f1c2d5cd66bba1fe5ed6e2ff6bb300008\", \"extensions_unittests\": \"876a694a2678bc847a64936efb739786eef76373\", \"filesystem_service_unittests\": \"e21052e87d7b7760881ecb683c73a76ff175d13f\", \"gcm_unit_tests\": \"9bffb70af63e3a63e4307b9be00480e58b19dfd5\", \"gfx_unittests\": \"04d681dfcff42dd131ab23bcf6b3cea3874ef3dd\", \"gin_unittests\": \"a56e7939fcb69d60745edc306ea94133adc8c893\", \"gl_unittests_ozone\": \"408bc63f566a6503580d0ae4c3d3c4aeca933dec\", \"google_apis_unittests\": \"6f9986e3efe11c17091b408357561c7137fc9583\", \"gpu_unittests\": \"ba1bdcfa90dd6931b6c1516bd2d8d5d2065e0847\", \"gwp_asan_unittests\": \"3986efcbc20a5a3dd20550f19ba49223bafb5da4\", \"interactive_ui_tests\": \"43a5a83f314fde2d74a3bf1ac8779270d06b7cad\", \"ipc_tests\": \"3f44d5c5d00dafcb007ad43073f42a27732a7387\", \"jingle_unittests\": \"c170885a546ca089ae3e05228e338ccd8432e74b\", \"keyboard_unittests\": \"e3bbad2f9655d7b8bf3ccac994e7a4e008c885d2\", \"latency_unittests\": \"3f05c8f307445b346c5115d348b6b883b2f9f07c\", \"libjingle_xmpp_unittests\": \"2908e1b48a16b32761f7bd3afd187fa2f5138e73\", \"media_blink_unittests\": \"863e364f1f9a91127d5ff44a47e1f43534420e38\", \"media_unittests\": \"18280a4a4d04ad729a12c0054f73e04985265e05\", \"message_center_unittests\": \"d71bfc32aa993ad2b21861b0c2a6067cba3b28bf\", \"midi_unittests\": \"947d50465262e3af7b184a6fb4ff0f72da14e809\", \"mojo_core_unittests\": \"ab928602f29d0abb870daf4576e20c769db4aee8\", \"mojo_unittests\": \"8192cbc967ed76ed5c6c34df822973554f9070f1\", \"nacl_helper_nonsfi_unittests\": \"76f7b9c4d39f7854994f92685e836879fadbbedb\", \"nacl_loader_unittests\": \"266c1936a778edf42333a5bba5e532ab66b4292a\", \"native_theme_unittests\": \"235e1d85cfee3951a03c914653854441672a58c8\", \"net_unittests\": \"fea45aa339dd43c838eafd957265bcf9e0e18a64\", \"ozone_gl_unittests\": \"ddaf759adf9356a651721ab8751e08bfec16b15c\", \"ozone_unittests\": \"fab715faa67242cbd14218a3e6df8787d8de3fb5\", \"ozone_x11_unittests\": \"8c36b1e47444ff7480367c54012c4b944a7b5ed9\", \"pdf_unittests\": \"d5908c0a912451b4b1d5c992484fd1e61f3d278a\", \"perfetto_unittests\": \"4acaf6aef95592a416d3258ebfb249da3bf8ef9a\", \"ppapi_unittests\": \"aac70440751e38eb571f6d6719ae6523bf0976c9\", \"printing_unittests\": \"dbe67b8588827e863973c0abae112ec1517f8454\", \"remoting_unittests\": \"ca9cdbba2ee4fcd5869ffafc3076ce7da5b49c7a\", \"sandbox_linux_unittests\": \"f5ec4506d5c6694f74913969cd7dc3922ff107d9\", \"service_manager_unittests\": \"fe3ae4e73c628e5ef0ce2b233b8aa5e4723a452e\", \"services_unittests\": \"9517d7849d18c4c239f9da82f02611af3767845c\", \"shell_dialogs_unittests\": \"280e836bf8a56b14bf47ca1c47d7b9b7daf4d470\", \"skia_unittests\": \"d93e8886b3b58106576f4ffa48803b2ac63b682f\", \"snapshot_unittests\": \"37364f049561c98c45bf5a6b7f6346fa72f96c3e\", \"sql_unittests\": \"ec9b24379c227a9977c262ee92974003631839e5\", \"standalone_angle_unittests\": \"a452dfb8a69af3d9980e058680cd9db1bbae09c1\", \"storage_unittests\": \"3e40bfca839514e6dd93eab2c3cfc96eb37997f4\", \"sync_integration_tests\": \"06b22e5e00e5bd084ee14bcc44e5cb6fc655a8ed\", \"traffic_annotation_auditor_unittests\": \"41f8f1dc98331811ee558be03ea08cdf5256350d\", \"ui_base_unittests\": \"adcbd72b80b08b5116def254b04e3fd7baf55ec5\", \"ui_chromeos_unittests\": \"80f55d55e28368bd64a5855a7c52a14c89afade6\", \"ui_touch_selection_unittests\": \"3add724344ba70834210a8a3e933f87b4e787c8f\", \"unit_tests\": \"268cdb675608462da778bf85ce7ff817fda5154f\", \"url_unittests\": \"b1bbac759c4e7414182167213bbba803db9e203b\", \"usage_time_limit_unittests\": \"a9ee7df74d56f75fae20b842b662c572b6e970b4\", \"views_unittests\": \"8455b8ac4dd208a591084445fc2d45dfa35fa741\", \"viz_unittests\": \"e01cc79dcd1e911eb49ee41d0b6acd06a92414c9\", \"wayland_client_perftests\": \"ee5f32deb0f3d2580eaaf60f4041819cad92082b\", \"wm_unittests\": \"b86eb302e9b113b50dcc08f8a0fdbac16b430ef5\", \"wtf_unittests\": \"ad0b2e38a2b59d0e6d0c1a2c69b236c19c1bd1db\", \"zlib_unittests\": \"8320a3ec757bdbd4bfeff2c192d82162b7665b29\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_34caa451ddae04e30af534f20a2f04cca4108dc27b0a92529062f4f2e114a8e9_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_9c30056936b0e6c113d792a7251a67e37568746469ef96a03dc78e237458f3d4_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"builder_e4464922e5c60bddfcc3d01cfc511ef7f4a711bded636fa98c66f03a573264ee_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-74-luct\"], \"image\": [\"chrome-xenial-20101600-2d400e1627f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5601-97d7e7d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**remoting_unittests** failed because of:\\n\\n- Webrtc/ConnectionTest.FirstCaptureFailed/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603268908899562",
"status": "COMPLETED",
"status_changed_ts": "1603268909522661",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42140",
"builder:linux-chromeos-rel",
"buildset:commit/git/1ae2f731448b4f5efa69167fbc5fa977bf68a7a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ae2f731448b4f5efa69167fbc5fa977bf68a7a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031274486461236480",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865844420914897792/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4f631796c5ac1610",
"user_agent:luci-scheduler"
],
"updated_ts": "1603272265708439",
"url": "https://ci.chromium.org/b/8865844420914897792",
"utcnow_ts": "1603272267511263"
}
}
[09:24:27.539] Invocation finished in 56m7.485108394s with status FAILED