[21:44:40.276] New invocation is queued and will start shortly
[21:44:41.397] Starting the invocation (attempt 1)
[21:44:41.441] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:44:41.441] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:44:41.441] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029503042932685072",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"afa861742e30296ddf61d6b0677d90a85af98054\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029503042932685072",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/afa861742e30296ddf61d6b0677d90a85af98054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/afa861742e30296ddf61d6b0677d90a85af98054",
"gitiles_ref:refs/heads/master"
]
}
[21:44:42.796] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604958281542758",
"id": "8864072980931616048",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"afa861742e30296ddf61d6b0677d90a85af98054\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604958282443561",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42728",
"builder:linux-chromeos-rel",
"buildset:commit/git/afa861742e30296ddf61d6b0677d90a85af98054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/afa861742e30296ddf61d6b0677d90a85af98054",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029503042932685072",
"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/8864072980931616048/+/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": "1604958282443753",
"url": "https://ci.chromium.org/b/8864072980931616048",
"utcnow_ts": "1604958282777850"
}
}
[21:44:42.796] Task URL: https://ci.chromium.org/b/8864072980931616048
[21:44:42.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:2:0) after 1m0s
[21:45:14.944] Received PubSub notification, asking Buildbucket for the build status
[21:45:15.021] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:42.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:2:0)
[21:45:42.870] Timer tick, asking Buildbucket for the build status
[21:45:42.988] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:42.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:4:0) after 1m0s
[21:46:43.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:4:0)
[21:46:43.017] Timer tick, asking Buildbucket for the build status
[21:46:43.163] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:43.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:5:0) after 1m0s
[21:47:43.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:5:0)
[21:47:43.253] Timer tick, asking Buildbucket for the build status
[21:47:43.330] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:43.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:6:0) after 1m0s
[21:48:43.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:6:0)
[21:48:43.437] Timer tick, asking Buildbucket for the build status
[21:48:43.695] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:43.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:7:0) after 1m0s
[21:49:43.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:7:0)
[21:49:43.809] Timer tick, asking Buildbucket for the build status
[21:49:44.299] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:44.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:8:0) after 1m0s
[21:50:44.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:8:0)
[21:50:44.288] Timer tick, asking Buildbucket for the build status
[21:50:44.468] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:44.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:9:0) after 1m0s
[21:51:44.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:9:0)
[21:51:44.466] Timer tick, asking Buildbucket for the build status
[21:51:44.776] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:44.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:10:0) after 1m0s
[21:52:44.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:10:0)
[21:52:44.825] Timer tick, asking Buildbucket for the build status
[21:52:44.911] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:44.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:11:0) after 1m0s
[21:53:45.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:11:0)
[21:53:45.018] Timer tick, asking Buildbucket for the build status
[21:53:45.140] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:45.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:12:0) after 1m0s
[21:54:45.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:12:0)
[21:54:45.216] Timer tick, asking Buildbucket for the build status
[21:54:45.331] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:45.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:13:0) after 1m0s
[21:55:45.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:13:0)
[21:55:45.365] Timer tick, asking Buildbucket for the build status
[21:55:45.712] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:45.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:14:0) after 1m0s
[21:56:45.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:14:0)
[21:56:45.755] Timer tick, asking Buildbucket for the build status
[21:56:46.828] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:46.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:15:0) after 1m0s
[21:57:46.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:15:0)
[21:57:46.931] Timer tick, asking Buildbucket for the build status
[21:57:46.992] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:46.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:16:0) after 1m0s
[21:58:47.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:16:0)
[21:58:47.189] Timer tick, asking Buildbucket for the build status
[21:58:47.299] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:47.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:17:0) after 1m0s
[21:59:47.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:17:0)
[21:59:47.413] Timer tick, asking Buildbucket for the build status
[21:59:47.533] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:47.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:18:0) after 1m0s
[22:00:47.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:18:0)
[22:00:47.627] Timer tick, asking Buildbucket for the build status
[22:00:47.697] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:47.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:19:0) after 1m0s
[22:01:47.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:19:0)
[22:01:47.896] Timer tick, asking Buildbucket for the build status
[22:01:48.819] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:48.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:20:0) after 1m0s
[22:02:49.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:20:0)
[22:02:49.215] Timer tick, asking Buildbucket for the build status
[22:02:49.305] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:49.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:21:0) after 1m0s
[22:03:49.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:21:0)
[22:03:49.443] Timer tick, asking Buildbucket for the build status
[22:03:49.529] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:49.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:22:0) after 1m0s
[22:04:49.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:22:0)
[22:04:49.625] Timer tick, asking Buildbucket for the build status
[22:04:49.741] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:49.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:23:0) after 1m0s
[22:05:49.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:23:0)
[22:05:49.813] Timer tick, asking Buildbucket for the build status
[22:05:49.880] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:49.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:24:0) after 1m0s
[22:06:49.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:24:0)
[22:06:49.921] Timer tick, asking Buildbucket for the build status
[22:06:50.125] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:50.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:25:0) after 1m0s
[22:07:50.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:25:0)
[22:07:50.167] Timer tick, asking Buildbucket for the build status
[22:07:50.237] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:50.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:26:0) after 1m0s
[22:08:50.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:26:0)
[22:08:50.299] Timer tick, asking Buildbucket for the build status
[22:08:50.491] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:50.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:27:0) after 1m0s
[22:09:50.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:27:0)
[22:09:50.659] Timer tick, asking Buildbucket for the build status
[22:09:50.828] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:50.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:28:0) after 1m0s
[22:10:50.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:28:0)
[22:10:50.924] Timer tick, asking Buildbucket for the build status
[22:10:51.006] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:51.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:29:0) after 1m0s
[22:11:51.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:29:0)
[22:11:51.211] Timer tick, asking Buildbucket for the build status
[22:11:51.292] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:51.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:30:0) after 1m0s
[22:12:51.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:30:0)
[22:12:51.327] Timer tick, asking Buildbucket for the build status
[22:12:51.534] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:51.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:31:0) after 1m0s
[22:13:51.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:31:0)
[22:13:51.792] Timer tick, asking Buildbucket for the build status
[22:13:51.930] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:51.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:32:0) after 1m0s
[22:14:52.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:32:0)
[22:14:52.076] Timer tick, asking Buildbucket for the build status
[22:14:52.390] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:52.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:33:0) after 1m0s
[22:15:52.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:33:0)
[22:15:52.440] Timer tick, asking Buildbucket for the build status
[22:15:52.626] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:52.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:34:0) after 1m0s
[22:16:52.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:34:0)
[22:16:52.672] Timer tick, asking Buildbucket for the build status
[22:16:52.929] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:52.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:35:0) after 1m0s
[22:17:52.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:35:0)
[22:17:52.985] Timer tick, asking Buildbucket for the build status
[22:17:53.805] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:53.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:36:0) after 1m0s
[22:18:53.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:36:0)
[22:18:53.949] Timer tick, asking Buildbucket for the build status
[22:18:54.026] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:54.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:37:0) after 1m0s
[22:19:54.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:37:0)
[22:19:54.047] Timer tick, asking Buildbucket for the build status
[22:19:54.210] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:54.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:38:0) after 1m0s
[22:20:54.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:38:0)
[22:20:54.339] Timer tick, asking Buildbucket for the build status
[22:20:54.443] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:54.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:39:0) after 1m0s
[22:21:54.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:39:0)
[22:21:54.565] Timer tick, asking Buildbucket for the build status
[22:21:54.635] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:54.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:40:0) after 1m0s
[22:22:54.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:40:0)
[22:22:54.707] Timer tick, asking Buildbucket for the build status
[22:22:54.782] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:54.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:41:0) after 1m0s
[22:23:54.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:41:0)
[22:23:54.913] Timer tick, asking Buildbucket for the build status
[22:23:55.876] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:55.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:42:0) after 1m0s
[22:24:55.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:42:0)
[22:24:55.964] Timer tick, asking Buildbucket for the build status
[22:24:56.084] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:56.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:43:0) after 1m0s
[22:25:56.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:43:0)
[22:25:56.162] Timer tick, asking Buildbucket for the build status
[22:25:56.262] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:56.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:44:0) after 1m0s
[22:26:56.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:44:0)
[22:26:56.342] Timer tick, asking Buildbucket for the build status
[22:26:56.467] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:56.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:45:0) after 1m0s
[22:27:56.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:45:0)
[22:27:56.633] Timer tick, asking Buildbucket for the build status
[22:27:56.775] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:56.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:46:0) after 1m0s
[22:28:56.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:46:0)
[22:28:56.822] Timer tick, asking Buildbucket for the build status
[22:28:57.167] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:57.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:47:0) after 1m0s
[22:29:57.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:47:0)
[22:29:57.293] Timer tick, asking Buildbucket for the build status
[22:29:57.461] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:57.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:48:0) after 1m0s
[22:30:57.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:48:0)
[22:30:57.538] Timer tick, asking Buildbucket for the build status
[22:30:57.668] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:57.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:49:0) after 1m0s
[22:31:57.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:49:0)
[22:31:57.734] Timer tick, asking Buildbucket for the build status
[22:31:57.845] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:57.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:50:0) after 1m0s
[22:32:57.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:50:0)
[22:32:57.884] Timer tick, asking Buildbucket for the build status
[22:32:58.278] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:58.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:51:0) after 1m0s
[22:33:58.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:51:0)
[22:33:58.437] Timer tick, asking Buildbucket for the build status
[22:33:58.949] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:58.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:52:0) after 1m0s
[22:34:59.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:52:0)
[22:34:59.026] Timer tick, asking Buildbucket for the build status
[22:35:00.174] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:00.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:53:0) after 1m0s
[22:36:00.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:53:0)
[22:36:00.272] Timer tick, asking Buildbucket for the build status
[22:36:00.418] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:00.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:54:0) after 1m0s
[22:37:02.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:54:0)
[22:37:02.685] Timer tick, asking Buildbucket for the build status
[22:37:02.856] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:02.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:55:0) after 1m0s
[22:38:03.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:55:0)
[22:38:03.009] Timer tick, asking Buildbucket for the build status
[22:38:03.181] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:03.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:56:0) after 1m0s
[22:39:03.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:56:0)
[22:39:03.246] Timer tick, asking Buildbucket for the build status
[22:39:03.433] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:03.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:57:0) after 1m0s
[22:40:04.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:57:0)
[22:40:04.294] Timer tick, asking Buildbucket for the build status
[22:40:04.429] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:04.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:58:0) after 1m0s
[22:41:04.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:58:0)
[22:41:04.451] Timer tick, asking Buildbucket for the build status
[22:41:04.683] Build 8864072980931616048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:04.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029503042932685072:59:0) after 1m0s
[22:41:12.102] Received PubSub notification, asking Buildbucket for the build status
[22:41:12.334] Build 8864072980931616048: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:41:12.334] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604961670751589",
"created_by": "project:chromium",
"created_ts": "1604958281542758",
"failure_reason": "BUILD_FAILURE",
"id": "8864072980931616048",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"afa861742e30296ddf61d6b0677d90a85af98054\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- HandsOffEnrollmentTest.NetworkConnectionReady\"}, \"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\": \"afa861742e30296ddf61d6b0677d90a85af98054\", \"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-114-jw8n\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42728, \"got_angle_revision\": \"9217d49db032476b003472b539b5c76fff234be1\", \"got_dawn_revision\": \"0053fef8a47d72a1a240df80395d5a4bf9f6170c\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"afa861742e30296ddf61d6b0677d90a85af98054\", \"got_revision_cp\": \"refs/heads/master@{#825479}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"c19c1b2ce2a08246c5ffd51bc78dfb63c8d8183d\", \"got_v8_revision_cp\": \"refs/heads/8.8.264@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"afa861742e30296ddf61d6b0677d90a85af98054\", \"swarm_hashes_refs/heads/master(at){#825479}_without_patch\": {\"absl_hardening_tests\": \"8547d3de1ac26f71562075c856ea9c705a62ba90\", \"accessibility_unittests\": \"0f63701f2ed546d5b34b9cae525d5f3177f72b6a\", \"angle_unittests\": \"1412aa0ba9f92235a1013b795a0ffb9dc5b7e14d\", \"app_list_unittests\": \"36a4e0e2a47f98b7cfcecee667450d257097d922\", \"app_shell_unittests\": \"99a7eb52db580f79ca02d0d1ad9459628701bc8c\", \"ash_unittests\": \"702f74377f3951312cfcf040d31c2da7cbdf0b33\", \"aura_unittests\": \"b43c0b8dfed19b68886dc0d2ed0802ff121e2d6b\", \"base_unittests\": \"616973c0fefa34f9a95a8ad61c886c8c9b756789\", \"base_util_unittests\": \"95450d67db5496f5460c894153bdcbee878133c5\", \"blink_common_unittests\": \"f451f82cb1cec2aa86e0d2692c17d7699e9f7346\", \"blink_fuzzer_unittests\": \"e7d912e404830bc14ffbe0905aee5f3d8e33070b\", \"blink_heap_unittests\": \"d8e582a97913c4e0bfed272856e1a7d5390e4a89\", \"blink_platform_unittests\": \"d9a3b4e3ea9d7e1a002b6e25a5a8e8f9eda25684\", \"blink_unittests\": \"2aa185013a85748c409bbd20db56906973faeb92\", \"boringssl_crypto_tests\": \"20920e9e0cfa05026c39cd1e635e56891d3e76ee\", \"boringssl_ssl_tests\": \"740475c5884e21d92a5301241932bbe5320021b6\", \"browser_tests\": \"d2deac40ffacace9fbe987da6c1d76876fc86411\", \"capture_unittests\": \"f4a69fd585839b1218ea781c094f69bc0d80b6f9\", \"cast_unittests\": \"bf694907fe2f3537a876fed2ab155990f99927bc\", \"cc_unittests\": \"9fb24b0759aa721aa29fdcc233c47af27fa4dac8\", \"chrome_app_unittests\": \"f39fb1d7f4475b4cf5e3cd1c71889659b728e074\", \"chromedriver_unittests\": \"a98e63eca01525386ebdaa8ce04262fbfef5b654\", \"chromeos_components_unittests\": \"92415aef7b5bf6a457ba4e08e94da162f87d543a\", \"chromeos_unittests\": \"021340b92f4ba3339823d4a2c8bfc6dab6cad22d\", \"components_browsertests\": \"d1211a5c201409eb82909306d4f82abff6129474\", \"components_unittests\": \"3e648ae1e15d8bc60e6656e16bda1886ad25acc9\", \"compositor_unittests\": \"59f4b8af85d94a4c96e1003bb4e3bc5a8fa7157d\", \"content_browsertests\": \"bfc20ad613b4f78d6ef65c28edce3590fb3ac39b\", \"content_unittests\": \"55b8e4e0420ef96a652fc5d94e578ad99f2ee9d6\", \"crypto_unittests\": \"f6f79d1a5405b908697167a82b7a0a47432a07f7\", \"dbus_unittests\": \"c0bb2c7be495861507ed6afd367c058d1083fb25\", \"device_unittests\": \"717c2a79dc50ade521650e7f94e07f0d8fee0f75\", \"display_unittests\": \"8544fa28be01f345e92c02be045354b4c94e3d02\", \"events_unittests\": \"b3e5e6ca57c916135b08ece509e0195f7fc0dfa5\", \"exo_unittests\": \"52d11af395cf3fa893d70af6e225ec0265895f7f\", \"extensions_browsertests\": \"bd866b4e809dc019741236d46642cc7840ae157b\", \"extensions_unittests\": \"e7064ae05ae89cf22775867680b8f65ae61a19fc\", \"filesystem_service_unittests\": \"a6e844ebe6693e31ec3586b78afba6bce728f968\", \"gcm_unit_tests\": \"61855bfd63a9064d1080901872dd0b2988e1fb3c\", \"gfx_unittests\": \"4aa606def2bbaa90ab8ef5ea5fd15c89d4bcbfb9\", \"gin_unittests\": \"ce6be0990fea08fb5219062962a274d9a8af11a7\", \"gl_unittests_ozone\": \"39f5a6bf3545d78fcbaa7e5e94742c86d0e67b36\", \"google_apis_unittests\": \"6b3b5f718d9ffdcb6517610eee6b8bf5951cab88\", \"gpu_unittests\": \"9370cdd01ce1fc03e2e32a428b05d2355afa812e\", \"gwp_asan_unittests\": \"daffc5f9ac4dcdcdd914f50d637f9df7f33d4579\", \"interactive_ui_tests\": \"abdc58a58e64ff53a51fd5aa8ea700f99116b2c6\", \"ipc_tests\": \"d4cb1a36a71da6ddf4e2f2c160e0630533a124ca\", \"jingle_unittests\": \"01bbc50ee1e09a621e8305f14f5b934e1b1194e3\", \"keyboard_unittests\": \"9baf5c8afe2cf167400365057e51c43dbd43ec7d\", \"latency_unittests\": \"9e038c372bfc49c99893743c774474151f752cc7\", \"libjingle_xmpp_unittests\": \"aa880c887b0ee24d2e0ccfbb60f4b801a9f7fcb4\", \"liburlpattern_unittests\": \"9e2212d1d95f7c06d074a4847d2be7f643499a51\", \"media_blink_unittests\": \"c750c688533d8c2cf23f94077aaf83f8b57e853c\", \"media_unittests\": \"3f8301b257547b0a606a5a7fedb0f07d397f12d2\", \"message_center_unittests\": \"7e86e18722de1bab7bd62bbbaeceb0486cd718b2\", \"midi_unittests\": \"fe8720859b9de986076e7e1e442675dfdd12ff88\", \"mojo_core_unittests\": \"e422dad99526334edc0eba9178df5fb679f244d9\", \"mojo_unittests\": \"01ecb341b8e273be6542602570a4d644dfc1dfe8\", \"nacl_helper_nonsfi_unittests\": \"b5a9623d55a82b3be0956006fb11ca0e781404aa\", \"nacl_loader_unittests\": \"f84b75866dd873bc9f114ae851da5caed127f23a\", \"native_theme_unittests\": \"ffe8bb850c91b5094b1be949f2e71658dae5156d\", \"net_unittests\": \"19e8df88087788f0e960113ab649fda09cebcf28\", \"ozone_gl_unittests\": \"20b7f37daf981a96a49e3f6dd2f83a156f897fe6\", \"ozone_unittests\": \"8e551f67c7141ead46e24045738ea3bec2afc407\", \"ozone_x11_unittests\": \"ee445c7e2c5ebe8a3dc2a1d0660a4bf142b07bd1\", \"pdf_unittests\": \"cc4cd43d5a25c4a8938a53c5411ce3521e35ab5c\", \"perfetto_unittests\": \"f4bc0e35a952d81fe21003490d64c16f67512755\", \"ppapi_unittests\": \"c56a575dce43d6d4d4dc25fc7661fb49f935c30d\", \"printing_unittests\": \"eab21f418338a1c3fb9829d47734f67836467a5b\", \"remoting_unittests\": \"b974606cd8e7fb20345af4ce3a458c34ff0909ba\", \"sandbox_linux_unittests\": \"c22e116ef96cc1f01e4d1f32ecf4f5c12a30af30\", \"service_manager_unittests\": \"8768b47eab68423f7e8a17c5b0debab5830c31b4\", \"services_unittests\": \"84641af7a63ec9c28711e1b29a7a3e42320b8070\", \"shell_dialogs_unittests\": \"f1bebfce56d9d69a27f297706b483087a7599de1\", \"skia_unittests\": \"61c784a7c8aca56628a3fafc55c4ba1c544a1e94\", \"snapshot_unittests\": \"297511bc3c48d11107868c005712c4f99b5881dd\", \"sql_unittests\": \"10b2059f001a89cf93957e73f384aa4234a418a2\", \"storage_unittests\": \"4012d7da11c5185d4af29076837a1b2fdb72a908\", \"sync_integration_tests\": \"1a61d64c616ae64d0ff6094a50545a2fb0f67134\", \"traffic_annotation_auditor_unittests\": \"455ce3d01dc6860a62bed8efe7dd7d3f2c315626\", \"ui_base_unittests\": \"240dfe817b5ccaae5e7e61b71c82650a49b2d24b\", \"ui_chromeos_unittests\": \"983e1bd648dced397776fa8341a908d3b4da9344\", \"ui_touch_selection_unittests\": \"8b89a6229a554dd290d1cba88d12918a3c9a52f2\", \"unit_tests\": \"867acb5be1a529bbe1867e15f274f0c2b199da7a\", \"url_unittests\": \"104ef9b9cc1657a217a487288fa436a7f8d96b0f\", \"usage_time_limit_unittests\": \"0e820cf7ba4afb3cd9178eae7a5a658c0eda236d\", \"views_unittests\": \"4d41ffc257d5ec8ebc4464dec11ab53fec382b9e\", \"viz_unittests\": \"b3601c159828e180bbf81c30e6ee475201a9b705\", \"wayland_client_perftests\": \"d822ef98983d302e9cb4777e81e371c12e45844d\", \"wm_unittests\": \"98a3983f23d2a265792d7452fb9297bf48905953\", \"wtf_unittests\": \"77d48951084cd866bf571a49821ba30f07bea267\", \"zlib_unittests\": \"b70c0945aa2dc313df3b16efbe2732e6b2c42d6a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_510bb217f1fbd7cfc6f82b4f5538258f70582616cab4052bd10c7cd8994fe33a_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_d6e5378aee5938d080eb0dcdbd0211f3098981aa309245e37202498e30b4f23b_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-114-jw8n\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"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\": [\"5626-39642e9\"], \"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- HandsOffEnrollmentTest.NetworkConnectionReady\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604958314167063",
"status": "COMPLETED",
"status_changed_ts": "1604958314611601",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42728",
"builder:linux-chromeos-rel",
"buildset:commit/git/afa861742e30296ddf61d6b0677d90a85af98054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/afa861742e30296ddf61d6b0677d90a85af98054",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029503042932685072",
"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/8864072980931616048/+/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:4fc7c96bdd732710",
"user_agent:luci-scheduler"
],
"updated_ts": "1604961671703221",
"url": "https://ci.chromium.org/b/8864072980931616048",
"utcnow_ts": "1604961672309533"
}
}
[22:41:12.334] Invocation finished in 56m32.083385108s with status FAILED