[12:16:09.816] New invocation is queued and will start shortly
[12:16:10.954] Starting the invocation (attempt 1)
[12:16:10.987] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:16:10.987] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:16:10.987] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019301353128457632",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"88fc12032afdb8f71004e6bf997ef255f579c38e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019301353128457632",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/88fc12032afdb8f71004e6bf997ef255f579c38e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88fc12032afdb8f71004e6bf997ef255f579c38e",
"gitiles_ref:refs/heads/master"
]
}
[12:16:11.572] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614687371098498",
"id": "8853871291121963808",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88fc12032afdb8f71004e6bf997ef255f579c38e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614687371443391",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45840",
"builder:linux-chromeos-rel",
"buildset:commit/git/88fc12032afdb8f71004e6bf997ef255f579c38e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88fc12032afdb8f71004e6bf997ef255f579c38e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019301353128457632",
"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/8853871291121963808/+/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": "1614687371443490",
"url": "https://ci.chromium.org/b/8853871291121963808",
"utcnow_ts": "1614687371566545"
}
}
[12:16:11.572] Task URL: https://ci.chromium.org/b/8853871291121963808
[12:16:11.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:2:0) after 1m0s
[12:16:16.383] Received PubSub notification, asking Buildbucket for the build status
[12:16:16.534] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:11.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:2:0)
[12:17:11.559] Timer tick, asking Buildbucket for the build status
[12:17:11.635] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:11.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:4:0) after 1m0s
[12:18:11.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:4:0)
[12:18:11.724] Timer tick, asking Buildbucket for the build status
[12:18:11.791] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:11.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:5:0) after 1m0s
[12:19:11.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:5:0)
[12:19:11.855] Timer tick, asking Buildbucket for the build status
[12:19:11.939] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:11.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:6:0) after 1m0s
[12:20:12.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:6:0)
[12:20:12.030] Timer tick, asking Buildbucket for the build status
[12:20:12.161] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:12.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:7:0) after 1m0s
[12:21:12.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:7:0)
[12:21:12.492] Timer tick, asking Buildbucket for the build status
[12:21:12.567] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:12.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:8:0) after 1m0s
[12:22:12.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:8:0)
[12:22:12.952] Timer tick, asking Buildbucket for the build status
[12:22:13.223] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:13.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:9:0) after 1m0s
[12:23:13.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:9:0)
[12:23:13.437] Timer tick, asking Buildbucket for the build status
[12:23:13.524] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:13.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:10:0) after 1m0s
[12:24:13.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:10:0)
[12:24:13.721] Timer tick, asking Buildbucket for the build status
[12:24:13.827] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:13.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:11:0) after 1m0s
[12:25:14.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:11:0)
[12:25:14.000] Timer tick, asking Buildbucket for the build status
[12:25:14.117] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:14.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:12:0) after 1m0s
[12:26:14.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:12:0)
[12:26:14.204] Timer tick, asking Buildbucket for the build status
[12:26:14.341] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:14.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:13:0) after 1m0s
[12:27:14.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:13:0)
[12:27:14.398] Timer tick, asking Buildbucket for the build status
[12:27:15.236] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:15.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:14:0) after 1m0s
[12:28:15.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:14:0)
[12:28:15.291] Timer tick, asking Buildbucket for the build status
[12:28:15.385] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:15.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:15:0) after 1m0s
[12:29:15.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:15:0)
[12:29:15.412] Timer tick, asking Buildbucket for the build status
[12:29:15.492] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:15.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:16:0) after 1m0s
[12:30:15.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:16:0)
[12:30:15.533] Timer tick, asking Buildbucket for the build status
[12:30:15.605] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:15.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:17:0) after 1m0s
[12:31:15.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:17:0)
[12:31:15.686] Timer tick, asking Buildbucket for the build status
[12:31:15.751] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:15.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:18:0) after 1m0s
[12:32:15.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:18:0)
[12:32:15.805] Timer tick, asking Buildbucket for the build status
[12:32:15.891] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:15.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:19:0) after 1m0s
[12:33:15.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:19:0)
[12:33:15.904] Timer tick, asking Buildbucket for the build status
[12:33:16.105] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:16.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:20:0) after 1m0s
[12:34:16.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:20:0)
[12:34:16.076] Timer tick, asking Buildbucket for the build status
[12:34:16.247] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:16.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:21:0) after 1m0s
[12:35:16.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:21:0)
[12:35:16.539] Timer tick, asking Buildbucket for the build status
[12:35:16.693] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:16.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:22:0) after 1m0s
[12:36:16.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:22:0)
[12:36:16.736] Timer tick, asking Buildbucket for the build status
[12:36:16.814] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:16.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:23:0) after 1m0s
[12:37:18.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:23:0)
[12:37:18.835] Timer tick, asking Buildbucket for the build status
[12:37:19.111] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:19.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:24:0) after 1m0s
[12:38:19.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:24:0)
[12:38:19.436] Timer tick, asking Buildbucket for the build status
[12:38:19.525] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:19.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:25:0) after 1m0s
[12:39:19.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:25:0)
[12:39:19.541] Timer tick, asking Buildbucket for the build status
[12:39:19.628] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:19.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:26:0) after 1m0s
[12:40:19.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:26:0)
[12:40:19.603] Timer tick, asking Buildbucket for the build status
[12:40:19.755] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:19.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:27:0) after 1m0s
[12:41:19.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:27:0)
[12:41:19.800] Timer tick, asking Buildbucket for the build status
[12:41:19.891] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:19.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:28:0) after 1m0s
[12:42:19.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:28:0)
[12:42:19.920] Timer tick, asking Buildbucket for the build status
[12:42:20.058] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:20.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:29:0) after 1m0s
[12:43:20.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:29:0)
[12:43:20.049] Timer tick, asking Buildbucket for the build status
[12:43:20.230] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:20.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:30:0) after 1m0s
[12:44:20.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:30:0)
[12:44:20.276] Timer tick, asking Buildbucket for the build status
[12:44:20.402] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:20.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:31:0) after 1m0s
[12:45:20.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:31:0)
[12:45:20.459] Timer tick, asking Buildbucket for the build status
[12:45:20.648] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:20.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:32:0) after 1m0s
[12:46:20.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:32:0)
[12:46:20.695] Timer tick, asking Buildbucket for the build status
[12:46:20.757] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:20.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:33:0) after 1m0s
[12:47:20.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:33:0)
[12:47:20.957] Timer tick, asking Buildbucket for the build status
[12:47:21.038] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:21.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:34:0) after 1m0s
[12:48:21.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:34:0)
[12:48:21.593] Timer tick, asking Buildbucket for the build status
[12:48:21.657] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:21.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:35:0) after 1m0s
[12:49:22.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:35:0)
[12:49:22.489] Timer tick, asking Buildbucket for the build status
[12:49:26.028] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:26.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:36:0) after 1m0s
[12:50:26.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:36:0)
[12:50:26.626] Timer tick, asking Buildbucket for the build status
[12:50:26.696] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:26.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:37:0) after 1m0s
[12:51:26.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:37:0)
[12:51:26.775] Timer tick, asking Buildbucket for the build status
[12:51:26.832] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:26.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:38:0) after 1m0s
[12:52:27.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:38:0)
[12:52:27.031] Timer tick, asking Buildbucket for the build status
[12:52:27.153] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:27.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:39:0) after 1m0s
[12:53:27.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:39:0)
[12:53:27.598] Timer tick, asking Buildbucket for the build status
[12:53:27.678] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:27.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:40:0) after 1m0s
[12:54:27.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:40:0)
[12:54:27.671] Timer tick, asking Buildbucket for the build status
[12:54:27.827] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:27.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:41:0) after 1m0s
[12:55:27.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:41:0)
[12:55:27.878] Timer tick, asking Buildbucket for the build status
[12:55:27.949] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:27.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:42:0) after 1m0s
[12:56:28.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:42:0)
[12:56:28.264] Timer tick, asking Buildbucket for the build status
[12:56:28.430] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:28.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:43:0) after 1m0s
[12:57:28.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:43:0)
[12:57:28.691] Timer tick, asking Buildbucket for the build status
[12:57:28.772] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:28.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:44:0) after 1m0s
[12:58:28.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:44:0)
[12:58:28.928] Timer tick, asking Buildbucket for the build status
[12:58:29.002] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:29.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:45:0) after 1m0s
[12:59:29.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:45:0)
[12:59:29.128] Timer tick, asking Buildbucket for the build status
[12:59:29.219] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:29.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:46:0) after 1m0s
[13:00:29.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:46:0)
[13:00:29.332] Timer tick, asking Buildbucket for the build status
[13:00:29.524] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:29.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:47:0) after 1m0s
[13:01:29.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:47:0)
[13:01:29.547] Timer tick, asking Buildbucket for the build status
[13:01:29.660] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:29.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:48:0) after 1m0s
[13:02:29.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:48:0)
[13:02:29.729] Timer tick, asking Buildbucket for the build status
[13:02:29.811] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:29.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:49:0) after 1m0s
[13:03:29.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:49:0)
[13:03:29.958] Timer tick, asking Buildbucket for the build status
[13:03:30.163] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:30.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:50:0) after 1m0s
[13:04:30.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:50:0)
[13:04:30.295] Timer tick, asking Buildbucket for the build status
[13:04:30.376] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:30.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:51:0) after 1m0s
[13:05:30.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:51:0)
[13:05:30.507] Timer tick, asking Buildbucket for the build status
[13:05:30.598] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:30.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:52:0) after 1m0s
[13:06:30.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:52:0)
[13:06:30.663] Timer tick, asking Buildbucket for the build status
[13:06:30.744] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:30.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:53:0) after 1m0s
[13:07:30.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:53:0)
[13:07:30.766] Timer tick, asking Buildbucket for the build status
[13:07:30.882] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:30.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:54:0) after 1m0s
[13:08:30.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:54:0)
[13:08:30.898] Timer tick, asking Buildbucket for the build status
[13:08:31.214] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:31.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:55:0) after 1m0s
[13:09:31.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:55:0)
[13:09:31.412] Timer tick, asking Buildbucket for the build status
[13:09:31.538] Build 8853871291121963808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:31.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019301353128457632:56:0) after 1m0s
[13:09:38.849] Received PubSub notification, asking Buildbucket for the build status
[13:09:39.060] Build 8853871291121963808: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:09:39.061] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614690578324612",
"created_by": "project:chromium",
"created_ts": "1614687371098498",
"failure_reason": "BUILD_FAILURE",
"id": "8853871291121963808",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88fc12032afdb8f71004e6bf997ef255f579c38e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- BackForwardCachePageLoadMetricsObserverBrowserTest.RequestAnimationFramesAfterBackForwardCacheRestore\"}, \"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\": \"88fc12032afdb8f71004e6bf997ef255f579c38e\", \"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-east1-d-8-48-l0pn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45840, \"got_angle_revision\": \"6b6da203cce03287ef269846d54e77a8e871e221\", \"got_dawn_revision\": \"ec3f48242250668ca510ceef7336cf93ba80faa5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"88fc12032afdb8f71004e6bf997ef255f579c38e\", \"got_revision_cp\": \"refs/heads/master@{#858960}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"e354dd1ed9d1e7e05e1365116b09a5a232ae8a86\", \"got_v8_revision_cp\": \"refs/heads/9.1.13@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"88fc12032afdb8f71004e6bf997ef255f579c38e\", \"swarm_hashes_refs/heads/master(at){#858960}_without_patch\": {\"absl_hardening_tests\": \"929f1dde61f4931e84a4b19754cd1027252ac611\", \"accessibility_unittests\": \"b3ab056e7856bcace4f9cb3205d3fd563f2949fa\", \"angle_unittests\": \"df1522fa122c7c4d1daad727296c6a46069eaf25\", \"app_list_unittests\": \"a7e74c9ac6f484ec47c6cedac867a1b30fb5047c\", \"app_shell_unittests\": \"2ca492b2a0b5beef8a3bf89188eb0b29d5712a9b\", \"ash_unittests\": \"6394b373667827444482f2178defd2c7deebf472\", \"aura_unittests\": \"668eea64402a27db3fcace02e9c912fbefb2d2b4\", \"base_unittests\": \"1f5cf45c56148d75f383000796e6a8ba10f76b1f\", \"base_util_unittests\": \"0dd851d2afa0cad8057e4bdbc393e4c3670384fd\", \"blink_common_unittests\": \"e2fe1468f5d7eda4dc4b155554729d73a573bb52\", \"blink_fuzzer_unittests\": \"645fa4e950004251709b22a4f751e3907509d718\", \"blink_heap_unittests\": \"07000f3d24686fbb1508af772bb1948570d1fbc8\", \"blink_platform_unittests\": \"b0bfd2f0967c339ce0a32f9d00d5f72df79cc3b2\", \"blink_unittests\": \"f11e530200e0c1a804d2d89f41e327f25860cca2\", \"boringssl_crypto_tests\": \"8e2626ab5eb4370588c3984c824c39e4f7ceb574\", \"boringssl_ssl_tests\": \"bf6b02cf686f5d709e645836264e6cfd4f9a0eec\", \"browser_tests\": \"7698a2a44f685629ac1410f6de833d71c608333c\", \"capture_unittests\": \"6adb17f08ec60ac4ffcef36560f127707d339499\", \"cast_unittests\": \"305e6083892597d60b38994c52a7112952da4f8c\", \"cc_unittests\": \"7658148879cbf0812905ba7f7eb89461cce66750\", \"chrome_app_unittests\": \"c894fa676f0475426b9bc6ccdaaa5a26891c5032\", \"chromedriver_unittests\": \"6d4bfdaa630b3ceb061eac4154288b2793568a53\", \"chromeos_components_unittests\": \"79e433e90037c7a91e39cb0ad2cd57353ebe4a79\", \"chromeos_unittests\": \"e53ffcff7bb54761555cec9956836ff07df2190f\", \"color_unittests\": \"6518720462877c3c5f278bc6d331fc8526880a1b\", \"components_browsertests\": \"f378af3fe6ce86ad112d86e34881cdfa41fe0c55\", \"components_unittests\": \"0be00acb2bb7ac57366a6efd41ce8b7731266ab0\", \"compositor_unittests\": \"240bcc3c7ea0d2a2138ec29a763db7a44224369c\", \"content_browsertests\": \"330d3084bbb4ba335b3e972c39f8d05ec08a64cc\", \"content_unittests\": \"091970df83f83b3fa305bcaac7408abb50548bbd\", \"crashpad_tests\": \"8f34f540ea96b552deeab0254a4a3a3cf738f5f6\", \"crypto_unittests\": \"d618c14b8452a9f66bedf5476b172aaf53bfb8c3\", \"dbus_unittests\": \"ceb1cf698059f7528a70fa4bdd44f88409767be1\", \"device_unittests\": \"c0b84a64f55b89b4a65a1a7d51f842a25dcaa967\", \"display_unittests\": \"2bb9ec23d2ab577a8166f4df29fdaa039fc87dd9\", \"events_unittests\": \"95a21ceba43732db65bb04b64c5bc9b48bb1069c\", \"exo_unittests\": \"93b612017aa7a1b8cb92cbffb16c47da2d800c85\", \"extensions_browsertests\": \"bc74aa4f44a5c116b72f63d97b57e3cf213200f1\", \"extensions_unittests\": \"11e660166730fc1db764fe3eb73a61500de7fa73\", \"filesystem_service_unittests\": \"776468dae24682c1351b42f2cf4d2d7132c2bdbd\", \"gcm_unit_tests\": \"755a58b2779eadff7f23efbac9b0de3e095f7bb4\", \"gfx_unittests\": \"50e522b467afbcf026f64d85b74f088fcf9d270e\", \"gin_unittests\": \"1bdc3861ee43807b159e3347141c484322619c0b\", \"gl_unittests_ozone\": \"01c390b0e40721c76bb2fff8071fc3899156183e\", \"google_apis_unittests\": \"f5c5ac71395ec3c820379da36e37e3b5576ccdbe\", \"gpu_unittests\": \"79588fd2f659963ffc37bdf08bac3325bb43198b\", \"gwp_asan_unittests\": \"c31e2696715d84e96d74151aca1b3a542d69a003\", \"interactive_ui_tests\": \"70bb691b2cb430c164615895af607080e36d2d0e\", \"ipc_tests\": \"9fb28a749976cb1585747bd8ccf26a505837a455\", \"jingle_unittests\": \"d1bab6361824f554247d7c8cdad5bad6782ef5c6\", \"keyboard_unittests\": \"ecbf3151ede46a09485ea7b0916df40d0f042fc5\", \"latency_unittests\": \"899e43eb37ef72088d4b8736bcb5bad57d5e4d35\", \"libjingle_xmpp_unittests\": \"1273fd6ffd72e58d2f89ddd0fccb34842fd29b4e\", \"liburlpattern_unittests\": \"e4eb0233fc6b48447eb6b77ffabf93d9e3d7fabf\", \"media_blink_unittests\": \"f8ed51b133e9ba4760278c32061d9950c498701c\", \"media_unittests\": \"6fc4461b5834df09389d818d235fa4b9b1debc4f\", \"message_center_unittests\": \"dd855858162554aabecd855962ddb824dab6cb83\", \"midi_unittests\": \"5341be1a104491272787a4cbedd00d8edfc3a9fa\", \"mojo_core_unittests\": \"a4d9bb6cb50fd2464a6988883a6e26c1c203c4a3\", \"mojo_unittests\": \"2a7d898185169d4a252704c7c4b1def4ba31a28b\", \"nacl_helper_nonsfi_unittests\": \"962a7e9af637643d6fb2d76177574d7c37bb8b6e\", \"nacl_loader_unittests\": \"423c7008768608ddefe79087e9ad84098bf25f70\", \"native_theme_unittests\": \"d048b60b3b759ca0d7bff40ac63f63d0b952f1d7\", \"net_unittests\": \"728b5b7ae0e847b45b21669a097119744355bb2f\", \"ozone_gl_unittests\": \"f1cf7e819257882cfe52ba10a45c6614b9a4ae01\", \"ozone_unittests\": \"aa55ab1c9bba4c31fb0f9554b70641c0dfd7e469\", \"ozone_x11_unittests\": \"89bb65116ddf9e2b870d9924b79f32820a63b299\", \"pdf_unittests\": \"ded888e1abed72ac8c01414d004831b535fe25d9\", \"perfetto_unittests\": \"9fbee8f815ce6e7b13fe926d2dbddd1a2950c28e\", \"ppapi_unittests\": \"d1b081ef4e20e5f4d6af4be472420407b91bce7c\", \"printing_unittests\": \"e0e4d9e5cabf7f14ea7f4e4ba800535f312d408d\", \"remoting_unittests\": \"f7d4efc367a05a62e6c9325e38abceddfacc0990\", \"sandbox_linux_unittests\": \"e6ffe02aa4b2724c7ecaa72d213344eda1fb5f7a\", \"service_manager_unittests\": \"2099ce6d684286a6df0d26514ce0b1b4b35d4f4d\", \"services_unittests\": \"9cabab15af7bc46ece3dd29ce8d7d40f4ad83434\", \"shell_dialogs_unittests\": \"05f1c088ac162a80a33a908675ddc7c02fbe7a27\", \"shell_encryption_unittests\": \"985353019a6e903e741adfe896a93b991af5c0e3\", \"skia_unittests\": \"d5debb7149d7ef87b89c286c0c92ff099a15b955\", \"snapshot_unittests\": \"c09ba95754a507e7e352fbf7adf9a89a4edb2fed\", \"sql_unittests\": \"188bbd4d7ae0536ac8c64f69765838ec91d03ea7\", \"storage_unittests\": \"9888fa2471caab6532c1ddb1eea5267ddfbb9c8b\", \"sync_integration_tests\": \"ca921f02fa10c52028e47d30d819011c8e4b24cd\", \"traffic_annotation_auditor_unittests\": \"b5bd167184cbe8bc0fe0c36a2d51172684dbcf1b\", \"ui_base_unittests\": \"96cfc1372d3def8a73705c6d6118f3f0c50834eb\", \"ui_chromeos_unittests\": \"031de3d026aad8d7007ebefa0973613f4af60f6b\", \"ui_touch_selection_unittests\": \"d0ecbffdb71c4298c8d7934cc44d50574322b7c7\", \"unit_tests\": \"eafcb4ea24057dbb863af24aa948d2bd33a92ac0\", \"url_unittests\": \"fe452c3ea410d6a058a00d48b7370d9b557f494e\", \"usage_time_limit_unittests\": \"99ba512483c97a2734095d7fbf14b41afc5f3a17\", \"views_unittests\": \"b093114742cac5654c7d647de3e53d6869944d81\", \"viz_unittests\": \"2d3d9b214b5c8208bb1eff11c925b02f2ee995ba\", \"wayland_client_perftests\": \"044388a83037f3abd5b56b75a4803e93df605590\", \"wayland_client_tests\": \"892642648fb44d317746509a1c5f88e86d3ef1e9\", \"wm_unittests\": \"21bd6d0d537b48dd42c04554bc258bf4c5030d06\", \"wtf_unittests\": \"197b9d49d94a65c26f3afdc51b1549fe11fae06d\", \"zlib_unittests\": \"26f811adb430e92efed4c3ad17ef711909680cf6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2ca3d20e0118d26eb9e66e2d735ef34d968865a41231e326a4ca456c04a8665c_v2\", \"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_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-east1-d-8-48-l0pn\"], \"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\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- BackForwardCachePageLoadMetricsObserverBrowserTest.RequestAnimationFramesAfterBackForwardCacheRestore\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614687375859604",
"status": "COMPLETED",
"status_changed_ts": "1614687376211419",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45840",
"builder:linux-chromeos-rel",
"buildset:commit/git/88fc12032afdb8f71004e6bf997ef255f579c38e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88fc12032afdb8f71004e6bf997ef255f579c38e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019301353128457632",
"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/8853871291121963808/+/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:520baf8b44dc2110",
"user_agent:luci-scheduler"
],
"updated_ts": "1614690578325505",
"url": "https://ci.chromium.org/b/8853871291121963808",
"utcnow_ts": "1614690579043970"
}
}
[13:09:39.063] Invocation finished in 53m29.261618724s with status FAILED