[23:29:18.673] New invocation is queued and will start shortly
[23:29:19.776] Starting the invocation (attempt 1)
[23:29:19.820] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:29:19.820] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:29:19.820] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019621390207747328",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"163c8aba4d4b79a7b0c04bf6fb207d68a6716d28\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019621390207747328",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/163c8aba4d4b79a7b0c04bf6fb207d68a6716d28",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/163c8aba4d4b79a7b0c04bf6fb207d68a6716d28",
"gitiles_ref:refs/heads/master"
]
}
[23:29:20.604] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614382159908146",
"id": "8854191328250260656",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"163c8aba4d4b79a7b0c04bf6fb207d68a6716d28\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614382160477403",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45774",
"builder:linux-chromeos-rel",
"buildset:commit/git/163c8aba4d4b79a7b0c04bf6fb207d68a6716d28",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/163c8aba4d4b79a7b0c04bf6fb207d68a6716d28",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019621390207747328",
"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/8854191328250260656/+/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": "1614382160477511",
"url": "https://ci.chromium.org/b/8854191328250260656",
"utcnow_ts": "1614382160597339"
}
}
[23:29:20.604] Task URL: https://ci.chromium.org/b/8854191328250260656
[23:29:20.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:2:0) after 1m0s
[23:29:26.393] Received PubSub notification, asking Buildbucket for the build status
[23:29:26.479] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:20.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:2:0)
[23:30:20.633] Timer tick, asking Buildbucket for the build status
[23:30:20.704] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:20.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:4:0) after 1m0s
[23:31:20.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:4:0)
[23:31:20.775] Timer tick, asking Buildbucket for the build status
[23:31:21.052] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:21.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:5:0) after 1m0s
[23:32:21.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:5:0)
[23:32:21.100] Timer tick, asking Buildbucket for the build status
[23:32:21.168] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:21.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:6:0) after 1m0s
[23:33:21.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:6:0)
[23:33:21.175] Timer tick, asking Buildbucket for the build status
[23:33:21.257] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:21.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:7:0) after 1m0s
[23:34:21.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:7:0)
[23:34:21.280] Timer tick, asking Buildbucket for the build status
[23:34:21.345] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:21.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:8:0) after 1m0s
[23:35:21.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:8:0)
[23:35:21.360] Timer tick, asking Buildbucket for the build status
[23:35:21.445] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:21.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:9:0) after 1m0s
[23:36:21.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:9:0)
[23:36:21.485] Timer tick, asking Buildbucket for the build status
[23:36:21.652] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:21.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:10:0) after 1m0s
[23:37:21.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:10:0)
[23:37:21.673] Timer tick, asking Buildbucket for the build status
[23:37:21.744] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:21.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:11:0) after 1m0s
[23:38:21.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:11:0)
[23:38:21.781] Timer tick, asking Buildbucket for the build status
[23:38:21.840] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:21.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:12:0) after 1m0s
[23:39:21.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:12:0)
[23:39:21.900] Timer tick, asking Buildbucket for the build status
[23:39:22.132] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:22.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:13:0) after 1m0s
[23:40:22.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:13:0)
[23:40:22.183] Timer tick, asking Buildbucket for the build status
[23:40:22.322] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:22.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:14:0) after 1m0s
[23:41:22.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:14:0)
[23:41:22.374] Timer tick, asking Buildbucket for the build status
[23:41:22.480] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:22.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:15:0) after 1m0s
[23:42:22.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:15:0)
[23:42:22.482] Timer tick, asking Buildbucket for the build status
[23:42:22.719] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:22.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:16:0) after 1m0s
[23:43:22.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:16:0)
[23:43:22.699] Timer tick, asking Buildbucket for the build status
[23:43:22.897] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:22.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:17:0) after 1m0s
[23:44:22.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:17:0)
[23:44:22.946] Timer tick, asking Buildbucket for the build status
[23:44:23.093] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:23.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:18:0) after 1m0s
[23:45:23.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:18:0)
[23:45:23.129] Timer tick, asking Buildbucket for the build status
[23:45:23.213] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:23.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:19:0) after 1m0s
[23:46:23.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:19:0)
[23:46:23.303] Timer tick, asking Buildbucket for the build status
[23:46:23.443] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:23.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:20:0) after 1m0s
[23:47:23.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:20:0)
[23:47:23.502] Timer tick, asking Buildbucket for the build status
[23:47:23.563] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:23.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:21:0) after 1m0s
[23:48:23.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:21:0)
[23:48:23.657] Timer tick, asking Buildbucket for the build status
[23:48:24.431] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:24.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:22:0) after 1m0s
[23:49:24.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:22:0)
[23:49:24.522] Timer tick, asking Buildbucket for the build status
[23:49:24.644] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:24.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:23:0) after 1m0s
[23:50:24.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:23:0)
[23:50:24.806] Timer tick, asking Buildbucket for the build status
[23:50:24.881] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:24.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:24:0) after 1m0s
[23:51:24.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:24:0)
[23:51:24.884] Timer tick, asking Buildbucket for the build status
[23:51:24.956] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:24.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:25:0) after 1m0s
[23:52:24.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:25:0)
[23:52:24.950] Timer tick, asking Buildbucket for the build status
[23:52:25.049] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:25.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:26:0) after 1m0s
[23:53:25.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:26:0)
[23:53:25.134] Timer tick, asking Buildbucket for the build status
[23:53:25.597] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:25.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:27:0) after 1m0s
[23:54:25.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:27:0)
[23:54:25.697] Timer tick, asking Buildbucket for the build status
[23:54:26.033] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:26.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:28:0) after 1m0s
[23:55:26.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:28:0)
[23:55:26.073] Timer tick, asking Buildbucket for the build status
[23:55:26.150] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:26.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:29:0) after 1m0s
[23:56:26.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:29:0)
[23:56:26.203] Timer tick, asking Buildbucket for the build status
[23:56:26.273] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:26.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:30:0) after 1m0s
[23:57:26.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:30:0)
[23:57:26.295] Timer tick, asking Buildbucket for the build status
[23:57:26.365] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:26.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:31:0) after 1m0s
[23:58:26.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:31:0)
[23:58:26.413] Timer tick, asking Buildbucket for the build status
[23:58:26.557] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:26.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:32:0) after 1m0s
[23:59:26.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:32:0)
[23:59:26.583] Timer tick, asking Buildbucket for the build status
[23:59:26.789] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:26.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:33:0) after 1m0s
[00:00:26.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:33:0)
[00:00:26.794] Timer tick, asking Buildbucket for the build status
[00:00:26.875] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:26.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:34:0) after 1m0s
[00:01:26.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:34:0)
[00:01:26.918] Timer tick, asking Buildbucket for the build status
[00:01:26.989] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:26.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:35:0) after 1m0s
[00:02:27.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:35:0)
[00:02:27.081] Timer tick, asking Buildbucket for the build status
[00:02:27.538] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:27.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:36:0) after 1m0s
[00:03:27.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:36:0)
[00:03:27.730] Timer tick, asking Buildbucket for the build status
[00:03:27.802] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:27.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:37:0) after 1m0s
[00:04:27.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:37:0)
[00:04:27.940] Timer tick, asking Buildbucket for the build status
[00:04:28.037] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:28.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:38:0) after 1m0s
[00:05:28.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:38:0)
[00:05:28.184] Timer tick, asking Buildbucket for the build status
[00:05:28.245] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:28.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:39:0) after 1m0s
[00:06:28.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:39:0)
[00:06:28.471] Timer tick, asking Buildbucket for the build status
[00:06:28.539] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:28.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:40:0) after 1m0s
[00:07:28.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:40:0)
[00:07:28.607] Timer tick, asking Buildbucket for the build status
[00:07:28.693] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:28.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:41:0) after 1m0s
[00:08:28.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:41:0)
[00:08:28.804] Timer tick, asking Buildbucket for the build status
[00:08:28.885] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:28.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:42:0) after 1m0s
[00:09:28.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:42:0)
[00:09:28.933] Timer tick, asking Buildbucket for the build status
[00:09:29.030] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:29.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:43:0) after 1m0s
[00:10:29.060] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:43:0)
[00:10:29.060] Timer tick, asking Buildbucket for the build status
[00:10:29.135] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:29.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:44:0) after 1m0s
[00:11:29.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:44:0)
[00:11:29.179] Timer tick, asking Buildbucket for the build status
[00:11:29.251] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:29.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:45:0) after 1m0s
[00:12:29.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:45:0)
[00:12:29.327] Timer tick, asking Buildbucket for the build status
[00:12:29.411] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:29.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:46:0) after 1m0s
[00:13:29.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:46:0)
[00:13:29.447] Timer tick, asking Buildbucket for the build status
[00:13:29.554] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:29.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:47:0) after 1m0s
[00:14:29.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:47:0)
[00:14:29.588] Timer tick, asking Buildbucket for the build status
[00:14:29.695] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:29.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:48:0) after 1m0s
[00:15:29.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:48:0)
[00:15:29.758] Timer tick, asking Buildbucket for the build status
[00:15:29.832] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:29.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:49:0) after 1m0s
[00:16:30.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:49:0)
[00:16:30.156] Timer tick, asking Buildbucket for the build status
[00:16:30.245] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:30.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:50:0) after 1m0s
[00:17:30.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:50:0)
[00:17:30.305] Timer tick, asking Buildbucket for the build status
[00:17:30.581] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:30.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:51:0) after 1m0s
[00:18:30.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:51:0)
[00:18:30.716] Timer tick, asking Buildbucket for the build status
[00:18:30.809] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:30.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:52:0) after 1m0s
[00:19:30.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:52:0)
[00:19:30.896] Timer tick, asking Buildbucket for the build status
[00:19:31.010] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:31.010] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:53:0) after 1m0s
[00:20:31.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:53:0)
[00:20:31.067] Timer tick, asking Buildbucket for the build status
[00:20:31.153] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:31.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:54:0) after 1m0s
[00:21:31.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:54:0)
[00:21:31.178] Timer tick, asking Buildbucket for the build status
[00:21:31.306] Build 8854191328250260656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:31.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019621390207747328:55:0) after 1m0s
[00:22:28.267] Received PubSub notification, asking Buildbucket for the build status
[00:22:28.575] Build 8854191328250260656: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:22:28.575] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614385347694577",
"created_by": "project:chromium",
"created_ts": "1614382159908146",
"failure_reason": "BUILD_FAILURE",
"id": "8854191328250260656",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"163c8aba4d4b79a7b0c04bf6fb207d68a6716d28\"}}",
"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\": \"163c8aba4d4b79a7b0c04bf6fb207d68a6716d28\", \"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-66-e7rm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45774, \"got_angle_revision\": \"33907625dbcaab89bb7f62abea53f40eb9488754\", \"got_dawn_revision\": \"329c1c98fef964ea532a0579ae6f2553ff3382c5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"163c8aba4d4b79a7b0c04bf6fb207d68a6716d28\", \"got_revision_cp\": \"refs/heads/master@{#858334}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"45e415150419491d6d12428ba6ee98876a405ba4\", \"got_v8_revision_cp\": \"refs/heads/9.1.1@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"163c8aba4d4b79a7b0c04bf6fb207d68a6716d28\", \"swarm_hashes_refs/heads/master(at){#858334}_without_patch\": {\"absl_hardening_tests\": \"e162a385f8c31a5d3187aaed23ab379c42cb8c75\", \"accessibility_unittests\": \"b0b276f99c48f1cafa3ca5bbb199d9da06efb54e\", \"angle_unittests\": \"369e934281c5a53cd85ed87dec0a3600e1d31d04\", \"app_list_unittests\": \"b3c92dd2cdbed29362e21011189839dd85c80e89\", \"app_shell_unittests\": \"48d24f445511f664193a422137d14006cd1ec60c\", \"ash_unittests\": \"4962de441326fe0144e843b16b06ba6513f250ad\", \"aura_unittests\": \"7c2ae914bc7f7067562dbd9993013a0f94d998bc\", \"base_unittests\": \"335cd76566934b6a24a564cbd46c798df1d49262\", \"base_util_unittests\": \"1d2aa54260a1881f6b93a9b23e596c7e69c49ec0\", \"blink_common_unittests\": \"2429588f5b612325240be1993ef592461ca71e8e\", \"blink_fuzzer_unittests\": \"66ce5cb3ff5c434231c9f5179358eadba6a891d7\", \"blink_heap_unittests\": \"0588fcfaf0ddd5f152df9afece72c7ab8a418f6f\", \"blink_platform_unittests\": \"82828b52dff6d53997f0bd3c08df52718b6a5bfa\", \"blink_unittests\": \"cd470936b1b8c573a39403b4049fa0ed87247a9e\", \"boringssl_crypto_tests\": \"3b783678d79cfaa2d3ce45802abb67fa840ce277\", \"boringssl_ssl_tests\": \"5028d1d1c12feea9050653d143edb5a53502eba9\", \"browser_tests\": \"b44fabafc316c85404650eb78506dd107c004294\", \"capture_unittests\": \"d3ff9c8a92ee95c38c55cdd54b2556c2b0b4b044\", \"cast_unittests\": \"abf9549e9298b14e7656e9e6a3b950dae318141d\", \"cc_unittests\": \"1853ba22efa4fff5c688612bd4951ab6e0575457\", \"chrome_app_unittests\": \"314ebba143cb882595b604ef226748a39cf9960f\", \"chromedriver_unittests\": \"e284673c7b4e645e89315c40e1cf05ffda4d4b98\", \"chromeos_components_unittests\": \"80f5ff8abb87bd2d8caa6277480d820b72947eeb\", \"chromeos_unittests\": \"5d0fa7543533d834676f5abe0b4f9b887e5b3472\", \"color_unittests\": \"4a7db5da4d05ccab254ddef7f528c6dfd61a37c4\", \"components_browsertests\": \"e65bf465ad87d6dbf3630a1411ec57b0c42f8c1b\", \"components_unittests\": \"92b98533f8baf60a44ff0f64dd9ec19533e5daa6\", \"compositor_unittests\": \"8e195d4e2bfa97a64117cf67945752a70d2a47c4\", \"content_browsertests\": \"27dc48b4478380b1483dc4e509e9598fb8a72c38\", \"content_unittests\": \"f04c1c989d0ab42c258e1a3b0b08f453c7cb90de\", \"crashpad_tests\": \"5c5f162391d4ccba7a6cd4c22d65ddd892883be1\", \"crypto_unittests\": \"80458af84d0d17ec69b6dd72778e8f33723f3473\", \"dbus_unittests\": \"b294f7f5a49c1cce817286f9ec325c223b943fb2\", \"device_unittests\": \"3e67dac5886c0375ae013056f91cccc65df51468\", \"display_unittests\": \"216243be9c04edbfa875f0f9758d31a2b64fb5f9\", \"events_unittests\": \"58d8ea39b793bfcf2cd9d280d774600a8989721c\", \"exo_unittests\": \"31eec6062f1bc1939d2af16467a335a1719d4eee\", \"extensions_browsertests\": \"4e5c07b245b7c97f1e268bfc7f04f0269f78ac77\", \"extensions_unittests\": \"91d7e5ee041dbb264700cc0e4a96ad9a4fb7bcf4\", \"filesystem_service_unittests\": \"66bb43fa54828bc39c1d473ea280622e98cb543a\", \"gcm_unit_tests\": \"ff1720afc6f6225d6afb8cc4a83db45d14b09672\", \"gfx_unittests\": \"7ffa3e98bc307a998434a6b696e48ea63a08fe24\", \"gin_unittests\": \"aed80ec426720997a30f94594793c8650f30c7d5\", \"gl_unittests_ozone\": \"8ac2481440b8c3dd6883473ef86e4f36fe909773\", \"google_apis_unittests\": \"8d081660c81fe0ca60976f48fd6f3ece82995845\", \"gpu_unittests\": \"9ded3d4602ee610ad2eb414912e9fc08b89a0be0\", \"gwp_asan_unittests\": \"c62788fbaf0fe94a83f22f7a492aac706f95c1fe\", \"interactive_ui_tests\": \"34909d1f92f5b0fcb32da5b1096d53f4a7e41e6e\", \"ipc_tests\": \"d2ba985ae6dc9f074538e70dd2830044b12d0a50\", \"jingle_unittests\": \"a128d30e7d4259ef1d3391d624d4224072429b06\", \"keyboard_unittests\": \"a0a28ed028cc92577cbb9b378f41ab997e303265\", \"latency_unittests\": \"f2328496dbf5c571a281890075d2969054631076\", \"libjingle_xmpp_unittests\": \"6641e5d3ed687383d669110dd102ce911f3a561b\", \"liburlpattern_unittests\": \"7bb117328646d29c283ab318ca78f27864e65fc9\", \"media_blink_unittests\": \"226e5d14a3164a4592543e705768dfeec35f875a\", \"media_unittests\": \"88aed33839b4f91807e9465ac5df30162c94502f\", \"message_center_unittests\": \"67ff6b7417b17936f5336b2239bd144e675e392b\", \"midi_unittests\": \"2476bc65e851430b313445b63a1c60845f6c2fe0\", \"mojo_core_unittests\": \"2694dace6423c221af1d92fe994ab6c01a6b098a\", \"mojo_unittests\": \"08031d163e374a1a4f45e12daaca5abfcec0a9f9\", \"nacl_helper_nonsfi_unittests\": \"576857fbaef322fddede5d3db4e76c7fa708bdee\", \"nacl_loader_unittests\": \"ca8c1f61b3181935ab249b6a2b77b7d4f4529571\", \"native_theme_unittests\": \"b12facd1013f4d6d6c0d01d824c9e68592d92e2b\", \"net_unittests\": \"a0c19c87c1919dcdb5536fe81bec7f7765cc1887\", \"ozone_gl_unittests\": \"8d569b8d3f5bf502c954098d61ba3416457b03c2\", \"ozone_unittests\": \"f2eee1e50fa01a5cb13e4bb3975b5b51170f590d\", \"ozone_x11_unittests\": \"2a22c9aad0817f6d2fe07b149c59d68ac68753b4\", \"pdf_unittests\": \"b5ade35dda4d0492367df86ea7ede53a220e092a\", \"perfetto_unittests\": \"e2a4f4abcd0426087a37f7d42270d0ee733cd58c\", \"ppapi_unittests\": \"6c425c6873033c414713496942222d2c2e832ab7\", \"printing_unittests\": \"6825a7272dd9fea563d81f025c5c5f85a85c75f3\", \"remoting_unittests\": \"550f209b4454868737e38600ad4a0c6ce8a1fa65\", \"sandbox_linux_unittests\": \"536b84b84929e42f6b328ddaeaa8d483abb8b538\", \"service_manager_unittests\": \"ec444cbfc30a53102cae0674a40181749a78fee8\", \"services_unittests\": \"3babcfbda3a0bc885d2f57a4c42fbe95179d7b59\", \"shell_dialogs_unittests\": \"58f17a341d6866079b88ab80ef2856132d4dc083\", \"shell_encryption_unittests\": \"49a421e1a9e9e1e573b427930a8776c8e6409884\", \"skia_unittests\": \"9fa48325ef906bc4b31b82416ac9c891494a79de\", \"snapshot_unittests\": \"49b6e2707c683babef4aaf5bfc792b799b32dd88\", \"sql_unittests\": \"6af783ad0dd5711bf501f72e0426c65d58337ffb\", \"storage_unittests\": \"7ec36820edd5e2c47d02e4ef6e213ea978a76173\", \"sync_integration_tests\": \"35dd92af1d15ca61f9e764edf9b5814c51c4b071\", \"traffic_annotation_auditor_unittests\": \"4bf374bda7a46bf46ee27629b75f6c07cbe6ffaa\", \"ui_base_unittests\": \"e58c5c17dd83dc50ac49ce55b288b629331bcb25\", \"ui_chromeos_unittests\": \"10088745d2cadfdf65f19e5e8aa735b3b6e077e4\", \"ui_touch_selection_unittests\": \"f16f17fc03e662fef8a16310825f3304e1101ea4\", \"unit_tests\": \"d306792e2d263c40fc06cc958bc6fbb94df874eb\", \"url_unittests\": \"9be4b340023e52658a549066dbd976958cbfd257\", \"usage_time_limit_unittests\": \"deab27face168dc74adda31882dbd12fb1b0d665\", \"views_unittests\": \"3c315d5981cab045b188791b27037237163f1c84\", \"viz_unittests\": \"b23202a6b2051e4e8009ba0b6d8ac7b641d0eec2\", \"wayland_client_perftests\": \"91b55a78be24a0504d1126577120e93655709044\", \"wayland_client_tests\": \"ddab6cf9e96accb80bd7f2a34132392dc22d304e\", \"wm_unittests\": \"55f1d9edd0ada31675a310e1e083f47616fc4775\", \"wtf_unittests\": \"fb5b8b2678c06bbdf8c0ea5fdd8dfc8dc1db673a\", \"zlib_unittests\": \"c9db475c4108c11e6b8bf889251c45c2160ea853\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_288b090d6274f2a2f913a0241f3e1a83c28ff98db6eccb4d2a91107c37eb954f_v2\", \"builder_28be8b4e850be8bd7a9db0671ff876681ccdf0c2c048860e172c20e0c8b9b79b_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_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-66-e7rm\"], \"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\": [\"5712-339372b\"], \"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": "1614382162005321",
"status": "COMPLETED",
"status_changed_ts": "1614382162289901",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45774",
"builder:linux-chromeos-rel",
"buildset:commit/git/163c8aba4d4b79a7b0c04bf6fb207d68a6716d28",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/163c8aba4d4b79a7b0c04bf6fb207d68a6716d28",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019621390207747328",
"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/8854191328250260656/+/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:51f97e6435722110",
"user_agent:luci-scheduler"
],
"updated_ts": "1614385347694906",
"url": "https://ci.chromium.org/b/8854191328250260656",
"utcnow_ts": "1614385348558838"
}
}
[00:22:28.575] Invocation finished in 53m9.916934117s with status FAILED