[19:19:24.762] New invocation is queued and will start shortly
[19:19:26.038] Starting the invocation (attempt 1)
[19:19:26.075] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:19:26.075] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:19:26.075] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019184127634034016",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019184127634034016",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9",
"gitiles_ref:refs/heads/master"
]
}
[19:19:27.795] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614799166336577",
"id": "8853754065318544256",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614799167412543",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45885",
"builder:linux-chromeos-rel",
"buildset:commit/git/f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019184127634034016",
"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/8853754065318544256/+/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": "1614799167412686",
"url": "https://ci.chromium.org/b/8853754065318544256",
"utcnow_ts": "1614799167768789"
}
}
[19:19:27.795] Task URL: https://ci.chromium.org/b/8853754065318544256
[19:19:27.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:2:0) after 1m0s
[19:20:18.294] Received PubSub notification, asking Buildbucket for the build status
[19:20:18.365] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:27.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:2:0)
[19:20:27.924] Timer tick, asking Buildbucket for the build status
[19:20:28.002] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:28.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:4:0) after 1m0s
[19:21:28.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:4:0)
[19:21:28.114] Timer tick, asking Buildbucket for the build status
[19:21:28.230] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:28.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:5:0) after 1m0s
[19:22:28.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:5:0)
[19:22:28.250] Timer tick, asking Buildbucket for the build status
[19:22:28.329] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:28.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:6:0) after 1m0s
[19:23:28.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:6:0)
[19:23:28.353] Timer tick, asking Buildbucket for the build status
[19:23:28.573] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:28.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:7:0) after 1m0s
[19:24:28.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:7:0)
[19:24:28.597] Timer tick, asking Buildbucket for the build status
[19:24:28.785] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:28.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:8:0) after 1m0s
[19:25:28.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:8:0)
[19:25:28.835] Timer tick, asking Buildbucket for the build status
[19:25:28.979] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:28.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:9:0) after 1m0s
[19:26:29.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:9:0)
[19:26:29.028] Timer tick, asking Buildbucket for the build status
[19:26:29.219] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:29.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:10:0) after 1m0s
[19:27:29.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:10:0)
[19:27:29.255] Timer tick, asking Buildbucket for the build status
[19:27:29.371] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:29.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:11:0) after 1m0s
[19:28:29.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:11:0)
[19:28:29.402] Timer tick, asking Buildbucket for the build status
[19:28:29.798] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:29.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:12:0) after 1m0s
[19:29:30.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:12:0)
[19:29:30.285] Timer tick, asking Buildbucket for the build status
[19:29:30.357] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:30.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:13:0) after 1m0s
[19:30:30.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:13:0)
[19:30:30.398] Timer tick, asking Buildbucket for the build status
[19:30:30.713] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:30.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:14:0) after 1m0s
[19:31:31.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:14:0)
[19:31:31.212] Timer tick, asking Buildbucket for the build status
[19:31:31.300] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:31.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:15:0) after 1m0s
[19:32:31.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:15:0)
[19:32:31.406] Timer tick, asking Buildbucket for the build status
[19:32:31.500] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:31.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:16:0) after 1m0s
[19:33:31.634] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:16:0)
[19:33:31.634] Timer tick, asking Buildbucket for the build status
[19:33:31.700] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:31.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:17:0) after 1m0s
[19:34:31.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:17:0)
[19:34:31.822] Timer tick, asking Buildbucket for the build status
[19:34:31.899] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:31.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:18:0) after 1m0s
[19:35:31.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:18:0)
[19:35:31.966] Timer tick, asking Buildbucket for the build status
[19:35:32.086] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:32.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:19:0) after 1m0s
[19:36:32.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:19:0)
[19:36:32.109] Timer tick, asking Buildbucket for the build status
[19:36:32.195] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:32.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:20:0) after 1m0s
[19:37:32.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:20:0)
[19:37:32.259] Timer tick, asking Buildbucket for the build status
[19:37:32.382] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:32.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:21:0) after 1m0s
[19:38:32.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:21:0)
[19:38:32.483] Timer tick, asking Buildbucket for the build status
[19:38:32.566] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:32.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:22:0) after 1m0s
[19:39:32.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:22:0)
[19:39:32.670] Timer tick, asking Buildbucket for the build status
[19:39:32.740] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:32.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:23:0) after 1m0s
[19:40:32.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:23:0)
[19:40:32.815] Timer tick, asking Buildbucket for the build status
[19:40:32.928] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:32.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:24:0) after 1m0s
[19:41:33.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:24:0)
[19:41:33.009] Timer tick, asking Buildbucket for the build status
[19:41:33.094] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:33.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:25:0) after 1m0s
[19:42:33.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:25:0)
[19:42:33.296] Timer tick, asking Buildbucket for the build status
[19:42:33.434] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:33.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:26:0) after 1m0s
[19:43:33.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:26:0)
[19:43:33.517] Timer tick, asking Buildbucket for the build status
[19:43:33.582] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:33.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:27:0) after 1m0s
[19:44:33.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:27:0)
[19:44:33.683] Timer tick, asking Buildbucket for the build status
[19:44:33.967] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:33.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:28:0) after 1m0s
[19:45:34.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:28:0)
[19:45:34.078] Timer tick, asking Buildbucket for the build status
[19:45:34.175] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:34.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:29:0) after 1m0s
[19:46:34.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:29:0)
[19:46:34.217] Timer tick, asking Buildbucket for the build status
[19:46:34.300] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:34.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:30:0) after 1m0s
[19:47:34.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:30:0)
[19:47:34.364] Timer tick, asking Buildbucket for the build status
[19:47:34.556] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:34.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:31:0) after 1m0s
[19:48:34.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:31:0)
[19:48:34.696] Timer tick, asking Buildbucket for the build status
[19:48:34.770] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:34.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:32:0) after 1m0s
[19:49:34.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:32:0)
[19:49:34.878] Timer tick, asking Buildbucket for the build status
[19:49:35.372] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:35.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:33:0) after 1m0s
[19:50:36.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:33:0)
[19:50:36.085] Timer tick, asking Buildbucket for the build status
[19:50:37.220] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:37.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:34:0) after 1m0s
[19:51:37.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:34:0)
[19:51:37.302] Timer tick, asking Buildbucket for the build status
[19:51:37.431] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:37.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:35:0) after 1m0s
[19:52:37.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:35:0)
[19:52:37.503] Timer tick, asking Buildbucket for the build status
[19:52:37.562] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:37.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:36:0) after 1m0s
[19:53:37.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:36:0)
[19:53:37.570] Timer tick, asking Buildbucket for the build status
[19:53:37.993] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:37.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:37:0) after 1m0s
[19:54:38.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:37:0)
[19:54:38.035] Timer tick, asking Buildbucket for the build status
[19:54:38.417] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:38.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:38:0) after 1m0s
[19:55:38.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:38:0)
[19:55:38.553] Timer tick, asking Buildbucket for the build status
[19:55:38.852] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:38.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:39:0) after 1m0s
[19:56:38.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:39:0)
[19:56:38.890] Timer tick, asking Buildbucket for the build status
[19:56:39.132] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:39.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:40:0) after 1m0s
[19:57:39.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:40:0)
[19:57:39.260] Timer tick, asking Buildbucket for the build status
[19:57:39.315] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:39.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:41:0) after 1m0s
[19:58:39.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:41:0)
[19:58:39.383] Timer tick, asking Buildbucket for the build status
[19:58:39.470] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:39.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:42:0) after 1m0s
[19:59:39.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:42:0)
[19:59:39.510] Timer tick, asking Buildbucket for the build status
[19:59:39.590] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:39.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:43:0) after 1m0s
[20:00:40.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:43:0)
[20:00:40.281] Timer tick, asking Buildbucket for the build status
[20:00:40.384] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:40.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:44:0) after 1m0s
[20:01:41.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:44:0)
[20:01:41.141] Timer tick, asking Buildbucket for the build status
[20:01:41.233] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:41.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:45:0) after 1m0s
[20:02:41.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:45:0)
[20:02:41.403] Timer tick, asking Buildbucket for the build status
[20:02:41.501] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:41.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:46:0) after 1m0s
[20:03:41.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:46:0)
[20:03:41.918] Timer tick, asking Buildbucket for the build status
[20:03:41.980] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:41.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:47:0) after 1m0s
[20:04:42.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:47:0)
[20:04:42.405] Timer tick, asking Buildbucket for the build status
[20:04:42.490] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:42.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:48:0) after 1m0s
[20:05:42.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:48:0)
[20:05:42.566] Timer tick, asking Buildbucket for the build status
[20:05:42.959] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:42.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:49:0) after 1m0s
[20:06:42.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:49:0)
[20:06:42.965] Timer tick, asking Buildbucket for the build status
[20:06:43.085] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:43.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:50:0) after 1m0s
[20:07:43.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:50:0)
[20:07:43.456] Timer tick, asking Buildbucket for the build status
[20:07:43.605] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:43.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:51:0) after 1m0s
[20:08:43.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:51:0)
[20:08:43.659] Timer tick, asking Buildbucket for the build status
[20:08:43.774] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:43.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:52:0) after 1m0s
[20:09:43.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:52:0)
[20:09:43.866] Timer tick, asking Buildbucket for the build status
[20:09:43.973] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:43.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:53:0) after 1m0s
[20:10:44.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:53:0)
[20:10:44.038] Timer tick, asking Buildbucket for the build status
[20:10:44.149] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:44.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:54:0) after 1m0s
[20:11:44.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:54:0)
[20:11:44.258] Timer tick, asking Buildbucket for the build status
[20:11:44.475] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:44.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:55:0) after 1m0s
[20:12:44.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:55:0)
[20:12:44.582] Timer tick, asking Buildbucket for the build status
[20:12:44.802] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:44.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:56:0) after 1m0s
[20:13:44.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:56:0)
[20:13:44.840] Timer tick, asking Buildbucket for the build status
[20:13:45.322] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:45.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:57:0) after 1m0s
[20:14:45.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:57:0)
[20:14:45.468] Timer tick, asking Buildbucket for the build status
[20:14:45.563] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:45.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:58:0) after 1m0s
[20:15:45.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:58:0)
[20:15:45.710] Timer tick, asking Buildbucket for the build status
[20:15:45.797] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:45.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:59:0) after 1m0s
[20:16:45.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:59:0)
[20:16:45.999] Timer tick, asking Buildbucket for the build status
[20:16:46.093] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:46.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:60:0) after 1m0s
[20:17:46.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:60:0)
[20:17:46.242] Timer tick, asking Buildbucket for the build status
[20:17:46.341] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:46.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:61:0) after 1m0s
[20:18:46.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:61:0)
[20:18:46.429] Timer tick, asking Buildbucket for the build status
[20:18:46.536] Build 8853754065318544256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:46.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019184127634034016:62:0) after 1m0s
[20:19:45.654] Received PubSub notification, asking Buildbucket for the build status
[20:19:45.740] Build 8853754065318544256: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:19:45.740] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614802785034141",
"created_by": "project:chromium",
"created_ts": "1614799166336577",
"failure_reason": "BUILD_FAILURE",
"id": "8853754065318544256",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9\"}}",
"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\": \"f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9\", \"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\": 45885, \"got_angle_revision\": \"1de8b5a697e731c03083bda16b2df67c1d7a4c47\", \"got_dawn_revision\": \"9b5f442c78cb642b18bc4f18ab672f1b4426c934\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9\", \"got_revision_cp\": \"refs/heads/master@{#859478}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"1bf9a25b8165c205ba4ed305e8e84bc4a8e54fbc\", \"got_v8_revision_cp\": \"refs/heads/9.1.31@{#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\": \"f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9\", \"swarm_hashes_refs/heads/master(at){#859478}_without_patch\": {\"absl_hardening_tests\": \"74156c66f8c757fb089d38c358d40430370b6813\", \"accessibility_unittests\": \"deef87400eddb904ccd6e10cbbd1d94cd169a44e\", \"angle_unittests\": \"48e94072a88821c99532759e5df2d86d2c554b12\", \"app_list_unittests\": \"0333aea0d39d7bec2098455b1f8a5eee1770e6ad\", \"app_shell_unittests\": \"bb8de074b780908e6674baf3bf12df7d0320be41\", \"ash_unittests\": \"28ad5c98498bc5a463b900c355dfed7c13adf69e\", \"aura_unittests\": \"5db654b5363f3ee50c3b16877ca3d75ccd04e4dd\", \"base_unittests\": \"d2ad500d0f27329d575947d1569c7776c1a6c823\", \"base_util_unittests\": \"72228ae7e99e7cbdd9b1a156bd6bd2f59343004f\", \"blink_common_unittests\": \"6e9b977fe5941f10a052776cdbbda5adb617a0d3\", \"blink_fuzzer_unittests\": \"8aa242e8e553e4010aef26f2e075db5c323cbc59\", \"blink_heap_unittests\": \"2e0486cc64cbd44965e173d5e0ec55661bf27c3f\", \"blink_platform_unittests\": \"3a11ccafc9d1c05987b7a1417a904220b056c28f\", \"blink_unittests\": \"0925bc1051341230d39cbbfc50e4ef6402dd63bb\", \"boringssl_crypto_tests\": \"ccd71a8b2e0fd434c419aa7a3952433b680d17c0\", \"boringssl_ssl_tests\": \"60099910fde29901b34e0f235bceae1736615b8e\", \"browser_tests\": \"0282e93fa9fc228c4cd9869736550b99752d1476\", \"capture_unittests\": \"a4d5f637558ac159b8561f9652df04b012347960\", \"cast_unittests\": \"59ab8357110afa5995db4f25bf80098ac1a32fc2\", \"cc_unittests\": \"0df3ad44745eb15cdb311e346ac9c26f7bd31049\", \"chrome_app_unittests\": \"1cb9aeaee1583a1f9f7a81f6fc15b884254e24a2\", \"chromedriver_unittests\": \"55b5019d9a2c9db6a81b6b398ccfd1686f0ee868\", \"chromeos_components_unittests\": \"80241dccb0c31c2138e7c5846e6f3f7b4969974c\", \"chromeos_unittests\": \"6b27451b080ced3b51762260c39581afd47fde80\", \"color_unittests\": \"20963644f07afcac7e9ccd51e477687f9b10c36f\", \"components_browsertests\": \"498d77825ff1a9f5364a139e46a904c7283dbe0a\", \"components_unittests\": \"83dd71a5d42ab0ec2b8c9d000554de22d1b291da\", \"compositor_unittests\": \"6c79fdf36f03bc5f22e02dc396fae62a7b791d08\", \"content_browsertests\": \"a6210cab8f48716ae72227246fdf3d5a754fd71f\", \"content_unittests\": \"1a4516ad1a1793fb068bf01032edbf03774d4906\", \"crashpad_tests\": \"138d8e483698979282b5c6e6aaa763a8a242a2aa\", \"crypto_unittests\": \"3b906656f8fb75bc71875c21fe56373e2e6aa75b\", \"dbus_unittests\": \"c86cd32044b64610fe9f1a4a975ad0d7aef58423\", \"device_unittests\": \"d9fbc24be27e868ddda693a770e689f832e7233c\", \"display_unittests\": \"be0b25359bc8d540b478f7a5b5ab2f4bf353b0cd\", \"events_unittests\": \"f7949c2e7c103f53023f3d32d1498fb06bf0e3e5\", \"exo_unittests\": \"f66b60e4e6cdf1f6d45f65c924ead0828a96e513\", \"extensions_browsertests\": \"e3437b43c8ad1b4ecfb8425eb7aac990195e0d94\", \"extensions_unittests\": \"38198c3e589d95a7c8ec00b18ced13abc05c2305\", \"filesystem_service_unittests\": \"c8b0f3d327f7bc75a59d00a810d1f66b5dd8a4bf\", \"gcm_unit_tests\": \"03138518c4f83c6065ee32b1f28a27222fc0e00c\", \"gfx_unittests\": \"552ce04f05c660fb076760e49e637d26736d4650\", \"gin_unittests\": \"0decefb6918fce7aea49bbe8b5433def02daa242\", \"gl_unittests_ozone\": \"ad7294383934997580695cf6b24cc9f8f073b77e\", \"google_apis_unittests\": \"a6586a1c3a800c891f938a296f6ac98e924f0f70\", \"gpu_unittests\": \"35405b1888b31c48b794a3a32ad5243339b2d9ec\", \"gwp_asan_unittests\": \"6f8e5f546adde34e44356948edd046bad584a86e\", \"interactive_ui_tests\": \"4be1cffc8f2155fa7d5c500fbe81a4500b34d4cf\", \"ipc_tests\": \"7ecf3cd0af9a47741529e612be60d9dfabf6b785\", \"jingle_unittests\": \"655347160aa2a1a8dd40b3ab24f9e5d461bae1cc\", \"keyboard_unittests\": \"5595d123137b77f3f610a8b25c61456452039756\", \"latency_unittests\": \"a8999bac1d7f107db89e1bf21d746242741aeb92\", \"libjingle_xmpp_unittests\": \"e8cf040b8c8342650ac9597e251141fbe889f4a0\", \"liburlpattern_unittests\": \"a83548cd1725a0e111f6ac079a2b45bc1aed8bef\", \"media_blink_unittests\": \"c8d5546078d9e5aaf0dbd67392e0c6d6ba4ed268\", \"media_unittests\": \"4b60a76988b1e0d4cfdd69527a1d3d0c90373dd9\", \"message_center_unittests\": \"96f091604a764b3e00ae5e1f64b772dff53c3f46\", \"midi_unittests\": \"2c3f8e2be7a13c0b7646e6d57f8d36523194e4b4\", \"mojo_core_unittests\": \"0cf6addcb006351ea0994dfb7b8caf6874598f66\", \"mojo_unittests\": \"9196d9d5d822b160ad42920cbf6e9c985cf312f6\", \"nacl_helper_nonsfi_unittests\": \"2fa781e0d8e33b3b19a681794590a142f0e78774\", \"nacl_loader_unittests\": \"40907a7ad0c5c1d93bce7b57d2a805a2f03fd58b\", \"native_theme_unittests\": \"076de4594eb9e3719201fdb01c032020ba15e1e6\", \"net_unittests\": \"2c58c49cc52acfdb79b9b98ad3bbb657652d35bc\", \"ozone_gl_unittests\": \"0f3a2a77bf0a712eaeafde728ec33371ca982e06\", \"ozone_unittests\": \"6410b35f5b063683ca0168f61505bef3e80f2a50\", \"ozone_x11_unittests\": \"71111a42121242c30851565dd64b2cc402b953ca\", \"pdf_unittests\": \"e65458c0a31cc9211170673e7732ed3096f5f84b\", \"perfetto_unittests\": \"d5b653e01329b24136b9e0122721ae1388ba9cf5\", \"ppapi_unittests\": \"56dc14330d622200aeb54ecb0c158ac02dc211e1\", \"printing_unittests\": \"62c63f88900a6059a364d2198ba2e2fe16ff0fdb\", \"remoting_unittests\": \"1fd2ed3bc99589e60892b6b2d807f57294b53624\", \"sandbox_linux_unittests\": \"2d8f8595f77f6f48199567a472fb4e8f4ff26c76\", \"service_manager_unittests\": \"8cdff59b8eb053365e6225febbf04e995be09c8b\", \"services_unittests\": \"742ba481a667976edeb04e074237cb2ab6c7c334\", \"shell_dialogs_unittests\": \"46766ea193e0886a476746668b41e53529f83a69\", \"shell_encryption_unittests\": \"54a954d04c4093cec8655f1a66308bb34098459d\", \"skia_unittests\": \"dc4301023e4f59e6a503fd0858f871b533a78628\", \"snapshot_unittests\": \"793a6e1dd85f991fa2188d3484eb1d4052a94236\", \"sql_unittests\": \"268f7f889e84185a5b4d9e7a7064630bbb59d534\", \"storage_unittests\": \"c7bc205c7e98e10a913f8f00fe3bfe00f0464f2a\", \"sync_integration_tests\": \"19f64bb2b26d71e87d336e822ea2cc05f8ab2c0f\", \"traffic_annotation_auditor_unittests\": \"63dc975fd3685d54e84e5388a987ecb7451dcc90\", \"ui_base_unittests\": \"62128a8b4d98ea405678a41ee81fa1e332a4b7b9\", \"ui_chromeos_unittests\": \"5d8369d2d9f0b2d4bb5b8cbbffa1bb8f53eb1ac1\", \"ui_touch_selection_unittests\": \"eaed9c1a52cebf1f80ccedab2550e29a8efc66be\", \"unit_tests\": \"3a88f3d80d1d0004fe486ce1b953ea52f5345169\", \"url_unittests\": \"67c5cfe3e06f9347d774eb05e136fab01f954dc4\", \"usage_time_limit_unittests\": \"552ee4a73ba38f8e9cb8efdbf94c65e26ac309a7\", \"views_unittests\": \"e80539820285185a53dc40a2ecc696f4c52731c0\", \"viz_unittests\": \"e962824b480624b5aba12a2ffbe38557141aacf3\", \"wayland_client_perftests\": \"8ac52901edc254c8225566eb234117388540ab71\", \"wayland_client_tests\": \"3668c42f1b7cd31d1380d2729433d0e7624b6d5b\", \"wm_unittests\": \"c668281de9c27e5c2075d86c13d6a272819abc1a\", \"wtf_unittests\": \"fd55433c0eaa745c14aa6e8088efa1e185a51e6e\", \"zlib_unittests\": \"44d1e273942a1a1442bd05c826d668f410ce7648\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_256f5ddd0fd0dc12b292dc06910d383b36d434fcd402039f959c9b6741115c5c_v2\", \"builder_28be8b4e850be8bd7a9db0671ff876681ccdf0c2c048860e172c20e0c8b9b79b_v2\", \"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_b1565bc506c7331f0456efe3635f211a71cb90c7c9b1da510ebf59e5503b99bd_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-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": "1614799217743547",
"status": "COMPLETED",
"status_changed_ts": "1614799218159806",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45885",
"builder:linux-chromeos-rel",
"buildset:commit/git/f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f20be00bd4aa67f94ccfd1672cf4acf6e8f7f7e9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019184127634034016",
"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/8853754065318544256/+/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:521259773ae6d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1614802785034531",
"url": "https://ci.chromium.org/b/8853754065318544256",
"utcnow_ts": "1614802785731123"
}
}
[20:19:45.740] Invocation finished in 1h0m20.996546506s with status FAILED