[01:28:46.096] New invocation is queued and will start shortly
[01:28:47.266] Starting the invocation (attempt 1)
[01:28:47.322] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:28:47.322] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:28:47.323] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021788201816193648",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021788201816193648",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06",
"gitiles_ref:refs/heads/master"
]
}
[01:28:48.708] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612315727449926",
"id": "8856358139732238784",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612315728542228",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45096",
"builder:linux-chromeos-rel",
"buildset:commit/git/c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021788201816193648",
"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/8856358139732238784/+/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": "1612315728542335",
"url": "https://ci.chromium.org/b/8856358139732238784",
"utcnow_ts": "1612315728700622"
}
}
[01:28:48.708] Task URL: https://ci.chromium.org/b/8856358139732238784
[01:28:48.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:2:0) after 1m0s
[01:29:48.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:2:0)
[01:29:48.905] Timer tick, asking Buildbucket for the build status
[01:29:49.067] Build 8856358139732238784: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:49.067] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:3:0) after 1m0s
[01:30:49.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:3:0)
[01:30:49.303] Timer tick, asking Buildbucket for the build status
[01:30:49.387] Build 8856358139732238784: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:49.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:4:0) after 1m0s
[01:31:37.741] Received PubSub notification, asking Buildbucket for the build status
[01:31:37.826] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:49.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:4:0)
[01:31:49.455] Timer tick, asking Buildbucket for the build status
[01:31:49.701] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:49.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:6:0) after 1m0s
[01:32:49.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:6:0)
[01:32:49.776] Timer tick, asking Buildbucket for the build status
[01:32:49.964] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:49.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:7:0) after 1m0s
[01:33:49.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:7:0)
[01:33:49.981] Timer tick, asking Buildbucket for the build status
[01:33:50.243] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:50.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:8:0) after 1m0s
[01:34:50.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:8:0)
[01:34:50.260] Timer tick, asking Buildbucket for the build status
[01:34:50.377] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:50.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:9:0) after 1m0s
[01:35:50.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:9:0)
[01:35:50.371] Timer tick, asking Buildbucket for the build status
[01:35:50.658] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:50.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:10:0) after 1m0s
[01:36:50.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:10:0)
[01:36:50.687] Timer tick, asking Buildbucket for the build status
[01:36:51.145] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:51.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:11:0) after 1m0s
[01:37:51.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:11:0)
[01:37:51.155] Timer tick, asking Buildbucket for the build status
[01:37:51.437] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:51.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:12:0) after 1m0s
[01:38:51.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:12:0)
[01:38:51.818] Timer tick, asking Buildbucket for the build status
[01:38:51.915] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:51.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:13:0) after 1m0s
[01:39:52.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:13:0)
[01:39:52.215] Timer tick, asking Buildbucket for the build status
[01:39:52.403] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:52.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:14:0) after 1m0s
[01:40:52.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:14:0)
[01:40:52.676] Timer tick, asking Buildbucket for the build status
[01:40:52.742] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:52.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:15:0) after 1m0s
[01:41:52.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:15:0)
[01:41:52.843] Timer tick, asking Buildbucket for the build status
[01:41:53.968] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:53.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:16:0) after 1m0s
[01:42:54.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:16:0)
[01:42:54.153] Timer tick, asking Buildbucket for the build status
[01:42:54.209] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:54.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:17:0) after 1m0s
[01:43:54.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:17:0)
[01:43:54.227] Timer tick, asking Buildbucket for the build status
[01:43:54.321] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:54.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:18:0) after 1m0s
[01:44:54.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:18:0)
[01:44:54.389] Timer tick, asking Buildbucket for the build status
[01:44:54.710] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:54.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:19:0) after 1m0s
[01:45:54.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:19:0)
[01:45:54.762] Timer tick, asking Buildbucket for the build status
[01:45:54.820] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:54.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:20:0) after 1m0s
[01:46:54.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:20:0)
[01:46:54.896] Timer tick, asking Buildbucket for the build status
[01:46:55.202] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:55.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:21:0) after 1m0s
[01:47:55.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:21:0)
[01:47:55.239] Timer tick, asking Buildbucket for the build status
[01:47:55.426] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:55.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:22:0) after 1m0s
[01:48:55.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:22:0)
[01:48:55.433] Timer tick, asking Buildbucket for the build status
[01:48:55.526] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:55.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:23:0) after 1m0s
[01:49:55.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:23:0)
[01:49:55.625] Timer tick, asking Buildbucket for the build status
[01:49:55.682] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:55.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:24:0) after 1m0s
[01:50:55.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:24:0)
[01:50:55.775] Timer tick, asking Buildbucket for the build status
[01:50:56.062] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:56.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:25:0) after 1m0s
[01:51:56.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:25:0)
[01:51:56.520] Timer tick, asking Buildbucket for the build status
[01:51:56.807] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:56.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:26:0) after 1m0s
[01:52:56.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:26:0)
[01:52:56.879] Timer tick, asking Buildbucket for the build status
[01:52:56.954] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:56.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:27:0) after 1m0s
[01:53:56.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:27:0)
[01:53:56.969] Timer tick, asking Buildbucket for the build status
[01:53:57.320] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:57.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:28:0) after 1m0s
[01:54:57.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:28:0)
[01:54:57.808] Timer tick, asking Buildbucket for the build status
[01:54:57.878] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:57.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:29:0) after 1m0s
[01:55:57.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:29:0)
[01:55:57.888] Timer tick, asking Buildbucket for the build status
[01:55:58.127] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:58.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:30:0) after 1m0s
[01:56:58.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:30:0)
[01:56:58.213] Timer tick, asking Buildbucket for the build status
[01:56:58.286] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:58.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:31:0) after 1m0s
[01:57:58.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:31:0)
[01:57:58.372] Timer tick, asking Buildbucket for the build status
[01:57:58.492] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:58.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:32:0) after 1m0s
[01:58:58.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:32:0)
[01:58:58.550] Timer tick, asking Buildbucket for the build status
[01:58:58.656] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:58.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:33:0) after 1m0s
[01:59:58.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:33:0)
[01:59:58.733] Timer tick, asking Buildbucket for the build status
[01:59:59.131] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:59.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:34:0) after 1m0s
[02:00:59.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:34:0)
[02:00:59.145] Timer tick, asking Buildbucket for the build status
[02:00:59.211] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:59.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:35:0) after 1m0s
[02:01:59.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:35:0)
[02:01:59.252] Timer tick, asking Buildbucket for the build status
[02:01:59.310] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:59.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:36:0) after 1m0s
[02:02:59.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:36:0)
[02:02:59.345] Timer tick, asking Buildbucket for the build status
[02:02:59.421] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:59.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:37:0) after 1m0s
[02:03:59.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:37:0)
[02:03:59.447] Timer tick, asking Buildbucket for the build status
[02:03:59.546] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:59.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:38:0) after 1m0s
[02:04:59.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:38:0)
[02:04:59.539] Timer tick, asking Buildbucket for the build status
[02:04:59.604] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:59.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:39:0) after 1m0s
[02:05:59.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:39:0)
[02:05:59.636] Timer tick, asking Buildbucket for the build status
[02:05:59.699] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:59.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:40:0) after 1m0s
[02:06:59.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:40:0)
[02:06:59.764] Timer tick, asking Buildbucket for the build status
[02:06:59.858] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:59.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:41:0) after 1m0s
[02:07:59.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:41:0)
[02:07:59.878] Timer tick, asking Buildbucket for the build status
[02:07:59.938] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:59.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:42:0) after 1m0s
[02:08:59.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:42:0)
[02:08:59.957] Timer tick, asking Buildbucket for the build status
[02:09:00.099] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:00.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:43:0) after 1m0s
[02:10:00.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:43:0)
[02:10:00.137] Timer tick, asking Buildbucket for the build status
[02:10:00.254] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:00.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:44:0) after 1m0s
[02:11:00.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:44:0)
[02:11:00.273] Timer tick, asking Buildbucket for the build status
[02:11:00.600] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:00.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:45:0) after 1m0s
[02:12:00.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:45:0)
[02:12:00.620] Timer tick, asking Buildbucket for the build status
[02:12:00.793] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:00.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:46:0) after 1m0s
[02:13:01.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:46:0)
[02:13:01.393] Timer tick, asking Buildbucket for the build status
[02:13:01.614] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:01.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:47:0) after 1m0s
[02:14:02.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:47:0)
[02:14:02.200] Timer tick, asking Buildbucket for the build status
[02:14:02.388] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:02.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:48:0) after 1m0s
[02:15:02.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:48:0)
[02:15:02.491] Timer tick, asking Buildbucket for the build status
[02:15:02.595] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:02.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:49:0) after 1m0s
[02:16:02.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:49:0)
[02:16:02.701] Timer tick, asking Buildbucket for the build status
[02:16:03.027] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:03.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:50:0) after 1m0s
[02:17:03.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:50:0)
[02:17:03.049] Timer tick, asking Buildbucket for the build status
[02:17:03.152] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:03.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:51:0) after 1m0s
[02:18:03.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:51:0)
[02:18:03.205] Timer tick, asking Buildbucket for the build status
[02:18:03.400] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:03.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:52:0) after 1m0s
[02:19:03.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:52:0)
[02:19:03.424] Timer tick, asking Buildbucket for the build status
[02:19:03.587] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:03.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:53:0) after 1m0s
[02:20:03.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:53:0)
[02:20:03.683] Timer tick, asking Buildbucket for the build status
[02:20:03.815] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:03.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:54:0) after 1m0s
[02:21:03.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:54:0)
[02:21:03.852] Timer tick, asking Buildbucket for the build status
[02:21:04.029] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:04.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:55:0) after 1m0s
[02:22:04.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:55:0)
[02:22:04.091] Timer tick, asking Buildbucket for the build status
[02:22:04.213] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:04.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:56:0) after 1m0s
[02:23:04.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:56:0)
[02:23:04.274] Timer tick, asking Buildbucket for the build status
[02:23:04.549] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:04.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:57:0) after 1m0s
[02:24:04.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:57:0)
[02:24:04.615] Timer tick, asking Buildbucket for the build status
[02:24:05.073] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:05.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:58:0) after 1m0s
[02:25:05.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:58:0)
[02:25:05.140] Timer tick, asking Buildbucket for the build status
[02:25:05.223] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:05.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:59:0) after 1m0s
[02:26:05.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:59:0)
[02:26:05.368] Timer tick, asking Buildbucket for the build status
[02:26:06.107] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:06.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:60:0) after 1m0s
[02:27:06.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:60:0)
[02:27:06.171] Timer tick, asking Buildbucket for the build status
[02:27:06.529] Build 8856358139732238784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:06.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021788201816193648:61:0) after 1m0s
[02:27:17.813] Received PubSub notification, asking Buildbucket for the build status
[02:27:17.885] Build 8856358139732238784: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:27:17.885] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612319237168661",
"created_by": "project:chromium",
"created_ts": "1612315727449926",
"failure_reason": "BUILD_FAILURE",
"id": "8856358139732238784",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- LazyBackgroundPageApiTest.Messaging\"}, \"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\": \"c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06\", \"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-65-vn8o\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45096, \"got_angle_revision\": \"926d1cea1a0c52325cc66dc51dd8b941a0dfa783\", \"got_dawn_revision\": \"dd988bca844a6003a7249221680fc3e4f2d8b445\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06\", \"got_revision_cp\": \"refs/heads/master@{#849880}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"3f037e8e983f58d3efe01425c31a524001f9fb34\", \"got_v8_revision_cp\": \"refs/heads/9.0.118@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06\", \"swarm_hashes_refs/heads/master(at){#849880}_without_patch\": {\"absl_hardening_tests\": \"4642a82ee14699f369f2b4c138e14eb6d0f48157\", \"accessibility_unittests\": \"4077cd0f17b07905f9f9e2ad591ae5bb83734d30\", \"angle_unittests\": \"172b9085688f8b928099ec20a78c186dfdd68ed3\", \"app_list_unittests\": \"f0a29161322f626224622198e5eaea7673578de2\", \"app_shell_unittests\": \"0732f28dd8a8da18006e6f45c7e93e96edc4fdb4\", \"ash_unittests\": \"d5982ffbd00d3ec8e801846bba79c04e659cfe58\", \"aura_unittests\": \"5333ea650bc75b63a748815193c6d0d1633d46d8\", \"base_unittests\": \"e896c32c27c77c5ab6419dbb159da5d9be078d76\", \"base_util_unittests\": \"ee517618b73b6c0d85a144c532645c8740a67157\", \"blink_common_unittests\": \"97ca2bdbe5c97bbfaabb7b600e152d903d0c0d89\", \"blink_fuzzer_unittests\": \"8fe7b1584347da71088151b6895beee4980dc6d3\", \"blink_heap_unittests\": \"67ffd3ad2532dff5515e74da9219c0f97217c919\", \"blink_platform_unittests\": \"b970e86eb9ebce53223e2d998f7906be7e0b7f2e\", \"blink_unittests\": \"260df4e5822a82b5b12b351ec45f1783ecbb8fd8\", \"boringssl_crypto_tests\": \"039068d5fe9ae21727c5f1a66e6a7fb6277ba9ab\", \"boringssl_ssl_tests\": \"0e80e5f100e36fb62a75e69de43cf2e88f5790aa\", \"browser_tests\": \"cd8553121deaac78149276ef8e26632f4a06cb2b\", \"capture_unittests\": \"8cd64538f0faae848f7a3ec1773c6a3b9f78281f\", \"cast_unittests\": \"eac1af164cfb60f4c26326826de4a3846b4a1f02\", \"cc_unittests\": \"b1c83ebd40b66f656e4d924fdecd8998b10336f3\", \"chrome_app_unittests\": \"3b8419902334d13f09834a75cfc0041f72718cac\", \"chromedriver_unittests\": \"de6c35922958f98c9f5cd3c46a53080e6d078a20\", \"chromeos_components_unittests\": \"098daf0539f81d088d2060b6c359162088afb7c1\", \"chromeos_unittests\": \"ab9dd9b52f7c4f014916dc053fd41cf4bbe940a3\", \"components_browsertests\": \"5002c9e45576c254b5c368b1d1b182eddce052e0\", \"components_unittests\": \"0509cff9ef117e480d2bf39b2709d0866fa93dd6\", \"compositor_unittests\": \"1b5282e86e363af40527c0a79f67bd376a56370c\", \"content_browsertests\": \"d16b0f46fcf34072ad97a6ae80cfd3f439255c48\", \"content_unittests\": \"094f90ad78de6d5b672e979b3cb8f9caeaf3e7e2\", \"crashpad_tests\": \"fe57e361b7708bd3089383ea99d4b48155ed63e1\", \"crypto_unittests\": \"9de4dab01588737445639bb883db27aa5bc6f40d\", \"dbus_unittests\": \"dc349397260db8df038d10d90aad6024d6038ca2\", \"device_unittests\": \"809e478273192527597abfb651aed4ea286fae90\", \"display_unittests\": \"4fb62dc71099e3eb6319601decd59d1361b8ace3\", \"events_unittests\": \"601ed7ce4807e602217aaae0fdddfdbd3c0957f2\", \"exo_unittests\": \"be59a72251769d8262738c9c998f8494a7d618f9\", \"extensions_browsertests\": \"94132ac02386b6c0df01ec122132fb564c21435d\", \"extensions_unittests\": \"17848a792aa5cf12aa12bfb29d4172ccf8894cad\", \"filesystem_service_unittests\": \"7b0040678de5d4d6947a7b8cab96a4852ca9f3d1\", \"gcm_unit_tests\": \"4afdf08d3b3cc3ab80bfa22afa5de8e5bb6897ba\", \"gfx_unittests\": \"36c6d8821a34b748c7ae5d253b8c4fd55b1eec93\", \"gin_unittests\": \"02c47953c7b1397e47c9c504495e8bb9e8c20a8c\", \"gl_unittests_ozone\": \"4e547a1b3156ea0ba44444c9d17d1c8a2e89cf0e\", \"google_apis_unittests\": \"3ce9fda40d765a3e88d4a9d1fb696e357c27d85a\", \"gpu_unittests\": \"fa82deaf48d1ffb3e159fcf947a9c4dd876f2096\", \"gwp_asan_unittests\": \"3a59c7226333afd89b4e9c87c6bec83669fad56f\", \"interactive_ui_tests\": \"2ebfe2c611e3edcb7707773b12fb63e7a41f72f9\", \"ipc_tests\": \"926d749852a61bc6a48d4588d907c9a2da54905f\", \"jingle_unittests\": \"894e0d95c6e62e7f7be40a1dcf49ca2ebb83207a\", \"keyboard_unittests\": \"ac29c8b0a7d566110b8afda96ed35dd461c92ce0\", \"latency_unittests\": \"e9f9ea4bad8ef4e9620c394b99581338d8e71c25\", \"libjingle_xmpp_unittests\": \"03d5d09d59ee3c1ae1e4d7eeef070e5546837c73\", \"liburlpattern_unittests\": \"77acefe0e6a4bbddcb44461af9e829f5177a7126\", \"media_blink_unittests\": \"aa61e106cd7985c1f41328b7eb66bdaead3e63b9\", \"media_unittests\": \"6535bba64da16d47a9210a3119997486515ad098\", \"message_center_unittests\": \"14aa1f62251032a7ffa1540608671dd3ff7a376b\", \"midi_unittests\": \"ab5fd331f3682489dcfb7f51031b5e842927df3e\", \"mojo_core_unittests\": \"27af55139833906963a6e0f3e89e16f77fa0c5a2\", \"mojo_unittests\": \"ad4235a28d8540a65beeaabce87b22835a6f648c\", \"nacl_helper_nonsfi_unittests\": \"e919a3c86056a1658fb24a7f31f951964c95bdeb\", \"nacl_loader_unittests\": \"bc7c44d1fdf746405aa65f90e5cbce731a26aa81\", \"native_theme_unittests\": \"f9a39ac2443a21f5ba290194e5f4312839daf30e\", \"net_unittests\": \"2eaa1e508b7badc4296d7c75e1f6892719973f87\", \"ozone_gl_unittests\": \"e16d6309e5057a88e0a7841313b2718f024bfef4\", \"ozone_unittests\": \"d8f3f87415d6d170c10388b0609ac42db136d2d4\", \"ozone_x11_unittests\": \"031049f7d1d93ae2b46231dd2be372280fd8c1ee\", \"pdf_unittests\": \"3ade0c3e55a03de2c50136ab2f682f503b5a4fa5\", \"perfetto_unittests\": \"9192d075680f45e43383d5a8742e7ff333ec5afe\", \"ppapi_unittests\": \"abab352ed92972935decc8bc04f2d570a00654ac\", \"printing_unittests\": \"934cc836dc3642dfe2820438814d67cdaeb0d723\", \"remoting_unittests\": \"90ded380309a4941b3830f63ab9916ce179cf786\", \"sandbox_linux_unittests\": \"0b6c4ad5b62ea18b1d39f8ae9cb72e306c9989db\", \"service_manager_unittests\": \"6821415c6c63bfe6d96bdc08de98ca728b706d64\", \"services_unittests\": \"bf94027cfd7fd643e6457e724374b991546c65c5\", \"shell_dialogs_unittests\": \"966e8aeee3aca4e41756bd5ac3e9bdb17e5cbbb3\", \"shell_encryption_unittests\": \"328fbb00725998dcdfddf416eefede53c812b177\", \"skia_unittests\": \"6ad4b3de0ee73063b313d6e0d8fb936b422c8269\", \"snapshot_unittests\": \"876024b98c4bfff9972e1ba9d5e8432fd8614056\", \"sql_unittests\": \"3f8e8a0c9a3d4c4e6607d98c2c3fe02c442cbeca\", \"storage_unittests\": \"042ac5c6303ecb0c5956e7ecf1b16b61cf0a943d\", \"sync_integration_tests\": \"d4ab90daf00921abcbc7e707019a5e91d5781997\", \"traffic_annotation_auditor_unittests\": \"6ce94ad97f1b75fdf0e45be31df565f3cd49a52c\", \"ui_base_unittests\": \"d572b3e64ec073a887e7d2d2becc41bd1b1d041b\", \"ui_chromeos_unittests\": \"6bc56af52a7082b895f4ba1012a7455fab25b796\", \"ui_touch_selection_unittests\": \"8f315e9bbef40638078008407d99bd5f71ad1d86\", \"unit_tests\": \"ca87adac9e01d3dc06da2b9e5c5445739e42fc45\", \"url_unittests\": \"d99ea02081d1606968576b43bc83088b8bdd2b88\", \"usage_time_limit_unittests\": \"ea01c826db081b342345f8fb2680c094e2cc20ba\", \"views_unittests\": \"a54dbf2d9effc8fa9bb9e3ade97cab83c09982b1\", \"viz_unittests\": \"65e251c0dc56c7db4e2359ae7a2c084ee912acff\", \"wayland_client_perftests\": \"77532dfad68d032c937c8537a3376dca342265ea\", \"wm_unittests\": \"74fbc38ad6de1980faee8579510a1623a4df08a5\", \"wtf_unittests\": \"4f48cbb7703abc901e3a418dd225f902fbb8de78\", \"zlib_unittests\": \"43f3c91e914694611d90c229f43777f3a28bfdcc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093d924ecb526dae9a1dcf9db4725f8944eec2b4c0f2ddc6c2ac4a04802f7f15_v2\", \"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_8c23a72064650b4b1d9380637a287eed4ae2e2a2092116afea022d28ee09b5dd_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a432d290b72bc0f3cae401fbd647c33c14340f64e89770e9c9c39d6230495526_v2\", \"builder_a7b9f8880f1f76f2cce14c79e9b35da9a713af6654c6d1393cfbcc356d1ede5d_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-65-vn8o\"], \"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- LazyBackgroundPageApiTest.Messaging\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612315895087140",
"status": "COMPLETED",
"status_changed_ts": "1612315897288838",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45096",
"builder:linux-chromeos-rel",
"buildset:commit/git/c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c0acc8ed5cfe1112b2b933bf6d69b8fe1ee06d06",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021788201816193648",
"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/8856358139732238784/+/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:517e532540d28910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612319237477994",
"url": "https://ci.chromium.org/b/8856358139732238784",
"utcnow_ts": "1612319237877357"
}
}
[02:27:17.885] Invocation finished in 58m31.807560122s with status FAILED