[16:37:33.213] New invocation is queued and will start shortly
[16:37:34.318] Starting the invocation (attempt 1)
[16:37:34.353] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:37:34.354] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:37:34.354] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026804456112813040",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9026804456112813040",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405",
"gitiles_ref:refs/heads/master"
]
}
[16:37:34.962] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607531854461277",
"id": "8861374394135112592",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607531854860061",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43578",
"builder:linux-chromeos-rel",
"buildset:commit/git/ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026804456112813040",
"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/8861374394135112592/+/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": "1607531854860209",
"url": "https://ci.chromium.org/b/8861374394135112592",
"utcnow_ts": "1607531854951919"
}
}
[16:37:34.962] Task URL: https://ci.chromium.org/b/8861374394135112592
[16:37:34.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:2:0) after 1m0s
[16:37:46.785] Received PubSub notification, asking Buildbucket for the build status
[16:37:47.331] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:35.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:2:0)
[16:38:35.077] Timer tick, asking Buildbucket for the build status
[16:38:35.284] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:35.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:4:0) after 1m0s
[16:39:35.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:4:0)
[16:39:35.391] Timer tick, asking Buildbucket for the build status
[16:39:35.506] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:35.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:5:0) after 1m0s
[16:40:35.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:5:0)
[16:40:35.984] Timer tick, asking Buildbucket for the build status
[16:40:36.042] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:36.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:6:0) after 1m0s
[16:41:36.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:6:0)
[16:41:36.295] Timer tick, asking Buildbucket for the build status
[16:41:36.369] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:36.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:7:0) after 1m0s
[16:42:36.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:7:0)
[16:42:36.708] Timer tick, asking Buildbucket for the build status
[16:42:41.441] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:41.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:8:0) after 1m0s
[16:43:41.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:8:0)
[16:43:41.519] Timer tick, asking Buildbucket for the build status
[16:43:41.665] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:41.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:9:0) after 1m0s
[16:44:41.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:9:0)
[16:44:41.689] Timer tick, asking Buildbucket for the build status
[16:44:41.781] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:41.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:10:0) after 1m0s
[16:45:41.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:10:0)
[16:45:41.796] Timer tick, asking Buildbucket for the build status
[16:45:41.903] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:41.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:11:0) after 1m0s
[16:46:41.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:11:0)
[16:46:41.948] Timer tick, asking Buildbucket for the build status
[16:46:42.025] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:42.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:12:0) after 1m0s
[16:47:42.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:12:0)
[16:47:42.053] Timer tick, asking Buildbucket for the build status
[16:47:43.017] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:43.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:13:0) after 1m0s
[16:48:43.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:13:0)
[16:48:43.045] Timer tick, asking Buildbucket for the build status
[16:48:43.363] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:43.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:14:0) after 1m0s
[16:49:43.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:14:0)
[16:49:43.444] Timer tick, asking Buildbucket for the build status
[16:49:43.628] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:43.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:15:0) after 1m0s
[16:50:43.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:15:0)
[16:50:43.727] Timer tick, asking Buildbucket for the build status
[16:50:43.786] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:43.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:16:0) after 1m0s
[16:51:43.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:16:0)
[16:51:43.804] Timer tick, asking Buildbucket for the build status
[16:51:43.879] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:43.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:17:0) after 1m0s
[16:52:43.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:17:0)
[16:52:43.904] Timer tick, asking Buildbucket for the build status
[16:52:44.033] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:44.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:18:0) after 1m0s
[16:53:44.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:18:0)
[16:53:44.013] Timer tick, asking Buildbucket for the build status
[16:53:44.108] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:44.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:19:0) after 1m0s
[16:54:44.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:19:0)
[16:54:44.181] Timer tick, asking Buildbucket for the build status
[16:54:44.252] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:44.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:20:0) after 1m0s
[16:55:44.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:20:0)
[16:55:44.231] Timer tick, asking Buildbucket for the build status
[16:55:44.295] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:44.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:21:0) after 1m0s
[16:56:44.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:21:0)
[16:56:44.361] Timer tick, asking Buildbucket for the build status
[16:56:44.447] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:44.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:22:0) after 1m0s
[16:57:44.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:22:0)
[16:57:44.478] Timer tick, asking Buildbucket for the build status
[16:57:44.672] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:44.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:23:0) after 1m0s
[16:58:44.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:23:0)
[16:58:44.691] Timer tick, asking Buildbucket for the build status
[16:58:44.757] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:44.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:24:0) after 1m0s
[16:59:44.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:24:0)
[16:59:44.763] Timer tick, asking Buildbucket for the build status
[16:59:44.835] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:44.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:25:0) after 1m0s
[17:00:44.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:25:0)
[17:00:44.858] Timer tick, asking Buildbucket for the build status
[17:00:44.930] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:44.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:26:0) after 1m0s
[17:01:44.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:26:0)
[17:01:44.943] Timer tick, asking Buildbucket for the build status
[17:01:45.002] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:45.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:27:0) after 1m0s
[17:02:45.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:27:0)
[17:02:45.028] Timer tick, asking Buildbucket for the build status
[17:02:45.137] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:45.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:28:0) after 1m0s
[17:03:45.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:28:0)
[17:03:45.403] Timer tick, asking Buildbucket for the build status
[17:03:45.459] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:45.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:29:0) after 1m0s
[17:04:45.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:29:0)
[17:04:45.620] Timer tick, asking Buildbucket for the build status
[17:04:45.733] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:45.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:30:0) after 1m0s
[17:05:45.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:30:0)
[17:05:45.952] Timer tick, asking Buildbucket for the build status
[17:05:46.241] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:46.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:31:0) after 1m0s
[17:06:46.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:31:0)
[17:06:46.444] Timer tick, asking Buildbucket for the build status
[17:06:46.564] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:46.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:32:0) after 1m0s
[17:07:46.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:32:0)
[17:07:46.779] Timer tick, asking Buildbucket for the build status
[17:07:47.258] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:47.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:33:0) after 1m0s
[17:08:47.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:33:0)
[17:08:47.295] Timer tick, asking Buildbucket for the build status
[17:08:47.420] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:47.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:34:0) after 1m0s
[17:09:47.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:34:0)
[17:09:47.508] Timer tick, asking Buildbucket for the build status
[17:09:47.596] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:47.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:35:0) after 1m0s
[17:10:47.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:35:0)
[17:10:47.685] Timer tick, asking Buildbucket for the build status
[17:10:47.753] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:47.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:36:0) after 1m0s
[17:11:47.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:36:0)
[17:11:47.865] Timer tick, asking Buildbucket for the build status
[17:11:48.014] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:48.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:37:0) after 1m0s
[17:12:48.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:37:0)
[17:12:48.092] Timer tick, asking Buildbucket for the build status
[17:12:48.310] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:48.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:38:0) after 1m0s
[17:13:48.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:38:0)
[17:13:48.364] Timer tick, asking Buildbucket for the build status
[17:13:48.487] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:48.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:39:0) after 1m0s
[17:14:48.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:39:0)
[17:14:48.574] Timer tick, asking Buildbucket for the build status
[17:14:48.661] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:48.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:40:0) after 1m0s
[17:15:48.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:40:0)
[17:15:48.802] Timer tick, asking Buildbucket for the build status
[17:15:48.931] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:48.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:41:0) after 1m0s
[17:16:49.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:41:0)
[17:16:49.012] Timer tick, asking Buildbucket for the build status
[17:16:49.210] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:49.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:42:0) after 1m0s
[17:17:49.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:42:0)
[17:17:49.327] Timer tick, asking Buildbucket for the build status
[17:17:49.441] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:49.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:43:0) after 1m0s
[17:18:49.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:43:0)
[17:18:49.451] Timer tick, asking Buildbucket for the build status
[17:18:49.516] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:49.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:44:0) after 1m0s
[17:19:49.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:44:0)
[17:19:49.513] Timer tick, asking Buildbucket for the build status
[17:19:49.586] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:49.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:45:0) after 1m0s
[17:20:49.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:45:0)
[17:20:49.728] Timer tick, asking Buildbucket for the build status
[17:20:49.789] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:49.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:46:0) after 1m0s
[17:21:49.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:46:0)
[17:21:49.804] Timer tick, asking Buildbucket for the build status
[17:21:50.029] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:50.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:47:0) after 1m0s
[17:22:50.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:47:0)
[17:22:50.056] Timer tick, asking Buildbucket for the build status
[17:22:50.222] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:50.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:48:0) after 1m0s
[17:23:50.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:48:0)
[17:23:50.219] Timer tick, asking Buildbucket for the build status
[17:23:50.325] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:50.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:49:0) after 1m0s
[17:24:50.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:49:0)
[17:24:50.366] Timer tick, asking Buildbucket for the build status
[17:24:50.498] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:50.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:50:0) after 1m0s
[17:25:50.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:50:0)
[17:25:50.536] Timer tick, asking Buildbucket for the build status
[17:25:50.803] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:50.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:51:0) after 1m0s
[17:26:50.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:51:0)
[17:26:50.849] Timer tick, asking Buildbucket for the build status
[17:26:50.987] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:50.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:52:0) after 1m0s
[17:27:50.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:52:0)
[17:27:50.984] Timer tick, asking Buildbucket for the build status
[17:27:51.926] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:51.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:53:0) after 1m0s
[17:28:52.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:53:0)
[17:28:52.138] Timer tick, asking Buildbucket for the build status
[17:28:52.277] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:52.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:54:0) after 1m0s
[17:29:52.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:54:0)
[17:29:52.319] Timer tick, asking Buildbucket for the build status
[17:29:52.446] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:52.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:55:0) after 1m0s
[17:30:52.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:55:0)
[17:30:52.543] Timer tick, asking Buildbucket for the build status
[17:30:53.252] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:53.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:56:0) after 1m0s
[17:31:53.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:56:0)
[17:31:53.293] Timer tick, asking Buildbucket for the build status
[17:31:53.470] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:53.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:57:0) after 1m0s
[17:32:53.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:57:0)
[17:32:53.506] Timer tick, asking Buildbucket for the build status
[17:32:54.013] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:54.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:58:0) after 1m0s
[17:33:54.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:58:0)
[17:33:54.077] Timer tick, asking Buildbucket for the build status
[17:33:54.505] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:54.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:59:0) after 1m0s
[17:34:54.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:59:0)
[17:34:54.528] Timer tick, asking Buildbucket for the build status
[17:34:55.726] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:55.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:60:0) after 1m0s
[17:35:55.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:60:0)
[17:35:55.793] Timer tick, asking Buildbucket for the build status
[17:35:56.480] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:56.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:61:0) after 1m0s
[17:36:56.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:61:0)
[17:36:56.530] Timer tick, asking Buildbucket for the build status
[17:36:56.637] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:56.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:62:0) after 1m0s
[17:37:56.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:62:0)
[17:37:56.663] Timer tick, asking Buildbucket for the build status
[17:37:56.871] Build 8861374394135112592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:56.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026804456112813040:63:0) after 1m0s
[17:37:57.033] Received PubSub notification, asking Buildbucket for the build status
[17:37:57.140] Build 8861374394135112592: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:37:57.140] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607535475020572",
"created_by": "project:chromium",
"created_ts": "1607531854461277",
"failure_reason": "BUILD_FAILURE",
"id": "8861374394135112592",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- FileDialog/FilesAppBrowserTest.Test/openFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/openFileDialogUnload\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405\", \"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-12-79fr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43578, \"got_angle_revision\": \"f0f79e08366208f15f05c909bc7b81a1198e261f\", \"got_dawn_revision\": \"30805557e65102253a77aadb93ea02fd62dde142\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405\", \"got_revision_cp\": \"refs/heads/master@{#835211}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405\", \"swarm_hashes_refs/heads/master(at){#835211}_without_patch\": {\"absl_hardening_tests\": \"9d3e8c77cd642147864ed6271eccc2f719224dd8\", \"accessibility_unittests\": \"aeeb6b1e1e1917ff212cdc0a5d63d5759198e30b\", \"angle_unittests\": \"8d2df2bdfea6102fddba8cb08fe3188c47367e1f\", \"app_list_unittests\": \"919c8b1346dfd7def8e61c0eca205e8759e270a6\", \"app_shell_unittests\": \"eb9043809cf36206ec53431f4567a25c4f5a02fc\", \"ash_unittests\": \"4a2bc37599983bdab8996990bb41fc86d2743d72\", \"aura_unittests\": \"eff30ee8271ac0e9adb9eeda1625e94ae0a66f96\", \"base_unittests\": \"d292d240c4f45737c8e205da6a0b9fabefafd656\", \"base_util_unittests\": \"3b9e0d5625fa6f8ecfe49bf95e762a2ed4f41b62\", \"blink_common_unittests\": \"f0921b88cbebc88241d1f1335a73780f620382ea\", \"blink_fuzzer_unittests\": \"b8e1570a0e5b7342174c7ab7f206596e42f60d31\", \"blink_heap_unittests\": \"56025eb3148f40c41cbe3d811fe1bb5d904af3aa\", \"blink_platform_unittests\": \"410087e11c1437b957c80f955c03a5afdb8e29e1\", \"blink_unittests\": \"7ad09fb68ae0447f63f720bba838661cfd8dc48e\", \"boringssl_crypto_tests\": \"2ea01100d054770bd341d79605f973f14c6d4b54\", \"boringssl_ssl_tests\": \"57ecfc145d69da304fadc50323207844bf560e0a\", \"browser_tests\": \"01de00c4a90ccb8fc0a2a70cae3ade7e55940b37\", \"capture_unittests\": \"c6ba36aee13fa6ccfae6f7a021033e423952845b\", \"cast_unittests\": \"b4fdd8ebd88571b60251b8401899c98599097279\", \"cc_unittests\": \"a7349a7704c1a09c2a1aa8ecdafcf2e4df823ab1\", \"chrome_app_unittests\": \"95e78ab7ee2f62c93e6b417bf64c721128d3a22e\", \"chromedriver_unittests\": \"493a04b8a7b9e4cb192ecf82ad00aa4455f2650d\", \"chromeos_components_unittests\": \"3a86e5ade291e1bdce06dff1eaf058e0a1db1e03\", \"chromeos_unittests\": \"688a97d70e347397bc0b367d6b0db63e8a5ba141\", \"components_browsertests\": \"e0b3305e9f93bfb2373a524824ab37067a50db4c\", \"components_unittests\": \"36da76cdbf50a21a5c3f7aa6710d7ec7d52d19a2\", \"compositor_unittests\": \"cc9954a98cbc0eae03f6de5088a3a32dd9f7e2c2\", \"content_browsertests\": \"9e9aec4d0348103042ae5225fa5791311447e23c\", \"content_unittests\": \"0aa7d0dd81ac19c589292b1268ea95b1d625483b\", \"crashpad_tests\": \"eac5dab67528eaf213b3f79905b167145c61b533\", \"crypto_unittests\": \"44658dedbf78e595bf0df913b43777aa66300d52\", \"dbus_unittests\": \"13ad873e3058779792a8cc4b99ae57e320883641\", \"device_unittests\": \"64f09b2d713e24be14afcce6f2278c053aa451f9\", \"display_unittests\": \"cdd562a14bbeadcea9ddb0d7921c329918fa3231\", \"events_unittests\": \"b38f7c2a649fb444e6432188e4149db8052a268c\", \"exo_unittests\": \"c6b5e0ef2c5215ceb5ebabc5224dfc477caab811\", \"extensions_browsertests\": \"8d088e7e796b6fdfac2386c4508e317aafd5a458\", \"extensions_unittests\": \"421239d1385702587a5553857f26997c643242ac\", \"filesystem_service_unittests\": \"86ea585dbab29b28ab0e0e7fc6af5ec7318dae6d\", \"gcm_unit_tests\": \"34b7452c68d315e3e2b10703e07e6ce61cd36d9e\", \"gfx_unittests\": \"1f01e2e5577e3992e5b53704b5622b64c1d02423\", \"gin_unittests\": \"045b4de7bea6de256708059815d5c91a7d6a5be7\", \"gl_unittests_ozone\": \"e42a5f541f6d874ea4c9b1a211197c480b4313ab\", \"google_apis_unittests\": \"e0f014847b81c552d94bd203bd0c338cf62825a7\", \"gpu_unittests\": \"9fa010de55e2b7b56b36ff800a4e3d6bb2f59045\", \"gwp_asan_unittests\": \"ea801af0304fd359d022187ac7c27d79af70a6c0\", \"interactive_ui_tests\": \"d124000697100f2e6b1aedb1b7c497fc2c13ce12\", \"ipc_tests\": \"2929a5c1007fa6a74a095d51fc36e7e96075dee2\", \"jingle_unittests\": \"3569cfabd1f26fb42e740faf0fc1294dabb45fe7\", \"keyboard_unittests\": \"ba4c17e39bf5cb0b428214d0c883041f62a41db0\", \"latency_unittests\": \"613966c08b065908aa8bae7010ac0b287ea7bde2\", \"libjingle_xmpp_unittests\": \"fb55cb3e057767533622302073dee697413c2f97\", \"liburlpattern_unittests\": \"38d443babd3bb145cfc3bbc3b5a06d5b5f45d385\", \"media_blink_unittests\": \"e9040f0be4064a0658c3b4473b6e4d6865cfe84b\", \"media_unittests\": \"7a1c6cbe2840402740c966fc9c33fb53b1452833\", \"message_center_unittests\": \"983cd8b1881e92317959489348f137b4de981888\", \"midi_unittests\": \"95d735a6ea123cba520425a2033a09be334f47f5\", \"mojo_core_unittests\": \"7b77b82d28eb54707b1facb3a6d46de2b83a5f1b\", \"mojo_unittests\": \"40dd44985fa14048800c30b2cb4a1b1d97886f37\", \"nacl_helper_nonsfi_unittests\": \"781d74b94819256ac36ef09ef4846101a69180b9\", \"nacl_loader_unittests\": \"e64cdc3599aa55e51fe57a7029728fba8f5656a5\", \"native_theme_unittests\": \"66c498ba7c27fa021eff74a74f04ff803c11cf87\", \"net_unittests\": \"7b4cb15b1499cfdb23f828f4b837102e35d9dd33\", \"ozone_gl_unittests\": \"b9d291a48ff481f6969c9d83a82009bf918395cd\", \"ozone_unittests\": \"4cc1ecb5b5d236fb1561cecba5a02e241b0cc8c9\", \"ozone_x11_unittests\": \"3bf84253779430fd1e0a311b00d1a654f4e790d2\", \"pdf_unittests\": \"1915bc0e83e82bd390ff4907478a04bf83618978\", \"perfetto_unittests\": \"c96a3edb02e3101b28c96ed9909104dfb7c67d14\", \"ppapi_unittests\": \"ff063abfab0643f861fb1ca11573ba9f62490764\", \"printing_unittests\": \"2ae7f4bf79cff57a50475415be06041a08397723\", \"remoting_unittests\": \"b860b05aaa39f454632b635c7b88b5f81f6d57a3\", \"sandbox_linux_unittests\": \"12f575b57c986105167450f74bb49457ae7f1783\", \"service_manager_unittests\": \"7c246780f7edd036d01859938ce110b48c5428b1\", \"services_unittests\": \"ec04c708cea6eeb7e4e9832371f7b0167c883f1a\", \"shell_dialogs_unittests\": \"0b4c91c46105d77da0fee0f494efe44e9cd6eddb\", \"shell_encryption_unittests\": \"b827c1f3383b7bd922b85174f1d31c6a659cc73a\", \"skia_unittests\": \"c86140c76fc6246fab90db25866c6332b3b42535\", \"snapshot_unittests\": \"caed3850ae50375d780e87c1560b51df34282c7a\", \"sql_unittests\": \"315ddc0d8148c62b216166408600537c23b7414a\", \"storage_unittests\": \"9adb816358f08ced6102040bb666587cb52fc9c5\", \"sync_integration_tests\": \"19fdc60deceb23b89de38720e0e9814f906d7ce9\", \"traffic_annotation_auditor_unittests\": \"e3003b0ded262fa80a680e1d67d0a5463f47f0e0\", \"ui_base_unittests\": \"00a9f1c07a96540a86b7dc3f8de9ec750f534997\", \"ui_chromeos_unittests\": \"660828aa0ac87e91cf626dcaad7de36896e84a8e\", \"ui_touch_selection_unittests\": \"f4e36333bf257a9025bf5946bc719e423bbceb90\", \"unit_tests\": \"fba1b232e1a7554c401e74935cac93cdf34680ae\", \"url_unittests\": \"a54456f6d19ea07c5defd0c4a31000b1b19ced05\", \"usage_time_limit_unittests\": \"adaa3194165916291a8b3a2141e49ec26201511d\", \"views_unittests\": \"2655bc87e36208cc57eb2e0302462c899acdb417\", \"viz_unittests\": \"0331670a9b1304102a69d0b70c30bf7b55ce6e33\", \"wayland_client_perftests\": \"0880cc79d311b20241a274c0eb76adbc9255b1a0\", \"wm_unittests\": \"bbcb2268ee9a15cdccd68f4f3ec1754d4c8ddb88\", \"wtf_unittests\": \"cf4a0b4059f749414fed18fcaecec5a285e29b0f\", \"zlib_unittests\": \"ad7f6e33a3439998d002c7e6ad270096aae81950\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_08506e22a0e0fdc29bd318165699b599f0671cf91287ea7a96642b02d7ff24e4_v2\", \"builder_219e0ffc8c25f5e0b87c50b06543131c770ba4c60d610054ab97561314494a41_v2\", \"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_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-12-79fr\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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\": [\"5640-d0eb544\"], \"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- FileDialog/FilesAppBrowserTest.Test/openFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/openFileDialogUnload\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607531866230316",
"status": "COMPLETED",
"status_changed_ts": "1607531866628104",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43578",
"builder:linux-chromeos-rel",
"buildset:commit/git/ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ac8dbaf66d238c8dae15fa65f9ec1486c1ab9405",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026804456112813040",
"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/8861374394135112592/+/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:50612f05923a8c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607535476640126",
"url": "https://ci.chromium.org/b/8861374394135112592",
"utcnow_ts": "1607535477122446"
}
}
[17:37:57.142] Invocation finished in 1h0m23.950868022s with status FAILED