[18:19:22.769] New invocation is queued and will start shortly
[18:19:23.864] Starting the invocation (attempt 1)
[18:19:23.905] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:19:23.905] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:19:23.905] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019187904640727776",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d847c096d82d1b41084ea35ff70959596753d4be\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019187904640727776",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/d847c096d82d1b41084ea35ff70959596753d4be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d847c096d82d1b41084ea35ff70959596753d4be",
"gitiles_ref:refs/heads/master"
]
}
[18:19:24.577] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614795563979967",
"id": "8853757842663283232",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d847c096d82d1b41084ea35ff70959596753d4be\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614795564439336",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45884",
"builder:linux-chromeos-rel",
"buildset:commit/git/d847c096d82d1b41084ea35ff70959596753d4be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d847c096d82d1b41084ea35ff70959596753d4be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019187904640727776",
"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/8853757842663283232/+/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": "1614795564439508",
"url": "https://ci.chromium.org/b/8853757842663283232",
"utcnow_ts": "1614795564571412"
}
}
[18:19:24.577] Task URL: https://ci.chromium.org/b/8853757842663283232
[18:19:24.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:2:0) after 1m0s
[18:19:26.635] Received PubSub notification, asking Buildbucket for the build status
[18:19:26.712] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:24.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:2:0)
[18:20:24.735] Timer tick, asking Buildbucket for the build status
[18:20:24.809] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:24.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:4:0) after 1m0s
[18:21:24.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:4:0)
[18:21:24.989] Timer tick, asking Buildbucket for the build status
[18:21:25.083] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:25.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:5:0) after 1m0s
[18:22:25.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:5:0)
[18:22:25.198] Timer tick, asking Buildbucket for the build status
[18:22:26.887] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:26.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:6:0) after 1m0s
[18:23:27.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:6:0)
[18:23:27.191] Timer tick, asking Buildbucket for the build status
[18:23:27.353] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:27.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:7:0) after 1m0s
[18:24:27.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:7:0)
[18:24:27.401] Timer tick, asking Buildbucket for the build status
[18:24:27.503] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:27.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:8:0) after 1m0s
[18:25:27.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:8:0)
[18:25:27.540] Timer tick, asking Buildbucket for the build status
[18:25:27.666] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:27.666] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:9:0) after 1m0s
[18:26:27.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:9:0)
[18:26:27.695] Timer tick, asking Buildbucket for the build status
[18:26:27.773] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:27.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:10:0) after 1m0s
[18:27:27.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:10:0)
[18:27:27.804] Timer tick, asking Buildbucket for the build status
[18:27:28.039] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:28.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:11:0) after 1m0s
[18:28:28.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:11:0)
[18:28:28.057] Timer tick, asking Buildbucket for the build status
[18:28:28.140] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:28.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:12:0) after 1m0s
[18:29:28.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:12:0)
[18:29:28.191] Timer tick, asking Buildbucket for the build status
[18:29:28.290] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:28.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:13:0) after 1m0s
[18:30:28.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:13:0)
[18:30:28.315] Timer tick, asking Buildbucket for the build status
[18:30:28.469] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:28.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:14:0) after 1m0s
[18:31:28.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:14:0)
[18:31:28.496] Timer tick, asking Buildbucket for the build status
[18:31:28.588] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:28.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:15:0) after 1m0s
[18:32:28.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:15:0)
[18:32:28.614] Timer tick, asking Buildbucket for the build status
[18:32:28.673] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:28.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:16:0) after 1m0s
[18:33:28.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:16:0)
[18:33:28.719] Timer tick, asking Buildbucket for the build status
[18:33:28.820] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:28.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:17:0) after 1m0s
[18:34:28.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:17:0)
[18:34:28.844] Timer tick, asking Buildbucket for the build status
[18:34:28.925] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:28.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:18:0) after 1m0s
[18:35:28.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:18:0)
[18:35:28.954] Timer tick, asking Buildbucket for the build status
[18:35:29.077] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:29.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:19:0) after 1m0s
[18:36:29.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:19:0)
[18:36:29.246] Timer tick, asking Buildbucket for the build status
[18:36:29.314] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:29.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:20:0) after 1m0s
[18:37:29.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:20:0)
[18:37:29.656] Timer tick, asking Buildbucket for the build status
[18:37:29.801] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:29.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:21:0) after 1m0s
[18:38:29.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:21:0)
[18:38:29.976] Timer tick, asking Buildbucket for the build status
[18:38:30.106] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:30.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:22:0) after 1m0s
[18:39:30.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:22:0)
[18:39:30.451] Timer tick, asking Buildbucket for the build status
[18:39:30.676] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:30.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:23:0) after 1m0s
[18:40:30.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:23:0)
[18:40:30.875] Timer tick, asking Buildbucket for the build status
[18:40:30.938] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:30.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:24:0) after 1m0s
[18:41:30.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:24:0)
[18:41:30.958] Timer tick, asking Buildbucket for the build status
[18:41:31.343] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:31.343] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:25:0) after 1m0s
[18:42:31.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:25:0)
[18:42:31.410] Timer tick, asking Buildbucket for the build status
[18:42:31.635] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:31.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:26:0) after 1m0s
[18:43:31.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:26:0)
[18:43:31.641] Timer tick, asking Buildbucket for the build status
[18:43:31.721] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:31.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:27:0) after 1m0s
[18:44:31.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:27:0)
[18:44:31.769] Timer tick, asking Buildbucket for the build status
[18:44:31.865] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:31.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:28:0) after 1m0s
[18:45:32.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:28:0)
[18:45:32.024] Timer tick, asking Buildbucket for the build status
[18:45:32.092] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:32.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:29:0) after 1m0s
[18:46:32.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:29:0)
[18:46:32.216] Timer tick, asking Buildbucket for the build status
[18:46:32.372] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:32.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:30:0) after 1m0s
[18:47:32.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:30:0)
[18:47:32.430] Timer tick, asking Buildbucket for the build status
[18:47:32.507] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:32.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:31:0) after 1m0s
[18:48:33.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:31:0)
[18:48:33.553] Timer tick, asking Buildbucket for the build status
[18:48:33.626] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:33.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:32:0) after 1m0s
[18:49:33.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:32:0)
[18:49:33.682] Timer tick, asking Buildbucket for the build status
[18:49:33.823] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:33.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:33:0) after 1m0s
[18:50:33.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:33:0)
[18:50:33.886] Timer tick, asking Buildbucket for the build status
[18:50:34.050] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:34.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:34:0) after 1m0s
[18:51:34.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:34:0)
[18:51:34.087] Timer tick, asking Buildbucket for the build status
[18:51:34.262] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:34.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:35:0) after 1m0s
[18:52:34.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:35:0)
[18:52:34.275] Timer tick, asking Buildbucket for the build status
[18:52:34.344] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:34.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:36:0) after 1m0s
[18:53:34.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:36:0)
[18:53:34.414] Timer tick, asking Buildbucket for the build status
[18:53:34.657] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:34.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:37:0) after 1m0s
[18:54:34.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:37:0)
[18:54:34.703] Timer tick, asking Buildbucket for the build status
[18:54:34.964] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:34.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:38:0) after 1m0s
[18:55:34.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:38:0)
[18:55:34.980] Timer tick, asking Buildbucket for the build status
[18:55:35.122] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:35.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:39:0) after 1m0s
[18:56:35.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:39:0)
[18:56:35.194] Timer tick, asking Buildbucket for the build status
[18:56:35.481] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:35.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:40:0) after 1m0s
[18:57:35.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:40:0)
[18:57:35.501] Timer tick, asking Buildbucket for the build status
[18:57:35.596] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:35.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:41:0) after 1m0s
[18:58:35.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:41:0)
[18:58:35.623] Timer tick, asking Buildbucket for the build status
[18:58:35.710] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:35.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:42:0) after 1m0s
[18:59:35.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:42:0)
[18:59:35.815] Timer tick, asking Buildbucket for the build status
[18:59:35.930] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:35.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:43:0) after 1m0s
[19:00:36.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:43:0)
[19:00:36.042] Timer tick, asking Buildbucket for the build status
[19:00:36.334] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:36.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:44:0) after 1m0s
[19:01:36.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:44:0)
[19:01:36.433] Timer tick, asking Buildbucket for the build status
[19:01:36.539] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:36.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:45:0) after 1m0s
[19:02:37.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:45:0)
[19:02:37.032] Timer tick, asking Buildbucket for the build status
[19:02:37.240] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:37.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:46:0) after 1m0s
[19:03:37.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:46:0)
[19:03:37.589] Timer tick, asking Buildbucket for the build status
[19:03:39.330] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:39.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:47:0) after 1m0s
[19:04:39.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:47:0)
[19:04:39.473] Timer tick, asking Buildbucket for the build status
[19:04:39.597] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:39.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:48:0) after 1m0s
[19:05:39.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:48:0)
[19:05:39.649] Timer tick, asking Buildbucket for the build status
[19:05:39.875] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:39.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:49:0) after 1m0s
[19:06:39.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:49:0)
[19:06:39.958] Timer tick, asking Buildbucket for the build status
[19:06:40.134] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:40.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:50:0) after 1m0s
[19:07:40.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:50:0)
[19:07:40.206] Timer tick, asking Buildbucket for the build status
[19:07:41.108] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:41.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:51:0) after 1m0s
[19:08:41.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:51:0)
[19:08:41.084] Timer tick, asking Buildbucket for the build status
[19:08:41.290] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:41.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:52:0) after 1m0s
[19:09:41.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:52:0)
[19:09:41.311] Timer tick, asking Buildbucket for the build status
[19:09:41.468] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:41.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:53:0) after 1m0s
[19:10:41.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:53:0)
[19:10:41.508] Timer tick, asking Buildbucket for the build status
[19:10:41.610] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:41.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:54:0) after 1m0s
[19:11:41.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:54:0)
[19:11:41.641] Timer tick, asking Buildbucket for the build status
[19:11:41.978] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:41.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:55:0) after 1m0s
[19:12:41.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:55:0)
[19:12:41.993] Timer tick, asking Buildbucket for the build status
[19:12:42.162] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:42.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:56:0) after 1m0s
[19:13:42.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:56:0)
[19:13:42.196] Timer tick, asking Buildbucket for the build status
[19:13:42.316] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:42.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:57:0) after 1m0s
[19:14:42.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:57:0)
[19:14:42.345] Timer tick, asking Buildbucket for the build status
[19:14:42.466] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:42.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:58:0) after 1m0s
[19:15:42.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:58:0)
[19:15:42.482] Timer tick, asking Buildbucket for the build status
[19:15:42.568] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:42.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:59:0) after 1m0s
[19:16:42.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:59:0)
[19:16:42.580] Timer tick, asking Buildbucket for the build status
[19:16:42.686] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:42.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:60:0) after 1m0s
[19:17:42.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:60:0)
[19:17:42.724] Timer tick, asking Buildbucket for the build status
[19:17:42.856] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:42.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:61:0) after 1m0s
[19:18:42.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:61:0)
[19:18:42.877] Timer tick, asking Buildbucket for the build status
[19:18:44.055] Build 8853757842663283232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:44.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019187904640727776:62:0) after 1m0s
[19:19:22.797] Received PubSub notification, asking Buildbucket for the build status
[19:19:22.947] Build 8853757842663283232: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:19:22.947] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614799162358918",
"created_by": "project:chromium",
"created_ts": "1614795563979967",
"failure_reason": "BUILD_FAILURE",
"id": "8853757842663283232",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d847c096d82d1b41084ea35ff70959596753d4be\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- CrSettingsSiteFaviconV3Test.All\\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\": \"d847c096d82d1b41084ea35ff70959596753d4be\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-177-ga26\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45884, \"got_angle_revision\": \"1de8b5a697e731c03083bda16b2df67c1d7a4c47\", \"got_dawn_revision\": \"9b5f442c78cb642b18bc4f18ab672f1b4426c934\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"d847c096d82d1b41084ea35ff70959596753d4be\", \"got_revision_cp\": \"refs/heads/master@{#859452}\", \"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\": \"d847c096d82d1b41084ea35ff70959596753d4be\", \"swarm_hashes_refs/heads/master(at){#859452}_without_patch\": {\"absl_hardening_tests\": \"74156c66f8c757fb089d38c358d40430370b6813\", \"accessibility_unittests\": \"deef87400eddb904ccd6e10cbbd1d94cd169a44e\", \"angle_unittests\": \"48e94072a88821c99532759e5df2d86d2c554b12\", \"app_list_unittests\": \"0333aea0d39d7bec2098455b1f8a5eee1770e6ad\", \"app_shell_unittests\": \"97f4fed793e6afecdf03bce4b001acd0885dcfea\", \"ash_unittests\": \"0ef389cefeb4a6f1c23a2da701e3c63634564fd8\", \"aura_unittests\": \"5db654b5363f3ee50c3b16877ca3d75ccd04e4dd\", \"base_unittests\": \"5677b0b4999b0e08224a307cfc541d5c8c626051\", \"base_util_unittests\": \"72228ae7e99e7cbdd9b1a156bd6bd2f59343004f\", \"blink_common_unittests\": \"46548b731dcacf0c854aaa4e7c0b2dcff027b597\", \"blink_fuzzer_unittests\": \"3a09b6fcd85bb8537b2559f30d25b3730c921529\", \"blink_heap_unittests\": \"3a94371861c67afcb1586df6e1067db0653eec69\", \"blink_platform_unittests\": \"56dc008b1e792b2fa89d15c3e4e1258172154db4\", \"blink_unittests\": \"e322dc985b603f23340a7bcef7d20f4792482da4\", \"boringssl_crypto_tests\": \"ccd71a8b2e0fd434c419aa7a3952433b680d17c0\", \"boringssl_ssl_tests\": \"60099910fde29901b34e0f235bceae1736615b8e\", \"browser_tests\": \"a39dac43d2d2a933199513011638efd3743f5b9f\", \"capture_unittests\": \"a4d5f637558ac159b8561f9652df04b012347960\", \"cast_unittests\": \"59ab8357110afa5995db4f25bf80098ac1a32fc2\", \"cc_unittests\": \"0df3ad44745eb15cdb311e346ac9c26f7bd31049\", \"chrome_app_unittests\": \"ac0fd0461178a2c5d06644340a9a51b8c0c72481\", \"chromedriver_unittests\": \"d057e155f202fd08ace991063b775f497b3113c8\", \"chromeos_components_unittests\": \"cb48229904ab2e9b52d62d8bff365b246b656e2c\", \"chromeos_unittests\": \"ff9e7cbee0764998baf89dbc3aa263b8c996d16c\", \"color_unittests\": \"20963644f07afcac7e9ccd51e477687f9b10c36f\", \"components_browsertests\": \"e5b62a7380cfddc27cf194153a0cd649758f7162\", \"components_unittests\": \"94a942ce54ab908c6fa2f30fba5f49475f8b624e\", \"compositor_unittests\": \"6c79fdf36f03bc5f22e02dc396fae62a7b791d08\", \"content_browsertests\": \"fb0d673c815ad59a16e43b141ef2050a939c63e8\", \"content_unittests\": \"767341c02bcf7a2ee9810de39903e15473881ec5\", \"crashpad_tests\": \"138d8e483698979282b5c6e6aaa763a8a242a2aa\", \"crypto_unittests\": \"3b906656f8fb75bc71875c21fe56373e2e6aa75b\", \"dbus_unittests\": \"c86cd32044b64610fe9f1a4a975ad0d7aef58423\", \"device_unittests\": \"3c488af14f7cc5c94d1529fd47db854023ed7985\", \"display_unittests\": \"be0b25359bc8d540b478f7a5b5ab2f4bf353b0cd\", \"events_unittests\": \"4204058ee9c270ddb100495bbd8a71cc4271c57e\", \"exo_unittests\": \"8aa6dba13e5ce50017663809b97cd6ff3dea5dc4\", \"extensions_browsertests\": \"1c8dac90dcfe6f549131e5ab9803d49d563acda9\", \"extensions_unittests\": \"0786aafe4c0b488f9f29b4430a9300a3697297bf\", \"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\": \"4a0f4e38990db069f4b2cb098d3022b18405a9c6\", \"ipc_tests\": \"7ecf3cd0af9a47741529e612be60d9dfabf6b785\", \"jingle_unittests\": \"655347160aa2a1a8dd40b3ab24f9e5d461bae1cc\", \"keyboard_unittests\": \"5595d123137b77f3f610a8b25c61456452039756\", \"latency_unittests\": \"a8999bac1d7f107db89e1bf21d746242741aeb92\", \"libjingle_xmpp_unittests\": \"e8cf040b8c8342650ac9597e251141fbe889f4a0\", \"liburlpattern_unittests\": \"a83548cd1725a0e111f6ac079a2b45bc1aed8bef\", \"media_blink_unittests\": \"fcad024a6c622973c04c04f4ef973bbf6ff22641\", \"media_unittests\": \"4b60a76988b1e0d4cfdd69527a1d3d0c90373dd9\", \"message_center_unittests\": \"96f091604a764b3e00ae5e1f64b772dff53c3f46\", \"midi_unittests\": \"2c3f8e2be7a13c0b7646e6d57f8d36523194e4b4\", \"mojo_core_unittests\": \"0cf6addcb006351ea0994dfb7b8caf6874598f66\", \"mojo_unittests\": \"783b0ce5abf9d79c7713a5d199169e570e799048\", \"nacl_helper_nonsfi_unittests\": \"2fa781e0d8e33b3b19a681794590a142f0e78774\", \"nacl_loader_unittests\": \"a6a861fd4364927f8f94b2fe652dee6c63300a67\", \"native_theme_unittests\": \"076de4594eb9e3719201fdb01c032020ba15e1e6\", \"net_unittests\": \"2c58c49cc52acfdb79b9b98ad3bbb657652d35bc\", \"ozone_gl_unittests\": \"0f3a2a77bf0a712eaeafde728ec33371ca982e06\", \"ozone_unittests\": \"6410b35f5b063683ca0168f61505bef3e80f2a50\", \"ozone_x11_unittests\": \"71111a42121242c30851565dd64b2cc402b953ca\", \"pdf_unittests\": \"40abe25202e76b71e98be32e7247e659eee27cbc\", \"perfetto_unittests\": \"d5b653e01329b24136b9e0122721ae1388ba9cf5\", \"ppapi_unittests\": \"56dc14330d622200aeb54ecb0c158ac02dc211e1\", \"printing_unittests\": \"62c63f88900a6059a364d2198ba2e2fe16ff0fdb\", \"remoting_unittests\": \"5d8c178bc4c0223753bb74f0eda94cff2b06b09a\", \"sandbox_linux_unittests\": \"2d8f8595f77f6f48199567a472fb4e8f4ff26c76\", \"service_manager_unittests\": \"8cdff59b8eb053365e6225febbf04e995be09c8b\", \"services_unittests\": \"c007ef2dd9eb252838b6f32d6af3cb9e3ec29ea0\", \"shell_dialogs_unittests\": \"46766ea193e0886a476746668b41e53529f83a69\", \"shell_encryption_unittests\": \"54a954d04c4093cec8655f1a66308bb34098459d\", \"skia_unittests\": \"dc4301023e4f59e6a503fd0858f871b533a78628\", \"snapshot_unittests\": \"793a6e1dd85f991fa2188d3484eb1d4052a94236\", \"sql_unittests\": \"268f7f889e84185a5b4d9e7a7064630bbb59d534\", \"storage_unittests\": \"6550d4ea9d6e7d2d39c9c9b79d66061921b510f6\", \"sync_integration_tests\": \"5220e913657e13e2d59ad9536785353140d01069\", \"traffic_annotation_auditor_unittests\": \"63dc975fd3685d54e84e5388a987ecb7451dcc90\", \"ui_base_unittests\": \"62128a8b4d98ea405678a41ee81fa1e332a4b7b9\", \"ui_chromeos_unittests\": \"5d8369d2d9f0b2d4bb5b8cbbffa1bb8f53eb1ac1\", \"ui_touch_selection_unittests\": \"eaed9c1a52cebf1f80ccedab2550e29a8efc66be\", \"unit_tests\": \"937d674187ee338386c4f3bbcd31a5b8ed55544d\", \"url_unittests\": \"67c5cfe3e06f9347d774eb05e136fab01f954dc4\", \"usage_time_limit_unittests\": \"552ee4a73ba38f8e9cb8efdbf94c65e26ac309a7\", \"views_unittests\": \"e80539820285185a53dc40a2ecc696f4c52731c0\", \"viz_unittests\": \"e962824b480624b5aba12a2ffbe38557141aacf3\", \"wayland_client_perftests\": \"0983d352daee20d61ecd67986e353e82b4b8a6a5\", \"wayland_client_tests\": \"7901fdab34b6efc62c6a5dcba2a47f3d6d0ee155\", \"wm_unittests\": \"c668281de9c27e5c2075d86c13d6a272819abc1a\", \"wtf_unittests\": \"fd55433c0eaa745c14aa6e8088efa1e185a51e6e\", \"zlib_unittests\": \"44d1e273942a1a1442bd05c826d668f410ce7648\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d5f41e0e2939f2447be43122175fb826239370d7da06e5b6b03ccaa65028999_v2\", \"builder_430de131691c92065dcde51cfe816220b2f886d2d859c4fe33bcc0b34c123c61_v2\", \"builder_8424eb90fa14b61ae49b4a2af2404de06c5c2a9a9345d31da54fda5995bf0e92_v2\", \"builder_8d2e09ca6cec324b1e6b903b111a43d36dfebcd565ea9813f9cc02a3286b21ea_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a7b9f8880f1f76f2cce14c79e9b35da9a713af6654c6d1393cfbcc356d1ede5d_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_ce300ca2149b7ede2a11dc5f29b170d3e5c2bdd2b201177604ba7c6bb221adeb_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-177-ga26\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- CrSettingsSiteFaviconV3Test.All\\n\\n- BackForwardCachePageLoadMetricsObserverBrowserTest.RequestAnimationFramesAfterBackForwardCacheRestore\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614795565933706",
"status": "COMPLETED",
"status_changed_ts": "1614795566465000",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45884",
"builder:linux-chromeos-rel",
"buildset:commit/git/d847c096d82d1b41084ea35ff70959596753d4be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d847c096d82d1b41084ea35ff70959596753d4be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019187904640727776",
"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/8853757842663283232/+/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:5212226ea93b6d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614799162359183",
"url": "https://ci.chromium.org/b/8853757842663283232",
"utcnow_ts": "1614799162938163"
}
}
[19:19:22.947] Invocation finished in 1h0m0.236896456s with status FAILED