[08:58:25.434] New invocation is queued and will start shortly
[08:58:26.667] Starting the invocation (attempt 1)
[08:58:26.717] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:58:26.718] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:58:26.718] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026199163281342304",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5d463cbd6f70500ea585f8837a954f10f03b7ffa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9026199163281342304",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5d463cbd6f70500ea585f8837a954f10f03b7ffa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d463cbd6f70500ea585f8837a954f10f03b7ffa",
"gitiles_ref:refs/heads/master"
]
}
[08:58:27.679] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608109106955559",
"id": "8860769101023857344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5d463cbd6f70500ea585f8837a954f10f03b7ffa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608109107546805",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43767",
"builder:linux-chromeos-rel",
"buildset:commit/git/5d463cbd6f70500ea585f8837a954f10f03b7ffa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d463cbd6f70500ea585f8837a954f10f03b7ffa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026199163281342304",
"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/8860769101023857344/+/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": "1608109107546965",
"url": "https://ci.chromium.org/b/8860769101023857344",
"utcnow_ts": "1608109107670802"
}
}
[08:58:27.679] Task URL: https://ci.chromium.org/b/8860769101023857344
[08:58:27.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:2:0) after 1m0s
[08:58:56.101] Received PubSub notification, asking Buildbucket for the build status
[08:58:56.200] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:28.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:2:0)
[08:59:28.413] Timer tick, asking Buildbucket for the build status
[08:59:28.723] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:28.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:4:0) after 1m0s
[09:00:28.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:4:0)
[09:00:28.981] Timer tick, asking Buildbucket for the build status
[09:00:31.548] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:31.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:5:0) after 1m0s
[09:01:32.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:5:0)
[09:01:32.023] Timer tick, asking Buildbucket for the build status
[09:01:32.319] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:32.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:6:0) after 1m0s
[09:02:32.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:6:0)
[09:02:32.448] Timer tick, asking Buildbucket for the build status
[09:02:32.732] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:32.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:7:0) after 1m0s
[09:03:32.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:7:0)
[09:03:32.821] Timer tick, asking Buildbucket for the build status
[09:03:32.926] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:32.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:8:0) after 1m0s
[09:04:33.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:8:0)
[09:04:33.121] Timer tick, asking Buildbucket for the build status
[09:04:33.241] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:33.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:9:0) after 1m0s
[09:05:33.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:9:0)
[09:05:33.344] Timer tick, asking Buildbucket for the build status
[09:05:33.442] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:33.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:10:0) after 1m0s
[09:06:35.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:10:0)
[09:06:35.161] Timer tick, asking Buildbucket for the build status
[09:06:36.330] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:36.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:11:0) after 1m0s
[09:07:36.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:11:0)
[09:07:36.351] Timer tick, asking Buildbucket for the build status
[09:07:36.443] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:36.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:12:0) after 1m0s
[09:08:36.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:12:0)
[09:08:36.465] Timer tick, asking Buildbucket for the build status
[09:08:36.546] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:36.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:13:0) after 1m0s
[09:09:36.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:13:0)
[09:09:36.568] Timer tick, asking Buildbucket for the build status
[09:09:38.040] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:38.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:14:0) after 1m0s
[09:10:38.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:14:0)
[09:10:38.113] Timer tick, asking Buildbucket for the build status
[09:10:40.764] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:40.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:15:0) after 1m0s
[09:11:40.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:15:0)
[09:11:40.874] Timer tick, asking Buildbucket for the build status
[09:11:41.126] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:41.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:16:0) after 1m0s
[09:12:41.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:16:0)
[09:12:41.322] Timer tick, asking Buildbucket for the build status
[09:12:41.384] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:41.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:17:0) after 1m0s
[09:13:41.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:17:0)
[09:13:41.425] Timer tick, asking Buildbucket for the build status
[09:13:41.516] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:41.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:18:0) after 1m0s
[09:14:41.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:18:0)
[09:14:41.706] Timer tick, asking Buildbucket for the build status
[09:14:41.807] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:41.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:19:0) after 1m0s
[09:15:41.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:19:0)
[09:15:41.883] Timer tick, asking Buildbucket for the build status
[09:15:41.962] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:41.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:20:0) after 1m0s
[09:16:42.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:20:0)
[09:16:42.120] Timer tick, asking Buildbucket for the build status
[09:16:42.185] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:42.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:21:0) after 1m0s
[09:17:42.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:21:0)
[09:17:42.211] Timer tick, asking Buildbucket for the build status
[09:17:42.277] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:42.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:22:0) after 1m0s
[09:18:42.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:22:0)
[09:18:42.324] Timer tick, asking Buildbucket for the build status
[09:18:42.396] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:42.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:23:0) after 1m0s
[09:19:42.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:23:0)
[09:19:42.454] Timer tick, asking Buildbucket for the build status
[09:19:42.578] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:42.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:24:0) after 1m0s
[09:20:42.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:24:0)
[09:20:42.621] Timer tick, asking Buildbucket for the build status
[09:20:42.688] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:42.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:25:0) after 1m0s
[09:21:42.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:25:0)
[09:21:42.941] Timer tick, asking Buildbucket for the build status
[09:21:42.998] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:42.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:26:0) after 1m0s
[09:22:43.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:26:0)
[09:22:43.042] Timer tick, asking Buildbucket for the build status
[09:22:43.103] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:43.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:27:0) after 1m0s
[09:23:43.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:27:0)
[09:23:43.253] Timer tick, asking Buildbucket for the build status
[09:23:43.348] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:43.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:28:0) after 1m0s
[09:24:43.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:28:0)
[09:24:43.368] Timer tick, asking Buildbucket for the build status
[09:24:43.461] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:43.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:29:0) after 1m0s
[09:25:43.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:29:0)
[09:25:43.552] Timer tick, asking Buildbucket for the build status
[09:25:43.612] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:43.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:30:0) after 1m0s
[09:26:44.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:30:0)
[09:26:44.211] Timer tick, asking Buildbucket for the build status
[09:26:44.486] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:44.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:31:0) after 1m0s
[09:27:44.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:31:0)
[09:27:44.573] Timer tick, asking Buildbucket for the build status
[09:27:44.643] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:44.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:32:0) after 1m0s
[09:28:44.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:32:0)
[09:28:44.731] Timer tick, asking Buildbucket for the build status
[09:28:44.792] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:44.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:33:0) after 1m0s
[09:29:44.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:33:0)
[09:29:44.896] Timer tick, asking Buildbucket for the build status
[09:29:45.062] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:45.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:34:0) after 1m0s
[09:30:45.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:34:0)
[09:30:45.576] Timer tick, asking Buildbucket for the build status
[09:30:45.633] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:45.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:35:0) after 1m0s
[09:31:46.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:35:0)
[09:31:46.038] Timer tick, asking Buildbucket for the build status
[09:31:47.278] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:47.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:36:0) after 1m0s
[09:32:47.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:36:0)
[09:32:47.744] Timer tick, asking Buildbucket for the build status
[09:32:47.805] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:47.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:37:0) after 1m0s
[09:33:47.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:37:0)
[09:33:47.858] Timer tick, asking Buildbucket for the build status
[09:33:47.937] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:47.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:38:0) after 1m0s
[09:34:48.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:38:0)
[09:34:48.045] Timer tick, asking Buildbucket for the build status
[09:34:48.152] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:48.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:39:0) after 1m0s
[09:35:48.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:39:0)
[09:35:48.180] Timer tick, asking Buildbucket for the build status
[09:35:48.250] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:48.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:40:0) after 1m0s
[09:36:48.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:40:0)
[09:36:48.228] Timer tick, asking Buildbucket for the build status
[09:36:48.421] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:48.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:41:0) after 1m0s
[09:37:48.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:41:0)
[09:37:48.434] Timer tick, asking Buildbucket for the build status
[09:37:48.543] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:48.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:42:0) after 1m0s
[09:38:48.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:42:0)
[09:38:48.618] Timer tick, asking Buildbucket for the build status
[09:38:48.677] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:48.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:43:0) after 1m0s
[09:39:48.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:43:0)
[09:39:48.715] Timer tick, asking Buildbucket for the build status
[09:39:48.912] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:48.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:44:0) after 1m0s
[09:40:48.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:44:0)
[09:40:48.974] Timer tick, asking Buildbucket for the build status
[09:40:49.210] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:49.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:45:0) after 1m0s
[09:41:49.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:45:0)
[09:41:49.222] Timer tick, asking Buildbucket for the build status
[09:41:49.599] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:49.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:46:0) after 1m0s
[09:42:49.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:46:0)
[09:42:49.656] Timer tick, asking Buildbucket for the build status
[09:42:49.936] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:49.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:47:0) after 1m0s
[09:43:50.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:47:0)
[09:43:50.129] Timer tick, asking Buildbucket for the build status
[09:43:50.255] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:50.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:48:0) after 1m0s
[09:44:50.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:48:0)
[09:44:50.366] Timer tick, asking Buildbucket for the build status
[09:44:50.513] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:50.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:49:0) after 1m0s
[09:45:50.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:49:0)
[09:45:50.555] Timer tick, asking Buildbucket for the build status
[09:45:50.679] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:50.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:50:0) after 1m0s
[09:46:50.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:50:0)
[09:46:50.698] Timer tick, asking Buildbucket for the build status
[09:46:50.812] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:50.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:51:0) after 1m0s
[09:47:50.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:51:0)
[09:47:50.874] Timer tick, asking Buildbucket for the build status
[09:47:50.983] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:50.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:52:0) after 1m0s
[09:48:51.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:52:0)
[09:48:51.007] Timer tick, asking Buildbucket for the build status
[09:48:51.126] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:51.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:53:0) after 1m0s
[09:49:51.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:53:0)
[09:49:51.149] Timer tick, asking Buildbucket for the build status
[09:49:51.267] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:51.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:54:0) after 1m0s
[09:50:51.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:54:0)
[09:50:51.323] Timer tick, asking Buildbucket for the build status
[09:50:52.226] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:52.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:55:0) after 1m0s
[09:51:52.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:55:0)
[09:51:52.273] Timer tick, asking Buildbucket for the build status
[09:51:52.393] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:52.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:56:0) after 1m0s
[09:52:52.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:56:0)
[09:52:52.434] Timer tick, asking Buildbucket for the build status
[09:52:52.562] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:52.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:57:0) after 1m0s
[09:53:52.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:57:0)
[09:53:52.582] Timer tick, asking Buildbucket for the build status
[09:53:53.073] Build 8860769101023857344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:53.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026199163281342304:58:0) after 1m0s
[09:54:04.152] Received PubSub notification, asking Buildbucket for the build status
[09:54:04.311] Build 8860769101023857344: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:54:04.311] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608112443325003",
"created_by": "project:chromium",
"created_ts": "1608109106955559",
"failure_reason": "BUILD_FAILURE",
"id": "8860769101023857344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5d463cbd6f70500ea585f8837a954f10f03b7ffa\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**compositor_unittests** failed because of:\\n\\n- TotalAnimationThroughputReporterTest.StartWhileAnimating\"}, \"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\": \"5d463cbd6f70500ea585f8837a954f10f03b7ffa\", \"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-48-1qrt\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43767, \"got_angle_revision\": \"328f72ae21cbc371431c3776718b3a2ebfbb79ca\", \"got_dawn_revision\": \"fea4c14580b11322b8ab0ced991dd42141ab4e89\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"5d463cbd6f70500ea585f8837a954f10f03b7ffa\", \"got_revision_cp\": \"refs/heads/master@{#837493}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"24e738a6bc98c6971154c655e17f656297085c45\", \"got_v8_revision_cp\": \"refs/heads/8.9.157@{#1}\", \"got_webrtc_revision\": \"cf8ec1f6867651204d81b14a8c31a86e6eb97572\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32829}\", \"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\": \"5d463cbd6f70500ea585f8837a954f10f03b7ffa\", \"swarm_hashes_refs/heads/master(at){#837493}_without_patch\": {\"absl_hardening_tests\": \"9a38194f843a462f064fba3f992d6dadaa4a258c\", \"accessibility_unittests\": \"2fe0e7a485a5eadc31832f36790a372a49fcaa97\", \"angle_unittests\": \"756fe0e1568963602927ab11313d06ceaa4e173a\", \"app_list_unittests\": \"3d38a4672aa3a62862a7a4521e1f6d8dc482661a\", \"app_shell_unittests\": \"0416f40c1c70458c5bc5db454981086b3bbc347e\", \"ash_unittests\": \"8e6a3556d060feb339742d8f2a3320bb2b00ef18\", \"aura_unittests\": \"9c4f192b3f70e65f87132f4ecc7a23d0d9edfe62\", \"base_unittests\": \"c87466fde17be449b8726d25c883727a839f4123\", \"base_util_unittests\": \"c93c12a2b88effc3d34f5bffc4aed7e7d6d092e7\", \"blink_common_unittests\": \"0bf6b682186ec15848fbcdd8105fb96cbccca9af\", \"blink_fuzzer_unittests\": \"e7deebfb187328fa9d6f913186510bb4e2aa558d\", \"blink_heap_unittests\": \"e6c269a0f1886fb60851751eb798cade3c71cd2b\", \"blink_platform_unittests\": \"016ea62ef320b0c43ccfbc1968948ecaa970bd6f\", \"blink_unittests\": \"6f05e6080a7505fc0e7cbfda2ae3887d4e4e81d9\", \"boringssl_crypto_tests\": \"fd81e908f1972eea5b3d863fe52a3e9578eb2660\", \"boringssl_ssl_tests\": \"16e1fd8ca7be400213dc36c93e20bece468d849a\", \"browser_tests\": \"f6d73720af3d5915285902723c4f6ad7d0297527\", \"capture_unittests\": \"dabe31e979498b566fabf0d97cad974773989ca4\", \"cast_unittests\": \"9e572d1e2e54d2b18d4d4915dfed780c93edefa5\", \"cc_unittests\": \"a6b3b1f8feca17216ad70763526f5044d53c1922\", \"chrome_app_unittests\": \"3ec6fd2430845c6015e034db81855f650389b221\", \"chromedriver_unittests\": \"cc136e0811e8eccdbfa22d1b432bbe5b507a9bf1\", \"chromeos_components_unittests\": \"4b431d5e7f144404e2a30b64742634ceff494d91\", \"chromeos_unittests\": \"23b1f3f4bdff74cdfb2985e6e132b8640a2ae835\", \"components_browsertests\": \"3d2f6c9e8a625b4166b84c2e8bcafd25b037b5af\", \"components_unittests\": \"ee8674378cce517742c4d7c26fd356d77dd45077\", \"compositor_unittests\": \"d62716a85870ba5534882bb3a1f8cdc6a88efeab\", \"content_browsertests\": \"9ab45054e9084fb575a8c3ef960a725195ec6ba1\", \"content_unittests\": \"ccdef94bd8da98a1564a6d1019bd77a599041784\", \"crashpad_tests\": \"f7924d280b55c314ec962effdfcc47c212c0c37f\", \"crypto_unittests\": \"992092f94c25fed66c339ff4087c3024e45c38b9\", \"dbus_unittests\": \"b9fdf074db4d796805da52a027314017808f5534\", \"device_unittests\": \"e6d24a655dcae02cc8315fd62a7fa0eec6449a23\", \"display_unittests\": \"918c71c66080ba6027ddf2a8f0edc8a716e6738b\", \"events_unittests\": \"f09b79c7105933853ec95fadfe0c039d8711328f\", \"exo_unittests\": \"ff3c58bbcffb93a800c3737e82c1e7654353849d\", \"extensions_browsertests\": \"afb92755a7b57946a7b658f64216c8fb8bfcf867\", \"extensions_unittests\": \"f21dba8f9c66064acfdc9aeb135d09a82cc1af31\", \"filesystem_service_unittests\": \"942f8850ae0e2d9abd255c854c2d0ad49f51d7c6\", \"gcm_unit_tests\": \"df1862fa187fbac0d65ec228194d45fd99917ccf\", \"gfx_unittests\": \"f4db940e8e619dcda2c7161a79b469a80644f93d\", \"gin_unittests\": \"d9e8919edd4f9ebb1a26158ea92389c520ee00e7\", \"gl_unittests_ozone\": \"8ddadf46fca23b34a3fdb950dba736d9d8ea0308\", \"google_apis_unittests\": \"20a0600b4afe75929bdc5d3284dd2e32118d1e82\", \"gpu_unittests\": \"cbf9a114f4d9ca2bbab089ea5644bfc420cdfdf0\", \"gwp_asan_unittests\": \"73ac960df4eb67f38fd9d35f46e5cad932ea8106\", \"interactive_ui_tests\": \"fb01dcdcef9fed17b6bddbb80eccd467382e27ca\", \"ipc_tests\": \"82fe3ee718096c3567148febe4ab5a9e961b4fa3\", \"jingle_unittests\": \"69be856b7c5c08fe41c00fd1c4a04b04331a5718\", \"keyboard_unittests\": \"9658cd566ca75c266d80d60da1bf70b081b94ece\", \"latency_unittests\": \"9db39ec051168bd5b83b7659be0b9d7c95c4592b\", \"libjingle_xmpp_unittests\": \"cde85612b3d2453f00389d06f3c2dd96cdce5437\", \"liburlpattern_unittests\": \"ff784c86cfcffd161cdfebf0f2618034eebdb05e\", \"media_blink_unittests\": \"598f34db617fe111980cb108496c960745ecebd6\", \"media_unittests\": \"79d67bd8470387d97a000df5b416a9182c1c9836\", \"message_center_unittests\": \"c6f860b34b9cf66d57c2e0568cd258ec900f6a54\", \"midi_unittests\": \"57f6db351dd070d1008eb59b3d10029bf3d97e5f\", \"mojo_core_unittests\": \"d53c5b6fa648a921e6d12e028976e9819da826d8\", \"mojo_unittests\": \"c6ae4d97e4601853f5219a744cf7b865a0a0f509\", \"nacl_helper_nonsfi_unittests\": \"a51a1f07650764e42850f593b60c30cfe6298f58\", \"nacl_loader_unittests\": \"61b3969fa3efe00caf239079f354ba54f9c77b5a\", \"native_theme_unittests\": \"d7c738699f07329f9078bf8afebbf4aaa8142648\", \"net_unittests\": \"7794c78a269bcf82c5c013b5e5da159bc4f2590e\", \"ozone_gl_unittests\": \"0d10ae47619029e32dc00c070c4a31f158b515e5\", \"ozone_unittests\": \"358f3c91eebf81980c451a58b1ced4a3507ac3c1\", \"ozone_x11_unittests\": \"42bce9c6ba3f985d3854d4c161d80f0387289759\", \"pdf_unittests\": \"99287ddf90dee210191541f7a75243d0d043e7ca\", \"perfetto_unittests\": \"959aca953134f6a52a5ff0d2d0de45e7630b0482\", \"ppapi_unittests\": \"6cea6cd2f3b87a39e7edc727680966a9a3fe3fae\", \"printing_unittests\": \"6dabe163096a0f20b17d3d428666111167f7e7e6\", \"remoting_unittests\": \"746a6320744d6cd82b8b0806ed78768137b4e50d\", \"sandbox_linux_unittests\": \"538b4f35b7674e00be2a1a6a0b4c028fbf6b510b\", \"service_manager_unittests\": \"c9a0af58950498dff95215e1d470fb935811fac1\", \"services_unittests\": \"57883d9cb8df020ad3c428ee9585ffb86213cbd9\", \"shell_dialogs_unittests\": \"f9cc54b09dd0679fe6f8bc1a833564cf37737165\", \"shell_encryption_unittests\": \"4efed8b4086f9afd5bf538411c320e73eb414d84\", \"skia_unittests\": \"67f92a57adc72e14253961bda2fd1812670755b5\", \"snapshot_unittests\": \"69b1708a7e159998c405c74a2cc7350c7462c0c1\", \"sql_unittests\": \"9cfbe1b741646161dea458d4bdf8c61ea224a426\", \"storage_unittests\": \"1f09fb5b086958b10934ddd91bee3470d70de1bc\", \"sync_integration_tests\": \"f01917ffe7e4e4e27ea303a5cb87b942f74f1dd6\", \"traffic_annotation_auditor_unittests\": \"df253aedf5653f918758a0a14078e081b4d084b9\", \"ui_base_unittests\": \"f45f4ac4deef250aa39d58e94a7c503bcb3c0de5\", \"ui_chromeos_unittests\": \"9ec22ec7d9f4d97c7edb558a5f91de5982cd27a5\", \"ui_touch_selection_unittests\": \"ccb0d7c348dda06c2e45b0c00677617599a8769d\", \"unit_tests\": \"d962e180fd6fb67cb284ddebd693a4c1c9503fba\", \"url_unittests\": \"394040f64727b5dc38abb103720fdda4af842de4\", \"usage_time_limit_unittests\": \"48a60b7b4f63731442e49bbf639986151955def8\", \"views_unittests\": \"bf789fa877739b561034bb5d1d9310e34792df13\", \"viz_unittests\": \"fc7403c567a80227a9d7f002700159c1a99dc717\", \"wayland_client_perftests\": \"f187d3e990950bf5e2bb6908cf0950ba7e0a8292\", \"wm_unittests\": \"bdb3d20cf938d508323c418b6b185eb7c46c2898\", \"wtf_unittests\": \"b0c1f0681a690f801fb69a178b48e02362e5fc02\", \"zlib_unittests\": \"cc9ccce2877e5e4136cb8d6dc50fc40d754a11fc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_c5a4a77f7d90ed750e2e3c196231723a8f9435afca7311d8fa2536a9dada12e3_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-48-1qrt\"], \"image\": [\"chrome-xenial-20120600-c5cfbe11ce8\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**compositor_unittests** failed because of:\\n\\n- TotalAnimationThroughputReporterTest.StartWhileAnimating\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608109135235357",
"status": "COMPLETED",
"status_changed_ts": "1608109135877189",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43767",
"builder:linux-chromeos-rel",
"buildset:commit/git/5d463cbd6f70500ea585f8837a954f10f03b7ffa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d463cbd6f70500ea585f8837a954f10f03b7ffa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026199163281342304",
"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/8860769101023857344/+/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:5083977b037a5410",
"user_agent:luci-scheduler"
],
"updated_ts": "1608112443899441",
"url": "https://ci.chromium.org/b/8860769101023857344",
"utcnow_ts": "1608112444301860"
}
}
[09:54:04.311] Invocation finished in 55m38.892024502s with status FAILED