[13:50:26.351] New invocation is queued and will start shortly
[13:50:27.478] Starting the invocation (attempt 1)
[13:50:27.514] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:50:27.524] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:50:27.524] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019295421819991536",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"478203ddc384b550608284ff714d3243d81013ad\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019295421819991536",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/478203ddc384b550608284ff714d3243d81013ad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/478203ddc384b550608284ff714d3243d81013ad",
"gitiles_ref:refs/heads/master"
]
}
[13:50:28.925] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614693027659735",
"id": "8853865359787821456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"478203ddc384b550608284ff714d3243d81013ad\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614693028795480",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45842",
"builder:linux-chromeos-rel",
"buildset:commit/git/478203ddc384b550608284ff714d3243d81013ad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/478203ddc384b550608284ff714d3243d81013ad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019295421819991536",
"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/8853865359787821456/+/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": "1614693028795591",
"url": "https://ci.chromium.org/b/8853865359787821456",
"utcnow_ts": "1614693028917489"
}
}
[13:50:28.925] Task URL: https://ci.chromium.org/b/8853865359787821456
[13:50:28.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:2:0) after 1m0s
[13:51:28.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:2:0)
[13:51:28.971] Timer tick, asking Buildbucket for the build status
[13:51:29.039] Build 8853865359787821456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:51:29.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:3:0) after 1m0s
[13:51:46.099] Received PubSub notification, asking Buildbucket for the build status
[13:51:46.270] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:29.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:3:0)
[13:52:29.058] Timer tick, asking Buildbucket for the build status
[13:52:29.122] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:29.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:5:0) after 1m0s
[13:53:29.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:5:0)
[13:53:29.171] Timer tick, asking Buildbucket for the build status
[13:53:29.239] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:29.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:6:0) after 1m0s
[13:54:29.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:6:0)
[13:54:29.327] Timer tick, asking Buildbucket for the build status
[13:54:29.396] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:29.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:7:0) after 1m0s
[13:55:29.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:7:0)
[13:55:29.422] Timer tick, asking Buildbucket for the build status
[13:55:29.488] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:29.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:8:0) after 1m0s
[13:56:29.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:8:0)
[13:56:29.598] Timer tick, asking Buildbucket for the build status
[13:56:29.693] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:29.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:9:0) after 1m0s
[13:57:29.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:9:0)
[13:57:29.744] Timer tick, asking Buildbucket for the build status
[13:57:29.813] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:29.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:10:0) after 1m0s
[13:58:29.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:10:0)
[13:58:29.838] Timer tick, asking Buildbucket for the build status
[13:58:29.899] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:29.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:11:0) after 1m0s
[13:59:29.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:11:0)
[13:59:29.920] Timer tick, asking Buildbucket for the build status
[13:59:29.980] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:29.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:12:0) after 1m0s
[14:00:30.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:12:0)
[14:00:30.072] Timer tick, asking Buildbucket for the build status
[14:00:30.142] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:30.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:13:0) after 1m0s
[14:01:30.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:13:0)
[14:01:30.185] Timer tick, asking Buildbucket for the build status
[14:01:31.160] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:31.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:14:0) after 1m0s
[14:02:31.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:14:0)
[14:02:31.272] Timer tick, asking Buildbucket for the build status
[14:02:31.459] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:31.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:15:0) after 1m0s
[14:03:31.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:15:0)
[14:03:31.528] Timer tick, asking Buildbucket for the build status
[14:03:31.586] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:31.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:16:0) after 1m0s
[14:04:31.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:16:0)
[14:04:31.570] Timer tick, asking Buildbucket for the build status
[14:04:31.652] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:31.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:17:0) after 1m0s
[14:05:31.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:17:0)
[14:05:31.791] Timer tick, asking Buildbucket for the build status
[14:05:31.864] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:31.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:18:0) after 1m0s
[14:06:31.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:18:0)
[14:06:31.976] Timer tick, asking Buildbucket for the build status
[14:06:32.036] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:32.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:19:0) after 1m0s
[14:07:32.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:19:0)
[14:07:32.145] Timer tick, asking Buildbucket for the build status
[14:07:32.215] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:32.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:20:0) after 1m0s
[14:08:32.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:20:0)
[14:08:32.268] Timer tick, asking Buildbucket for the build status
[14:08:32.454] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:32.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:21:0) after 1m0s
[14:09:32.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:21:0)
[14:09:32.466] Timer tick, asking Buildbucket for the build status
[14:09:32.545] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:32.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:22:0) after 1m0s
[14:10:32.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:22:0)
[14:10:32.563] Timer tick, asking Buildbucket for the build status
[14:10:32.632] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:32.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:23:0) after 1m0s
[14:11:32.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:23:0)
[14:11:32.838] Timer tick, asking Buildbucket for the build status
[14:11:32.945] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:32.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:24:0) after 1m0s
[14:12:33.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:24:0)
[14:12:33.027] Timer tick, asking Buildbucket for the build status
[14:12:33.247] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:33.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:25:0) after 1m0s
[14:13:33.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:25:0)
[14:13:33.325] Timer tick, asking Buildbucket for the build status
[14:13:34.968] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:34.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:26:0) after 1m0s
[14:14:35.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:26:0)
[14:14:35.187] Timer tick, asking Buildbucket for the build status
[14:14:35.281] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:35.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:27:0) after 1m0s
[14:15:35.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:27:0)
[14:15:35.432] Timer tick, asking Buildbucket for the build status
[14:15:35.546] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:35.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:28:0) after 1m0s
[14:16:35.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:28:0)
[14:16:35.652] Timer tick, asking Buildbucket for the build status
[14:16:35.721] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:35.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:29:0) after 1m0s
[14:17:35.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:29:0)
[14:17:35.777] Timer tick, asking Buildbucket for the build status
[14:17:35.846] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:35.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:30:0) after 1m0s
[14:18:35.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:30:0)
[14:18:35.901] Timer tick, asking Buildbucket for the build status
[14:18:35.997] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:35.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:31:0) after 1m0s
[14:19:36.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:31:0)
[14:19:36.089] Timer tick, asking Buildbucket for the build status
[14:19:36.172] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:36.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:32:0) after 1m0s
[14:20:36.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:32:0)
[14:20:36.278] Timer tick, asking Buildbucket for the build status
[14:20:36.516] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:36.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:33:0) after 1m0s
[14:21:36.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:33:0)
[14:21:36.650] Timer tick, asking Buildbucket for the build status
[14:21:36.719] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:36.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:34:0) after 1m0s
[14:22:36.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:34:0)
[14:22:36.780] Timer tick, asking Buildbucket for the build status
[14:22:36.848] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:36.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:35:0) after 1m0s
[14:23:36.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:35:0)
[14:23:36.891] Timer tick, asking Buildbucket for the build status
[14:23:36.964] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:36.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:36:0) after 1m0s
[14:24:37.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:36:0)
[14:24:37.054] Timer tick, asking Buildbucket for the build status
[14:24:37.109] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:37.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:37:0) after 1m0s
[14:25:38.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:37:0)
[14:25:38.530] Timer tick, asking Buildbucket for the build status
[14:25:38.600] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:38.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:38:0) after 1m0s
[14:26:38.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:38:0)
[14:26:38.752] Timer tick, asking Buildbucket for the build status
[14:26:39.330] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:39.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:39:0) after 1m0s
[14:27:40.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:39:0)
[14:27:40.831] Timer tick, asking Buildbucket for the build status
[14:27:40.889] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:40.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:40:0) after 1m0s
[14:28:40.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:40:0)
[14:28:40.960] Timer tick, asking Buildbucket for the build status
[14:28:41.022] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:41.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:41:0) after 1m0s
[14:29:41.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:41:0)
[14:29:41.087] Timer tick, asking Buildbucket for the build status
[14:29:41.182] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:41.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:42:0) after 1m0s
[14:30:41.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:42:0)
[14:30:41.221] Timer tick, asking Buildbucket for the build status
[14:30:41.294] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:41.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:43:0) after 1m0s
[14:31:41.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:43:0)
[14:31:41.414] Timer tick, asking Buildbucket for the build status
[14:31:41.529] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:41.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:44:0) after 1m0s
[14:32:41.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:44:0)
[14:32:41.628] Timer tick, asking Buildbucket for the build status
[14:32:41.776] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:41.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:45:0) after 1m0s
[14:33:41.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:45:0)
[14:33:41.770] Timer tick, asking Buildbucket for the build status
[14:33:42.437] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:42.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:46:0) after 1m0s
[14:34:42.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:46:0)
[14:34:42.439] Timer tick, asking Buildbucket for the build status
[14:34:42.531] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:42.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:47:0) after 1m0s
[14:35:42.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:47:0)
[14:35:42.579] Timer tick, asking Buildbucket for the build status
[14:35:42.676] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:42.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:48:0) after 1m0s
[14:36:42.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:48:0)
[14:36:42.749] Timer tick, asking Buildbucket for the build status
[14:36:43.132] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:43.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:49:0) after 1m0s
[14:37:43.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:49:0)
[14:37:43.160] Timer tick, asking Buildbucket for the build status
[14:37:43.593] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:43.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:50:0) after 1m0s
[14:38:43.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:50:0)
[14:38:43.676] Timer tick, asking Buildbucket for the build status
[14:38:43.770] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:43.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:51:0) after 1m0s
[14:39:43.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:51:0)
[14:39:43.780] Timer tick, asking Buildbucket for the build status
[14:39:43.859] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:43.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:52:0) after 1m0s
[14:40:43.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:52:0)
[14:40:43.915] Timer tick, asking Buildbucket for the build status
[14:40:44.008] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:44.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:53:0) after 1m0s
[14:41:44.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:53:0)
[14:41:44.085] Timer tick, asking Buildbucket for the build status
[14:41:44.218] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:44.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:54:0) after 1m0s
[14:42:44.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:54:0)
[14:42:44.320] Timer tick, asking Buildbucket for the build status
[14:42:44.756] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:44.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:55:0) after 1m0s
[14:43:44.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:55:0)
[14:43:44.748] Timer tick, asking Buildbucket for the build status
[14:43:44.880] Build 8853865359787821456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:44.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019295421819991536:56:0) after 1m0s
[14:44:23.584] Received PubSub notification, asking Buildbucket for the build status
[14:44:23.715] Build 8853865359787821456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:44:23.715] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614696263336528",
"created_by": "project:chromium",
"created_ts": "1614693027659735",
"failure_reason": "BUILD_FAILURE",
"id": "8853865359787821456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"478203ddc384b550608284ff714d3243d81013ad\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- BackForwardCachePageLoadMetricsObserverBrowserTest.RequestAnimationFramesAfterBackForwardCacheRestore\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"478203ddc384b550608284ff714d3243d81013ad\", \"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\": 45842, \"got_angle_revision\": \"6b6da203cce03287ef269846d54e77a8e871e221\", \"got_dawn_revision\": \"ec3f48242250668ca510ceef7336cf93ba80faa5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"478203ddc384b550608284ff714d3243d81013ad\", \"got_revision_cp\": \"refs/heads/master@{#858979}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"da800c736f3a0b127f576fbded9d56d380b78a77\", \"got_v8_revision_cp\": \"refs/heads/9.1.14@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"478203ddc384b550608284ff714d3243d81013ad\", \"swarm_hashes_refs/heads/master(at){#858979}_without_patch\": {\"absl_hardening_tests\": \"929f1dde61f4931e84a4b19754cd1027252ac611\", \"accessibility_unittests\": \"b3ab056e7856bcace4f9cb3205d3fd563f2949fa\", \"angle_unittests\": \"df1522fa122c7c4d1daad727296c6a46069eaf25\", \"app_list_unittests\": \"d1e4332824bcbd47b7670ca1edcf291a2b476ad5\", \"app_shell_unittests\": \"a7841cb25fe37cc2b326edae8a6c333429abfcae\", \"ash_unittests\": \"8a19b8f498e22fc862ad7fb85ae981e9f9640862\", \"aura_unittests\": \"668eea64402a27db3fcace02e9c912fbefb2d2b4\", \"base_unittests\": \"155e4963b4e06e84eaac5dec1a6f4acf983336ff\", \"base_util_unittests\": \"0dd851d2afa0cad8057e4bdbc393e4c3670384fd\", \"blink_common_unittests\": \"f36efde68661dab601d7cba4b0148eebab5d4cf5\", \"blink_fuzzer_unittests\": \"bbcce94efbe80f6e82a44185a9aba2532674655b\", \"blink_heap_unittests\": \"3bf951fc5923c2a31d5c89226a3be621b966d85c\", \"blink_platform_unittests\": \"c34462006f305b1100daf24e9200947dc7bc28d3\", \"blink_unittests\": \"680de3232332fe648b008d853f36557a7de9de50\", \"boringssl_crypto_tests\": \"8e2626ab5eb4370588c3984c824c39e4f7ceb574\", \"boringssl_ssl_tests\": \"bf6b02cf686f5d709e645836264e6cfd4f9a0eec\", \"browser_tests\": \"d58e9b5d0396983fc164ed0430d727ff15dcb81e\", \"capture_unittests\": \"6adb17f08ec60ac4ffcef36560f127707d339499\", \"cast_unittests\": \"305e6083892597d60b38994c52a7112952da4f8c\", \"cc_unittests\": \"7658148879cbf0812905ba7f7eb89461cce66750\", \"chrome_app_unittests\": \"3fd598a0f66cb3d29838e8d38784d71d74273789\", \"chromedriver_unittests\": \"400684eacceff9f75ff030155bde4f90517dfdfd\", \"chromeos_components_unittests\": \"e48d44b7890b1e3b7cbe36eaa7f9c9cb5e7839ea\", \"chromeos_unittests\": \"f9a9c9d72d5ada1f9fc0aa01a8e7acaa28c84020\", \"color_unittests\": \"6518720462877c3c5f278bc6d331fc8526880a1b\", \"components_browsertests\": \"de6a59e29165a29421532bdac9f4422b3e88e153\", \"components_unittests\": \"54df1ecfabd12f3a00888bd5af682483f5b0808f\", \"compositor_unittests\": \"240bcc3c7ea0d2a2138ec29a763db7a44224369c\", \"content_browsertests\": \"1e2fdf9f1e63696e00e14b33f676e0cac8da7903\", \"content_unittests\": \"65bf721254fb362a9bdbd7064ddceb28f7863e23\", \"crashpad_tests\": \"8f34f540ea96b552deeab0254a4a3a3cf738f5f6\", \"crypto_unittests\": \"d618c14b8452a9f66bedf5476b172aaf53bfb8c3\", \"dbus_unittests\": \"ceb1cf698059f7528a70fa4bdd44f88409767be1\", \"device_unittests\": \"c0b84a64f55b89b4a65a1a7d51f842a25dcaa967\", \"display_unittests\": \"2bb9ec23d2ab577a8166f4df29fdaa039fc87dd9\", \"events_unittests\": \"95a21ceba43732db65bb04b64c5bc9b48bb1069c\", \"exo_unittests\": \"eff4c259740dcf1eb330062e857fa54a79e500c5\", \"extensions_browsertests\": \"c66fb7928d1aee6f8b0aeb9da76e5cc0ba464b1b\", \"extensions_unittests\": \"ec3567dc5ab27438f6c6c49257bd0b9d81a39f25\", \"filesystem_service_unittests\": \"776468dae24682c1351b42f2cf4d2d7132c2bdbd\", \"gcm_unit_tests\": \"755a58b2779eadff7f23efbac9b0de3e095f7bb4\", \"gfx_unittests\": \"50e522b467afbcf026f64d85b74f088fcf9d270e\", \"gin_unittests\": \"e6002ac071df1b28108dee4c131455ae4d21ce80\", \"gl_unittests_ozone\": \"01c390b0e40721c76bb2fff8071fc3899156183e\", \"google_apis_unittests\": \"f5c5ac71395ec3c820379da36e37e3b5576ccdbe\", \"gpu_unittests\": \"79588fd2f659963ffc37bdf08bac3325bb43198b\", \"gwp_asan_unittests\": \"1609fed052244afa1476aedd13d66ea439ce7cb8\", \"interactive_ui_tests\": \"8ed91cfa1bc3f5917a3c95098f93584133886e9f\", \"ipc_tests\": \"9fb28a749976cb1585747bd8ccf26a505837a455\", \"jingle_unittests\": \"d1bab6361824f554247d7c8cdad5bad6782ef5c6\", \"keyboard_unittests\": \"83ccc05bf5d5e7f8df75b1859e2e58c37a3510a6\", \"latency_unittests\": \"899e43eb37ef72088d4b8736bcb5bad57d5e4d35\", \"libjingle_xmpp_unittests\": \"1273fd6ffd72e58d2f89ddd0fccb34842fd29b4e\", \"liburlpattern_unittests\": \"e4eb0233fc6b48447eb6b77ffabf93d9e3d7fabf\", \"media_blink_unittests\": \"357d9a84aad77c54253551149b612befbcec54ea\", \"media_unittests\": \"6fc4461b5834df09389d818d235fa4b9b1debc4f\", \"message_center_unittests\": \"837136399183db567b2426118183ab5b150ca7ec\", \"midi_unittests\": \"5341be1a104491272787a4cbedd00d8edfc3a9fa\", \"mojo_core_unittests\": \"a4d9bb6cb50fd2464a6988883a6e26c1c203c4a3\", \"mojo_unittests\": \"e1f7ef04a25e501f6ee8aed2111957112ea46db1\", \"nacl_helper_nonsfi_unittests\": \"962a7e9af637643d6fb2d76177574d7c37bb8b6e\", \"nacl_loader_unittests\": \"423c7008768608ddefe79087e9ad84098bf25f70\", \"native_theme_unittests\": \"d048b60b3b759ca0d7bff40ac63f63d0b952f1d7\", \"net_unittests\": \"728b5b7ae0e847b45b21669a097119744355bb2f\", \"ozone_gl_unittests\": \"f1cf7e819257882cfe52ba10a45c6614b9a4ae01\", \"ozone_unittests\": \"aa55ab1c9bba4c31fb0f9554b70641c0dfd7e469\", \"ozone_x11_unittests\": \"89bb65116ddf9e2b870d9924b79f32820a63b299\", \"pdf_unittests\": \"abe6f7bcddca537961adc137446d9dd0e2baad0a\", \"perfetto_unittests\": \"9fbee8f815ce6e7b13fe926d2dbddd1a2950c28e\", \"ppapi_unittests\": \"998f05656010884df2d35803338f29ef279c6c27\", \"printing_unittests\": \"e0e4d9e5cabf7f14ea7f4e4ba800535f312d408d\", \"remoting_unittests\": \"5b37520948a79e07f56bdb132ebd994c6119456e\", \"sandbox_linux_unittests\": \"e6ffe02aa4b2724c7ecaa72d213344eda1fb5f7a\", \"service_manager_unittests\": \"2099ce6d684286a6df0d26514ce0b1b4b35d4f4d\", \"services_unittests\": \"af5979ac211831f913d4d901b4713bdb4e0038b9\", \"shell_dialogs_unittests\": \"05f1c088ac162a80a33a908675ddc7c02fbe7a27\", \"shell_encryption_unittests\": \"985353019a6e903e741adfe896a93b991af5c0e3\", \"skia_unittests\": \"d5debb7149d7ef87b89c286c0c92ff099a15b955\", \"snapshot_unittests\": \"c09ba95754a507e7e352fbf7adf9a89a4edb2fed\", \"sql_unittests\": \"188bbd4d7ae0536ac8c64f69765838ec91d03ea7\", \"storage_unittests\": \"9888fa2471caab6532c1ddb1eea5267ddfbb9c8b\", \"sync_integration_tests\": \"d32ab4a12e4bd5d02fea02cef1f14078699bc8be\", \"traffic_annotation_auditor_unittests\": \"b5bd167184cbe8bc0fe0c36a2d51172684dbcf1b\", \"ui_base_unittests\": \"193849a886a82ca3ecb520500b6d75b7bb4609c4\", \"ui_chromeos_unittests\": \"beae9c15fa9f42565a3a5249cbe32d3e8f6d6f3b\", \"ui_touch_selection_unittests\": \"d0ecbffdb71c4298c8d7934cc44d50574322b7c7\", \"unit_tests\": \"afec3dee94d1f2527e910f86447ab8d18a79a990\", \"url_unittests\": \"fe452c3ea410d6a058a00d48b7370d9b557f494e\", \"usage_time_limit_unittests\": \"99ba512483c97a2734095d7fbf14b41afc5f3a17\", \"views_unittests\": \"2efe77a157e3afc9ad8c61678dc427a13318a286\", \"viz_unittests\": \"2d3d9b214b5c8208bb1eff11c925b02f2ee995ba\", \"wayland_client_perftests\": \"cdb78f020097e2897f20c8490ca5dc6f47937df0\", \"wayland_client_tests\": \"b59e7549a825101882215191b06aa75fb1e8bd2f\", \"wm_unittests\": \"21bd6d0d537b48dd42c04554bc258bf4c5030d06\", \"wtf_unittests\": \"af84a5da07ade4b6664da028e6e682f6fc2db189\", \"zlib_unittests\": \"26f811adb430e92efed4c3ad17ef711909680cf6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2ca3d20e0118d26eb9e66e2d735ef34d968865a41231e326a4ca456c04a8665c_v2\", \"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-48-l0pn\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- BackForwardCachePageLoadMetricsObserverBrowserTest.RequestAnimationFramesAfterBackForwardCacheRestore\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614693105535292",
"status": "COMPLETED",
"status_changed_ts": "1614693105865635",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45842",
"builder:linux-chromeos-rel",
"buildset:commit/git/478203ddc384b550608284ff714d3243d81013ad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/478203ddc384b550608284ff714d3243d81013ad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019295421819991536",
"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/8853865359787821456/+/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:520c05dcf342a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1614696263337014",
"url": "https://ci.chromium.org/b/8853865359787821456",
"utcnow_ts": "1614696263706959"
}
}
[14:44:23.715] Invocation finished in 53m57.378709497s with status FAILED