[22:19:56.087] New invocation is queued and will start shortly
[22:19:57.180] Starting the invocation (attempt 1)
[22:19:57.213] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:19:57.213] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:19:57.213] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019263367128305344",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3d884a48b30f318c664004f149440908dd8dec8c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019263367128305344",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/3d884a48b30f318c664004f149440908dd8dec8c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3d884a48b30f318c664004f149440908dd8dec8c",
"gitiles_ref:refs/heads/master"
]
}
[22:19:58.612] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614723597393673",
"id": "8853833305098710480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3d884a48b30f318c664004f149440908dd8dec8c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614723598336331",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45852",
"builder:linux-chromeos-rel",
"buildset:commit/git/3d884a48b30f318c664004f149440908dd8dec8c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3d884a48b30f318c664004f149440908dd8dec8c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019263367128305344",
"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/8853833305098710480/+/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": "1614723598336455",
"url": "https://ci.chromium.org/b/8853833305098710480",
"utcnow_ts": "1614723598604579"
}
}
[22:19:58.612] Task URL: https://ci.chromium.org/b/8853833305098710480
[22:19:58.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:2:0) after 1m0s
[22:20:58.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:2:0)
[22:20:58.659] Timer tick, asking Buildbucket for the build status
[22:20:58.760] Build 8853833305098710480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:58.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:3:0) after 1m0s
[22:21:14.740] Received PubSub notification, asking Buildbucket for the build status
[22:21:14.802] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:58.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:3:0)
[22:21:58.831] Timer tick, asking Buildbucket for the build status
[22:21:59.137] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:59.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:5:0) after 1m0s
[22:22:59.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:5:0)
[22:22:59.227] Timer tick, asking Buildbucket for the build status
[22:22:59.650] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:59.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:6:0) after 1m0s
[22:23:59.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:6:0)
[22:23:59.707] Timer tick, asking Buildbucket for the build status
[22:24:03.595] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:03.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:7:0) after 1m0s
[22:25:03.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:7:0)
[22:25:03.659] Timer tick, asking Buildbucket for the build status
[22:25:03.760] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:03.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:8:0) after 1m0s
[22:26:03.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:8:0)
[22:26:03.735] Timer tick, asking Buildbucket for the build status
[22:26:04.072] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:04.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:9:0) after 1m0s
[22:27:04.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:9:0)
[22:27:04.178] Timer tick, asking Buildbucket for the build status
[22:27:04.462] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:04.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:10:0) after 1m0s
[22:28:04.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:10:0)
[22:28:04.509] Timer tick, asking Buildbucket for the build status
[22:28:04.607] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:04.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:11:0) after 1m0s
[22:29:04.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:11:0)
[22:29:04.596] Timer tick, asking Buildbucket for the build status
[22:29:05.917] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:05.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:12:0) after 1m0s
[22:30:05.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:12:0)
[22:30:05.946] Timer tick, asking Buildbucket for the build status
[22:30:06.077] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:06.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:13:0) after 1m0s
[22:31:06.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:13:0)
[22:31:06.102] Timer tick, asking Buildbucket for the build status
[22:31:06.335] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:06.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:14:0) after 1m0s
[22:32:06.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:14:0)
[22:32:06.542] Timer tick, asking Buildbucket for the build status
[22:32:06.613] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:06.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:15:0) after 1m0s
[22:33:06.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:15:0)
[22:33:06.684] Timer tick, asking Buildbucket for the build status
[22:33:06.752] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:06.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:16:0) after 1m0s
[22:34:06.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:16:0)
[22:34:06.813] Timer tick, asking Buildbucket for the build status
[22:34:08.311] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:08.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:17:0) after 1m0s
[22:35:08.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:17:0)
[22:35:08.295] Timer tick, asking Buildbucket for the build status
[22:35:08.454] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:08.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:18:0) after 1m0s
[22:36:08.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:18:0)
[22:36:08.472] Timer tick, asking Buildbucket for the build status
[22:36:08.570] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:08.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:19:0) after 1m0s
[22:37:08.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:19:0)
[22:37:08.616] Timer tick, asking Buildbucket for the build status
[22:37:08.888] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:08.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:20:0) after 1m0s
[22:38:08.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:20:0)
[22:38:08.925] Timer tick, asking Buildbucket for the build status
[22:38:09.030] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:09.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:21:0) after 1m0s
[22:39:09.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:21:0)
[22:39:09.053] Timer tick, asking Buildbucket for the build status
[22:39:09.132] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:09.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:22:0) after 1m0s
[22:40:09.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:22:0)
[22:40:09.153] Timer tick, asking Buildbucket for the build status
[22:40:09.233] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:09.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:23:0) after 1m0s
[22:41:09.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:23:0)
[22:41:09.226] Timer tick, asking Buildbucket for the build status
[22:41:09.300] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:09.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:24:0) after 1m0s
[22:42:09.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:24:0)
[22:42:09.556] Timer tick, asking Buildbucket for the build status
[22:42:09.754] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:09.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:25:0) after 1m0s
[22:43:09.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:25:0)
[22:43:09.857] Timer tick, asking Buildbucket for the build status
[22:43:09.938] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:09.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:26:0) after 1m0s
[22:44:09.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:26:0)
[22:44:09.962] Timer tick, asking Buildbucket for the build status
[22:44:10.066] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:10.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:27:0) after 1m0s
[22:45:10.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:27:0)
[22:45:10.087] Timer tick, asking Buildbucket for the build status
[22:45:10.796] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:10.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:28:0) after 1m0s
[22:46:10.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:28:0)
[22:46:10.868] Timer tick, asking Buildbucket for the build status
[22:46:10.985] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:10.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:29:0) after 1m0s
[22:47:11.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:29:0)
[22:47:11.072] Timer tick, asking Buildbucket for the build status
[22:47:11.178] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:11.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:30:0) after 1m0s
[22:48:11.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:30:0)
[22:48:11.205] Timer tick, asking Buildbucket for the build status
[22:48:11.339] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:11.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:31:0) after 1m0s
[22:49:11.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:31:0)
[22:49:11.408] Timer tick, asking Buildbucket for the build status
[22:49:11.473] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:11.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:32:0) after 1m0s
[22:50:11.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:32:0)
[22:50:11.474] Timer tick, asking Buildbucket for the build status
[22:50:11.559] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:11.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:33:0) after 1m0s
[22:51:11.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:33:0)
[22:51:11.580] Timer tick, asking Buildbucket for the build status
[22:51:11.659] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:11.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:34:0) after 1m0s
[22:52:11.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:34:0)
[22:52:11.702] Timer tick, asking Buildbucket for the build status
[22:52:11.774] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:11.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:35:0) after 1m0s
[22:53:11.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:35:0)
[22:53:11.807] Timer tick, asking Buildbucket for the build status
[22:53:11.937] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:11.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:36:0) after 1m0s
[22:54:11.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:36:0)
[22:54:11.914] Timer tick, asking Buildbucket for the build status
[22:54:11.974] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:11.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:37:0) after 1m0s
[22:55:11.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:37:0)
[22:55:11.998] Timer tick, asking Buildbucket for the build status
[22:55:14.863] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:14.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:38:0) after 1m0s
[22:56:14.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:38:0)
[22:56:14.933] Timer tick, asking Buildbucket for the build status
[22:56:14.998] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:14.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:39:0) after 1m0s
[22:57:15.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:39:0)
[22:57:15.001] Timer tick, asking Buildbucket for the build status
[22:57:15.088] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:15.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:40:0) after 1m0s
[22:58:15.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:40:0)
[22:58:15.108] Timer tick, asking Buildbucket for the build status
[22:58:15.235] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:15.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:41:0) after 1m0s
[22:59:15.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:41:0)
[22:59:15.252] Timer tick, asking Buildbucket for the build status
[22:59:15.310] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:15.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:42:0) after 1m0s
[23:00:15.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:42:0)
[23:00:15.305] Timer tick, asking Buildbucket for the build status
[23:00:15.419] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:15.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:43:0) after 1m0s
[23:01:15.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:43:0)
[23:01:15.439] Timer tick, asking Buildbucket for the build status
[23:01:15.618] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:15.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:44:0) after 1m0s
[23:02:15.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:44:0)
[23:02:15.638] Timer tick, asking Buildbucket for the build status
[23:02:15.701] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:15.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:45:0) after 1m0s
[23:03:15.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:45:0)
[23:03:15.749] Timer tick, asking Buildbucket for the build status
[23:03:15.819] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:15.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:46:0) after 1m0s
[23:04:15.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:46:0)
[23:04:15.796] Timer tick, asking Buildbucket for the build status
[23:04:15.885] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:15.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:47:0) after 1m0s
[23:05:15.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:47:0)
[23:05:15.907] Timer tick, asking Buildbucket for the build status
[23:05:16.020] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:16.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:48:0) after 1m0s
[23:06:16.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:48:0)
[23:06:16.089] Timer tick, asking Buildbucket for the build status
[23:06:16.222] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:16.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:49:0) after 1m0s
[23:07:16.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:49:0)
[23:07:16.294] Timer tick, asking Buildbucket for the build status
[23:07:16.389] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:16.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:50:0) after 1m0s
[23:08:16.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:50:0)
[23:08:16.429] Timer tick, asking Buildbucket for the build status
[23:08:16.532] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:16.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:51:0) after 1m0s
[23:09:16.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:51:0)
[23:09:16.740] Timer tick, asking Buildbucket for the build status
[23:09:17.020] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:17.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:52:0) after 1m0s
[23:10:17.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:52:0)
[23:10:17.047] Timer tick, asking Buildbucket for the build status
[23:10:18.800] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:18.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:53:0) after 1m0s
[23:11:18.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:53:0)
[23:11:18.875] Timer tick, asking Buildbucket for the build status
[23:11:18.973] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:18.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:54:0) after 1m0s
[23:12:19.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:54:0)
[23:12:19.012] Timer tick, asking Buildbucket for the build status
[23:12:19.094] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:19.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:55:0) after 1m0s
[23:13:19.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:55:0)
[23:13:19.112] Timer tick, asking Buildbucket for the build status
[23:13:19.228] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:19.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:56:0) after 1m0s
[23:14:19.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:56:0)
[23:14:19.254] Timer tick, asking Buildbucket for the build status
[23:14:19.357] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:19.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:57:0) after 1m0s
[23:15:19.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:57:0)
[23:15:19.445] Timer tick, asking Buildbucket for the build status
[23:15:19.588] Build 8853833305098710480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:19.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019263367128305344:58:0) after 1m0s
[23:16:16.067] Received PubSub notification, asking Buildbucket for the build status
[23:16:16.188] Build 8853833305098710480: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:16:16.188] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614726975144843",
"created_by": "project:chromium",
"created_ts": "1614723597393673",
"failure_reason": "BUILD_FAILURE",
"id": "8853833305098710480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3d884a48b30f318c664004f149440908dd8dec8c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- OverviewSessionFlingTest.BasicFling\\n\\n**browser_tests** failed because of:\\n\\n- AccessibilityCommonE2ETest.ToggleFeatures\\n\\n- AccessibilityPolicyTest.AutoclickEnabled\\n\\n- All/GestureNavigationScreenTest.ScreenSkippedWithAutoclickEnabled/1\\n\\n- BackForwardCachePageLoadMetricsObserverBrowserTest.RequestAnimationFramesAfterBackForwardCacheRestore\\n\\n- ...2 more failure(s) (6 total)...\"}, \"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\": \"3d884a48b30f318c664004f149440908dd8dec8c\", \"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\": 45852, \"got_angle_revision\": \"87b1875ca78afe9bf258287c5b8848e45df7dc10\", \"got_dawn_revision\": \"9b9e7b293e674bc62af385ed543f8775c9ee9f05\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"3d884a48b30f318c664004f149440908dd8dec8c\", \"got_revision_cp\": \"refs/heads/master@{#859145}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"12772c49b2417af1dba4f4469f249d377a01a676\", \"got_v8_revision_cp\": \"refs/heads/9.1.20@{#1}\", \"got_webrtc_revision\": \"07d83c8a9af02a0afc473503f75f150f23e3e0ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33366}\", \"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\": \"3d884a48b30f318c664004f149440908dd8dec8c\", \"swarm_hashes_refs/heads/master(at){#859145}_without_patch\": {\"absl_hardening_tests\": \"d5e973b2e7ffcba2254c4aad44b05290555318d4\", \"accessibility_unittests\": \"57650bc8983f8b034b5e09734e0505bac5ae4b8d\", \"angle_unittests\": \"28e66d37566563bd9140035eb9d09bc2263c92ef\", \"app_list_unittests\": \"8fe14d771ecd3ca373df2c2b9b5c1250f2018243\", \"app_shell_unittests\": \"ad878a12040a38afeb5cd3bfd552d00407357bab\", \"ash_unittests\": \"5b0dd3a5b9dc63f8d755616eb9081512e2367beb\", \"aura_unittests\": \"b6f25dd44590e0599f5b683e0005e56b69396e22\", \"base_unittests\": \"c24a8dccabb958841430a985ff21a810b408acea\", \"base_util_unittests\": \"22e9d9930d97ce55a347b6ef554e2198d757f616\", \"blink_common_unittests\": \"e56fb00e7cd1d255496560462ad912fa0fd1ef9e\", \"blink_fuzzer_unittests\": \"5aec705c16c91213f66f5d89405f1333746202da\", \"blink_heap_unittests\": \"5bc78caa205914f32553cad0be36ca38bb658ab9\", \"blink_platform_unittests\": \"de553d4b6f55cf3b0c5c752799b224a47a31d2a0\", \"blink_unittests\": \"7dd09513d0f40e0407888de87fa5a7f96010a592\", \"boringssl_crypto_tests\": \"232f46886afb7cf4c82899c3a0ce9d5034dc0e8e\", \"boringssl_ssl_tests\": \"087e5d6655d13192ccbd656c6f3228d01c28373f\", \"browser_tests\": \"c7e0e0f734691aaf1edb6e4395d4fc050ebcfccd\", \"capture_unittests\": \"78334fc2839813f4668abb234dde1c58834f5785\", \"cast_unittests\": \"16ac571c954b94a530f9ee900670283d87b78f26\", \"cc_unittests\": \"4339dd55a492cd70601da9c4473ea4ce68daec64\", \"chrome_app_unittests\": \"a1b90a28dffa7e5f8a04752017a08d9aa62ba5ce\", \"chromedriver_unittests\": \"b0bd6236f050ff84df4dae4c7078fd548e4f1774\", \"chromeos_components_unittests\": \"f93963680f9d752d79f40a0254fde2d9a2ae0ca0\", \"chromeos_unittests\": \"39680c920beafb4ed77c6c3146fbe50ec3c47e8e\", \"color_unittests\": \"c78a36b90a608d8d87e2f5f1a4e622c05eddff63\", \"components_browsertests\": \"9f834bdd35a7b97102ffa547f2d9fb4953f2bd69\", \"components_unittests\": \"cdf4dab63730d305c94f5e3f9b2c203fcc7ef14f\", \"compositor_unittests\": \"0c23ea9567c547cdea0ceaa2fff558115b00fe44\", \"content_browsertests\": \"b79ce163fc073d60c6aa9360e6e919c50d062a52\", \"content_unittests\": \"a8ceecf7f8e07d76f667983a185ad5a3c69d351d\", \"crashpad_tests\": \"42f7818a236eaf2e73c0a6d6bd091cb8e8ba998c\", \"crypto_unittests\": \"747eb13eec2bd38b7986632184c89c51ffbffc46\", \"dbus_unittests\": \"fc7de518ad42f54702ffe9811ec79b0aa43a4c96\", \"device_unittests\": \"44d664d84f96a3c67d409ccea664d0c34825089a\", \"display_unittests\": \"2dc6c3b3b9daafb6f766ef2d1c457a8a9bb489a8\", \"events_unittests\": \"1649dc3fb2a444b7091041516713cdcae1c9ff64\", \"exo_unittests\": \"c49de17e769778d6cd1d4b08f690759e5f2738f2\", \"extensions_browsertests\": \"434d55d071f37bd8d07e3991c91569d66572e932\", \"extensions_unittests\": \"29064f1a53fc857aae468513de2e97152a2f1352\", \"filesystem_service_unittests\": \"fa584e717a93a02df35a90716a38c224f1bf6e3e\", \"gcm_unit_tests\": \"a59c1b4fcb314f69db6221d567a8e6b568df69ea\", \"gfx_unittests\": \"2f090a7430a3a26839405411a4f73fb2f8ec913e\", \"gin_unittests\": \"5562ae9f78267839d4eb0000c1f449cee3d31aab\", \"gl_unittests_ozone\": \"49021ec18ed3ea7c0603bbc1942b17a5b0b5c85f\", \"google_apis_unittests\": \"70e786dd2d44f15ec57e87e4d102062d195b7e7a\", \"gpu_unittests\": \"27e112d34577585389fc0e115b6dd971a401ed71\", \"gwp_asan_unittests\": \"e02d2cc000dea551704906b067c79547a21def83\", \"interactive_ui_tests\": \"96ea7e6edcfbb130f4b0b1f161feeb4e537e111b\", \"ipc_tests\": \"e0050a994f7192202d8ef4b35257fc6582c13d91\", \"jingle_unittests\": \"adeaa7c188b79259cc1a1521f4e89c839be91175\", \"keyboard_unittests\": \"c4584035fadbc869e2c30c17e3d88bcdca608e98\", \"latency_unittests\": \"0c539a16e69db475f11f5743c6126ca5e2f327af\", \"libjingle_xmpp_unittests\": \"1b3c07b5a5aa45fc0c4c900a3a85eb08eaff3e4a\", \"liburlpattern_unittests\": \"fef96c9cc33e7636cfb294b3fd870042b30d8188\", \"media_blink_unittests\": \"2bccd90fadd4e4d86d094a85155d2083e5713397\", \"media_unittests\": \"fcc265dd7de2d45e0f6dc3ef7c44a2a6196e8c0c\", \"message_center_unittests\": \"4ca593d58d6b3b4d8f83f41265dc6ef17287b0ad\", \"midi_unittests\": \"7eed4df0a151b209dd591ae136904cf2b4a6f2b3\", \"mojo_core_unittests\": \"2678df768ad3bff9389dc1e2d6081b20c6c74aff\", \"mojo_unittests\": \"87688a02cd005560b466f1bb7c9870f5c37755a4\", \"nacl_helper_nonsfi_unittests\": \"5a60aa4bd6ad44065746a89e60c6d628931b9f4a\", \"nacl_loader_unittests\": \"40ab6446dde16d182d6dbeccb74ff96a513c7ad6\", \"native_theme_unittests\": \"f1db8110cb307f846f7f74ab31242fd908394686\", \"net_unittests\": \"cdd16803cc5e612325953e9cc64ef04be7053a2d\", \"ozone_gl_unittests\": \"a195b72924f197e38756e3c1f5ec09ec26835e0b\", \"ozone_unittests\": \"1b9e8c6324616b1b830864636168af667e713895\", \"ozone_x11_unittests\": \"7a86ee4eff285a792dd6d17ec667c8c36f83dc4c\", \"pdf_unittests\": \"9d8a29fac15aacd8a63d005a91f457f9bffc6f22\", \"perfetto_unittests\": \"f9bda2a76542a7d5b4f1e13b17216028ece354a2\", \"ppapi_unittests\": \"e9f1030085b5f132762dbe2afc0f9ff48524987f\", \"printing_unittests\": \"ea118b9b671727843ba85ce4928ec1129a6b3260\", \"remoting_unittests\": \"d1fadff54ab31b17ab40de0239a1e577c4aa7729\", \"sandbox_linux_unittests\": \"2869108fa59ce20162c105403ad48ba977e99490\", \"service_manager_unittests\": \"324acb4cfe613268bd754ea8d47081cca43422e9\", \"services_unittests\": \"d007909be8550cf55719332ba60771fb31f3dfb4\", \"shell_dialogs_unittests\": \"bb264ddd6aed1073044386deee0e892ea86294fa\", \"shell_encryption_unittests\": \"2a5a3a9a07c15c088551892c9660921deff0bb5e\", \"skia_unittests\": \"8f3ab3c71af47dfebaad4a3ad4c24603da4f4c89\", \"snapshot_unittests\": \"224b7537b650bbccc5bfffe308ddd5477f294bd6\", \"sql_unittests\": \"267d4ea06f25549cf963008c24db72a47d3f1a07\", \"storage_unittests\": \"1b3514bac60b62e89ae4f000731ecd94b5201bf3\", \"sync_integration_tests\": \"93345aaa9063a7c9c899e505418dbd4b4596809d\", \"traffic_annotation_auditor_unittests\": \"b53cac98d3ae64dd6f2bc04872b2b300f6fab910\", \"ui_base_unittests\": \"d0ef35bd722c4f6f9be692b018703fdbe5c17b04\", \"ui_chromeos_unittests\": \"fd24fc4129c8afa5de58ec4823748bdd8d4247ff\", \"ui_touch_selection_unittests\": \"438c8d1a5d1335f11c8d32bbb813be4ff3476eae\", \"unit_tests\": \"ecd68057c770571c768e53a9bc93503e9ef36036\", \"url_unittests\": \"9477af08ce1bf588d464c838db7f8accfb8a7a82\", \"usage_time_limit_unittests\": \"7a35c54ce98c6d1000ab9ffc406f539a83c19e67\", \"views_unittests\": \"dce4507afef9d9dcd7dbcc652e3ee9935923df7a\", \"viz_unittests\": \"601d76d7187c3466b3e942cfe078b4015f3d592e\", \"wayland_client_perftests\": \"4d1c9d896e561d0fb5b1d54a04cc7ca03dbf21f7\", \"wayland_client_tests\": \"786855df3ae1e51a08710b12236a17b0bd885ea9\", \"wm_unittests\": \"6d76ecc69a4d604edc4095f12667d7df1c0b5e9f\", \"wtf_unittests\": \"9f3bc7fdde799c653606b0a98800b0a1b5427bfa\", \"zlib_unittests\": \"e6d7acee32c3fa1271e3cae9fb320a9b62d71887\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_28be8b4e850be8bd7a9db0671ff876681ccdf0c2c048860e172c20e0c8b9b79b_v2\", \"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_b1565bc506c7331f0456efe3635f211a71cb90c7c9b1da510ebf59e5503b99bd_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\": \"2 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- OverviewSessionFlingTest.BasicFling\\n\\n**browser_tests** failed because of:\\n\\n- AccessibilityCommonE2ETest.ToggleFeatures\\n\\n- AccessibilityPolicyTest.AutoclickEnabled\\n\\n- All/GestureNavigationScreenTest.ScreenSkippedWithAutoclickEnabled/1\\n\\n- BackForwardCachePageLoadMetricsObserverBrowserTest.RequestAnimationFramesAfterBackForwardCacheRestore\\n\\n- ...2 more failure(s) (6 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614723673734622",
"status": "COMPLETED",
"status_changed_ts": "1614723674108872",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45852",
"builder:linux-chromeos-rel",
"buildset:commit/git/3d884a48b30f318c664004f149440908dd8dec8c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3d884a48b30f318c664004f149440908dd8dec8c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019263367128305344",
"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/8853833305098710480/+/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:520dd851fa676010",
"user_agent:luci-scheduler"
],
"updated_ts": "1614726975145400",
"url": "https://ci.chromium.org/b/8853833305098710480",
"utcnow_ts": "1614726976177559"
}
}
[23:16:16.188] Invocation finished in 56m20.114226676s with status FAILED