[17:14:42.491] New invocation is queued and will start shortly
[17:14:43.715] Starting the invocation (attempt 1)
[17:14:43.817] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:14:43.819] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:14:43.819] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022181673253562480",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e58043f3130dd13460a3b937b466ebffd4e05399\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022181673253562480",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/e58043f3130dd13460a3b937b466ebffd4e05399",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e58043f3130dd13460a3b937b466ebffd4e05399",
"gitiles_ref:refs/heads/master"
]
}
[17:14:44.778] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611940483940229",
"id": "8856751611070116768",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e58043f3130dd13460a3b937b466ebffd4e05399\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611940484616512",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45004",
"builder:linux-chromeos-rel",
"buildset:commit/git/e58043f3130dd13460a3b937b466ebffd4e05399",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e58043f3130dd13460a3b937b466ebffd4e05399",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022181673253562480",
"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/8856751611070116768/+/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": "1611940484617462",
"url": "https://ci.chromium.org/b/8856751611070116768",
"utcnow_ts": "1611940484767825"
}
}
[17:14:44.778] Task URL: https://ci.chromium.org/b/8856751611070116768
[17:14:44.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:2:0) after 1m0s
[17:15:42.447] Received PubSub notification, asking Buildbucket for the build status
[17:15:42.584] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:44.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:2:0)
[17:15:44.852] Timer tick, asking Buildbucket for the build status
[17:15:45.245] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:45.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:4:0) after 1m0s
[17:16:45.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:4:0)
[17:16:45.315] Timer tick, asking Buildbucket for the build status
[17:16:45.455] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:45.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:5:0) after 1m0s
[17:17:45.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:5:0)
[17:17:45.496] Timer tick, asking Buildbucket for the build status
[17:17:45.631] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:45.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:6:0) after 1m0s
[17:18:45.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:6:0)
[17:18:45.638] Timer tick, asking Buildbucket for the build status
[17:18:45.708] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:45.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:7:0) after 1m0s
[17:19:45.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:7:0)
[17:19:45.779] Timer tick, asking Buildbucket for the build status
[17:19:45.878] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:45.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:8:0) after 1m0s
[17:20:45.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:8:0)
[17:20:45.875] Timer tick, asking Buildbucket for the build status
[17:20:45.985] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:45.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:9:0) after 1m0s
[17:21:46.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:9:0)
[17:21:46.007] Timer tick, asking Buildbucket for the build status
[17:21:46.269] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:46.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:10:0) after 1m0s
[17:22:46.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:10:0)
[17:22:46.288] Timer tick, asking Buildbucket for the build status
[17:22:46.547] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:46.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:11:0) after 1m0s
[17:23:46.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:11:0)
[17:23:46.606] Timer tick, asking Buildbucket for the build status
[17:23:46.767] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:46.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:12:0) after 1m0s
[17:24:46.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:12:0)
[17:24:46.839] Timer tick, asking Buildbucket for the build status
[17:24:46.945] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:46.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:13:0) after 1m0s
[17:25:46.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:13:0)
[17:25:46.927] Timer tick, asking Buildbucket for the build status
[17:25:47.260] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:47.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:14:0) after 1m0s
[17:26:47.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:14:0)
[17:26:47.324] Timer tick, asking Buildbucket for the build status
[17:26:47.388] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:47.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:15:0) after 1m0s
[17:27:47.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:15:0)
[17:27:47.430] Timer tick, asking Buildbucket for the build status
[17:27:47.492] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:47.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:16:0) after 1m0s
[17:28:47.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:16:0)
[17:28:47.539] Timer tick, asking Buildbucket for the build status
[17:28:47.601] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:47.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:17:0) after 1m0s
[17:29:47.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:17:0)
[17:29:47.654] Timer tick, asking Buildbucket for the build status
[17:29:48.208] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:48.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:18:0) after 1m0s
[17:30:48.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:18:0)
[17:30:48.228] Timer tick, asking Buildbucket for the build status
[17:30:48.320] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:48.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:19:0) after 1m0s
[17:31:48.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:19:0)
[17:31:48.378] Timer tick, asking Buildbucket for the build status
[17:31:48.526] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:48.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:20:0) after 1m0s
[17:32:48.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:20:0)
[17:32:48.548] Timer tick, asking Buildbucket for the build status
[17:32:48.720] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:48.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:21:0) after 1m0s
[17:33:48.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:21:0)
[17:33:48.801] Timer tick, asking Buildbucket for the build status
[17:33:48.881] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:48.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:22:0) after 1m0s
[17:34:48.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:22:0)
[17:34:48.932] Timer tick, asking Buildbucket for the build status
[17:34:49.340] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:49.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:23:0) after 1m0s
[17:35:49.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:23:0)
[17:35:49.346] Timer tick, asking Buildbucket for the build status
[17:35:49.597] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:49.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:24:0) after 1m0s
[17:36:49.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:24:0)
[17:36:49.588] Timer tick, asking Buildbucket for the build status
[17:36:49.671] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:49.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:25:0) after 1m0s
[17:37:49.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:25:0)
[17:37:49.700] Timer tick, asking Buildbucket for the build status
[17:37:49.763] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:49.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:26:0) after 1m0s
[17:38:49.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:26:0)
[17:38:49.810] Timer tick, asking Buildbucket for the build status
[17:38:49.948] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:49.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:27:0) after 1m0s
[17:39:49.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:27:0)
[17:39:49.929] Timer tick, asking Buildbucket for the build status
[17:39:50.084] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:50.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:28:0) after 1m0s
[17:40:50.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:28:0)
[17:40:50.109] Timer tick, asking Buildbucket for the build status
[17:40:50.162] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:50.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:29:0) after 1m0s
[17:41:50.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:29:0)
[17:41:50.206] Timer tick, asking Buildbucket for the build status
[17:41:50.272] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:50.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:30:0) after 1m0s
[17:42:50.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:30:0)
[17:42:50.253] Timer tick, asking Buildbucket for the build status
[17:42:50.520] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:50.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:31:0) after 1m0s
[17:43:50.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:31:0)
[17:43:50.605] Timer tick, asking Buildbucket for the build status
[17:43:50.674] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:50.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:32:0) after 1m0s
[17:44:50.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:32:0)
[17:44:50.686] Timer tick, asking Buildbucket for the build status
[17:44:50.807] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:50.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:33:0) after 1m0s
[17:45:50.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:33:0)
[17:45:50.908] Timer tick, asking Buildbucket for the build status
[17:45:51.001] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:51.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:34:0) after 1m0s
[17:46:51.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:34:0)
[17:46:51.190] Timer tick, asking Buildbucket for the build status
[17:46:51.317] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:51.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:35:0) after 1m0s
[17:47:51.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:35:0)
[17:47:51.376] Timer tick, asking Buildbucket for the build status
[17:47:51.481] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:51.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:36:0) after 1m0s
[17:48:51.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:36:0)
[17:48:51.635] Timer tick, asking Buildbucket for the build status
[17:48:51.764] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:51.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:37:0) after 1m0s
[17:49:51.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:37:0)
[17:49:51.860] Timer tick, asking Buildbucket for the build status
[17:49:52.003] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:52.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:38:0) after 1m0s
[17:50:52.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:38:0)
[17:50:52.079] Timer tick, asking Buildbucket for the build status
[17:50:52.513] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:52.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:39:0) after 1m0s
[17:51:52.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:39:0)
[17:51:52.641] Timer tick, asking Buildbucket for the build status
[17:51:52.849] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:52.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:40:0) after 1m0s
[17:52:52.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:40:0)
[17:52:52.916] Timer tick, asking Buildbucket for the build status
[17:52:53.085] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:53.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:41:0) after 1m0s
[17:53:53.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:41:0)
[17:53:53.141] Timer tick, asking Buildbucket for the build status
[17:53:53.263] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:53.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:42:0) after 1m0s
[17:54:53.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:42:0)
[17:54:53.333] Timer tick, asking Buildbucket for the build status
[17:54:53.465] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:53.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:43:0) after 1m0s
[17:55:53.578] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:43:0)
[17:55:53.578] Timer tick, asking Buildbucket for the build status
[17:55:54.026] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:54.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:44:0) after 1m0s
[17:56:54.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:44:0)
[17:56:54.144] Timer tick, asking Buildbucket for the build status
[17:56:54.767] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:54.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:45:0) after 1m0s
[17:57:54.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:45:0)
[17:57:54.796] Timer tick, asking Buildbucket for the build status
[17:57:55.645] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:55.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:46:0) after 1m0s
[17:58:55.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:46:0)
[17:58:55.751] Timer tick, asking Buildbucket for the build status
[17:58:55.896] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:55.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:47:0) after 1m0s
[17:59:55.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:47:0)
[17:59:55.890] Timer tick, asking Buildbucket for the build status
[17:59:56.309] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:56.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:48:0) after 1m0s
[18:00:56.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:48:0)
[18:00:56.316] Timer tick, asking Buildbucket for the build status
[18:00:56.915] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:56.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:49:0) after 1m0s
[18:01:56.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:49:0)
[18:01:56.983] Timer tick, asking Buildbucket for the build status
[18:01:57.055] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:57.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:50:0) after 1m0s
[18:02:57.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:50:0)
[18:02:57.074] Timer tick, asking Buildbucket for the build status
[18:02:57.449] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:57.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:51:0) after 1m0s
[18:03:57.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:51:0)
[18:03:57.480] Timer tick, asking Buildbucket for the build status
[18:03:57.633] Build 8856751611070116768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:57.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022181673253562480:52:0) after 1m0s
[18:04:21.622] Received PubSub notification, asking Buildbucket for the build status
[18:04:21.766] Build 8856751611070116768: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:04:21.766] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611943460925695",
"created_by": "project:chromium",
"created_ts": "1611940483940229",
"failure_reason": "BUILD_FAILURE",
"id": "8856751611070116768",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e58043f3130dd13460a3b937b466ebffd4e05399\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SelectFileDialogExtensionBrowserTest/SelectFileDialogExtensionBrowserTest.SelectFileVirtualKeyboard_TabletMode/1\\n\\n- TestAsNormalAndGuestUser/SpokenFeedbackTest.MoveByCharacterPhoneticSpeechAndHints/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\": \"e58043f3130dd13460a3b937b466ebffd4e05399\", \"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-23-q58c\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45004, \"got_angle_revision\": \"a29ef6129501a14774387f23574d30459b70f9e6\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"e58043f3130dd13460a3b937b466ebffd4e05399\", \"got_revision_cp\": \"refs/heads/master@{#848573}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e58043f3130dd13460a3b937b466ebffd4e05399\", \"swarm_hashes_refs/heads/master(at){#848573}_without_patch\": {\"absl_hardening_tests\": \"d825f247f0091f95fddebda7c01d12df354334cb\", \"accessibility_unittests\": \"ebb2476c43ebffd66dc59c73a906c2ab714b3ff3\", \"angle_unittests\": \"9fe368c2f977337b37d7e2725f638249245d0c1f\", \"app_list_unittests\": \"fb8d4952ad24b0c159735c83a650a1da02d2af10\", \"app_shell_unittests\": \"25a0b728ee5a27409fd7eddbb295a3ffa053d2f5\", \"ash_unittests\": \"c190f8435b10d93336fb1e5b39329952cde335d6\", \"aura_unittests\": \"3f0d941ce95b7cf905125d1cf9fd6d84f32ba967\", \"base_unittests\": \"7dbcb1d3cb4e65e7adff8f0fb4cfa3f60a86dba4\", \"base_util_unittests\": \"241f2516f71a57259ebca8be6ea9b00f3387a40b\", \"blink_common_unittests\": \"8f7c78ed04bd243674a75cd1c0f18258fee81323\", \"blink_fuzzer_unittests\": \"6d6c4cea7ffc6abffaa2b63d5987d8a1a9239d2c\", \"blink_heap_unittests\": \"a9d58290bdb51a739ab8aebd5f1d032b9bc0b2fd\", \"blink_platform_unittests\": \"97b5d869e91ed2f3be76a108a10bff09d22eef96\", \"blink_unittests\": \"88c202a9cfd4164db0d07192ab373dc89aa22562\", \"boringssl_crypto_tests\": \"a770de1b0852ef96bc3c3e8a2651a0a7fbe5cf10\", \"boringssl_ssl_tests\": \"32796134b0fc74991c6488aa13c10ad22b840fb0\", \"browser_tests\": \"7d525d7b085f8767d4327a1f9709670f0b51f96d\", \"capture_unittests\": \"13698942f50ae1b68a5d789414330847bb4903ff\", \"cast_unittests\": \"68a8df11d68e755b290d10fdb2df7bb8ad6b4138\", \"cc_unittests\": \"541691b90f3c978c8966227253f902646bce6852\", \"chrome_app_unittests\": \"3f56ce535bab5e759c7bfe25e71374f97e2f8222\", \"chromedriver_unittests\": \"fdcb4f6a5a6c46719a4399e61fa4be3ebd4f9ef2\", \"chromeos_components_unittests\": \"c313d97819bfe6bcd1f39ecf0119e585e5384c2f\", \"chromeos_unittests\": \"f6d6abc32cf01c09ba3d3404e9734c688037fb6c\", \"components_browsertests\": \"56350e8a0c6b1a35c33fd77ceb238e2136d7bc84\", \"components_unittests\": \"038ef9d429eaf06ea7341831254a633e96567378\", \"compositor_unittests\": \"9f8202d8f13b932c8294dbc1011350f8810ef0a9\", \"content_browsertests\": \"79809485fc4af69e5a3c39b0db1976cebfb9d7ff\", \"content_unittests\": \"2f48f5df5713238a841fa0c62c851a59295bb94e\", \"crashpad_tests\": \"3288f9e7c4d12b79d4b264d13e235ea98b8d1ad7\", \"crypto_unittests\": \"620a7cb3d5846fb649423475398bfa0222e9ca7e\", \"dbus_unittests\": \"12d33d8b862500ff335ec76b0f9d0844af643498\", \"device_unittests\": \"4a96e9a0afe0b86e3680c2333dbf2533e8086144\", \"display_unittests\": \"5d319a64e5697ccc2ab4c7ab6c5e450307105fec\", \"events_unittests\": \"753ae1dd1cf76b206128c9fe2ba145ec4cbe3191\", \"exo_unittests\": \"91206ae049060932473e951652bed2605b2715da\", \"extensions_browsertests\": \"fefc50dc4d1e68ba8adc23371aaa644c0b0478c1\", \"extensions_unittests\": \"089785ccdcc834ff90481b68064ade2cb9400874\", \"filesystem_service_unittests\": \"4008b69b0f132892f7b50935171c5e4f4943b18d\", \"gcm_unit_tests\": \"a1320f2d8ccfe59b2a9069b66ddea63e9cbd6635\", \"gfx_unittests\": \"c52b95ffd4c3695ee423122d45f38ac0dd7338b3\", \"gin_unittests\": \"36518327181cb763937182d43bb340354ee3be0e\", \"gl_unittests_ozone\": \"5a6fb7492bf9297e4aa98076d6656a503777026b\", \"google_apis_unittests\": \"ca7b600c9ad4fb2e1403710b5bebffa787c7bc35\", \"gpu_unittests\": \"752fcbf00b7cd2a934e2313e17bbc0877449c3b0\", \"gwp_asan_unittests\": \"7f98e1a9ce57ea562b04b9d658e0979d4fc2a840\", \"interactive_ui_tests\": \"118eb41530e24ee92f309df22e774393df0fd6dd\", \"ipc_tests\": \"7aa58f395372208b9fa2c8e6b163078893079443\", \"jingle_unittests\": \"739c64d11bbc1b75795cdaf52a73fcbf9a929d04\", \"keyboard_unittests\": \"350cd11dd5b3ceb3a4b145236cb0d8b334ac6fda\", \"latency_unittests\": \"2311c73b9cef716c74e5514cfc0f90188deedc29\", \"libjingle_xmpp_unittests\": \"e308239816b1904ca281eecb112091023296711c\", \"liburlpattern_unittests\": \"1398c8da3763fd826809bbe00aac164578012248\", \"media_blink_unittests\": \"6aa625e4a4f6f045a425448d465f44470bf43897\", \"media_unittests\": \"cd56d7d2d4cd07bea5c8734c1d471e638c01a974\", \"message_center_unittests\": \"325aa7ce0dfab7613d26e26847fe84646024f7c8\", \"midi_unittests\": \"aaa61a3479ecd1233990e2d668e9ae6b31607b36\", \"mojo_core_unittests\": \"19cf4896fc010b36b488aef272f96a2fb9d1ca5b\", \"mojo_unittests\": \"51b8a2634554d3ef97593fab8a574b89d029f05b\", \"nacl_helper_nonsfi_unittests\": \"3ff9c4fe7a7762206b5b36c4ef531adfd031245b\", \"nacl_loader_unittests\": \"067c53ae61444fe91b560d142bfa005b66e4e43e\", \"native_theme_unittests\": \"85b63289fd94bab87a5c665244cc1aa4f64272fa\", \"net_unittests\": \"76d360ec4547123c3379d220cbbd2a4aba35e7d7\", \"ozone_gl_unittests\": \"585d3eb04475eb31363e154e5ecb215438cf0c22\", \"ozone_unittests\": \"cfe31defdd9beb53824475f0d6b22d7753837e8f\", \"ozone_x11_unittests\": \"dbb1927b74169ab91388a59a5e9730c228bd7540\", \"pdf_unittests\": \"d4a7de642a1759ffb56643b8737310cfb9d2224b\", \"perfetto_unittests\": \"1cf3a19f79287401ed6ece6e298a568fa964ecb9\", \"ppapi_unittests\": \"97d8c39a79562b36cef71c838ebcb94374ff2f23\", \"printing_unittests\": \"8154e476aa88f2d2d860e52e1cf2fb36e5aaf3a6\", \"remoting_unittests\": \"b4b290c650a18dc2833aadc5d8a95b21bfd2ff96\", \"sandbox_linux_unittests\": \"993bdcb68db16eb95a53a143bd2c362687fbf029\", \"service_manager_unittests\": \"9ec4a32616a060ac89561a34b1e09361ac0f199d\", \"services_unittests\": \"e42ed49faa6cd953fbeae89e5049344e20fc4152\", \"shell_dialogs_unittests\": \"a9f60ac1c1c4485f697170cac486a13766fd9bea\", \"shell_encryption_unittests\": \"34b18c78c4f41a85e71e8b59a53b3ef754c803a9\", \"skia_unittests\": \"64c4b49253826c234db622e153d732107b4afa06\", \"snapshot_unittests\": \"4034ee9843951d822b0c156e79684733a8bef380\", \"sql_unittests\": \"ad561168c23cc383484e3448995372167734fbd6\", \"storage_unittests\": \"7a6e1a6fd28da8d9ca3245c6dc7a0a4aae78d21e\", \"sync_integration_tests\": \"4736ba69e5f577d5d1c4e30a2da81740b4cd3276\", \"traffic_annotation_auditor_unittests\": \"ce331ba3bd727c48fd801fd1dbc7a29608c6ffd5\", \"ui_base_unittests\": \"b47aa4b12b9dcabf56452882a979b70ecb42a5a6\", \"ui_chromeos_unittests\": \"c2a73e18e0c6c3aa2cdc89e019c6e0c563e0b2dc\", \"ui_touch_selection_unittests\": \"de93cd2110da3da71d991dd0798c988a1fb5681e\", \"unit_tests\": \"722d0f4b36c32ce91a932da19a6ca5cbbd0ee811\", \"url_unittests\": \"467308fb05065b64c770bb8fffd4363f8122ed83\", \"usage_time_limit_unittests\": \"940bd78391ff0c05b2c30612d2689703f727045c\", \"views_unittests\": \"8e38c29b8de93ca47d5bd73d14fa865d45a5288d\", \"viz_unittests\": \"f797b772cae705ed896fa2b6c5d88389b672578b\", \"wayland_client_perftests\": \"9e98699c458ba0b863ce1f2577a8e79a477cb02b\", \"wm_unittests\": \"f18d8f880697f9da11fee100b51ede8cb94de40f\", \"wtf_unittests\": \"3c5c64bb35270bbb038d3b727fd09ddb654d4dde\", \"zlib_unittests\": \"9df5064a389c37f282b2ff316515553a43f6a55b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_eae3a4dc277b4e2282e9fce002ae6861b5ee5509389502cf3acc9366ec79a2d3_v2\", \"builder_eb9e31050ddbebce4ba6cbaea9a0445903ee5f3e90bdb319e3517a7851b1b7f7_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-23-q58c\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SelectFileDialogExtensionBrowserTest/SelectFileDialogExtensionBrowserTest.SelectFileVirtualKeyboard_TabletMode/1\\n\\n- TestAsNormalAndGuestUser/SpokenFeedbackTest.MoveByCharacterPhoneticSpeechAndHints/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611940541710290",
"status": "COMPLETED",
"status_changed_ts": "1611940542086071",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45004",
"builder:linux-chromeos-rel",
"buildset:commit/git/e58043f3130dd13460a3b937b466ebffd4e05399",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e58043f3130dd13460a3b937b466ebffd4e05399",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022181673253562480",
"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/8856751611070116768/+/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:5167f55fec907210",
"user_agent:luci-scheduler"
],
"updated_ts": "1611943461348587",
"url": "https://ci.chromium.org/b/8856751611070116768",
"utcnow_ts": "1611943461755381"
}
}
[18:04:21.766] Invocation finished in 49m39.29244828s with status FAILED