[10:11:32.528] New invocation is queued and will start shortly
[10:11:33.685] Starting the invocation (attempt 1)
[10:11:33.752] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:11:33.754] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:11:33.754] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023023669255807696",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"786e552717ee5d41921b627ce4babc02ae7c722e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023023669255807696",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/786e552717ee5d41921b627ce4babc02ae7c722e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/786e552717ee5d41921b627ce4babc02ae7c722e",
"gitiles_ref:refs/heads/master"
]
}
[10:11:34.743] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611137493886780",
"id": "8857593607169242096",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"786e552717ee5d41921b627ce4babc02ae7c722e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611137494573833",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44759",
"builder:linux-chromeos-rel",
"buildset:commit/git/786e552717ee5d41921b627ce4babc02ae7c722e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/786e552717ee5d41921b627ce4babc02ae7c722e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023023669255807696",
"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/8857593607169242096/+/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": "1611137494574013",
"url": "https://ci.chromium.org/b/8857593607169242096",
"utcnow_ts": "1611137494734546"
}
}
[10:11:34.743] Task URL: https://ci.chromium.org/b/8857593607169242096
[10:11:34.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:2:0) after 1m0s
[10:11:36.302] Received PubSub notification, asking Buildbucket for the build status
[10:11:36.377] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:34.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:2:0)
[10:12:34.833] Timer tick, asking Buildbucket for the build status
[10:12:35.012] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:35.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:4:0) after 1m0s
[10:13:35.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:4:0)
[10:13:35.066] Timer tick, asking Buildbucket for the build status
[10:13:35.152] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:35.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:5:0) after 1m0s
[10:14:35.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:5:0)
[10:14:35.265] Timer tick, asking Buildbucket for the build status
[10:14:35.413] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:35.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:6:0) after 1m0s
[10:15:35.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:6:0)
[10:15:35.669] Timer tick, asking Buildbucket for the build status
[10:15:35.785] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:35.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:7:0) after 1m0s
[10:16:35.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:7:0)
[10:16:35.828] Timer tick, asking Buildbucket for the build status
[10:16:35.927] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:35.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:8:0) after 1m0s
[10:17:40.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:8:0)
[10:17:40.889] Timer tick, asking Buildbucket for the build status
[10:17:41.401] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:41.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:9:0) after 1m0s
[10:18:41.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:9:0)
[10:18:41.431] Timer tick, asking Buildbucket for the build status
[10:18:41.505] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:41.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:10:0) after 1m0s
[10:19:41.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:10:0)
[10:19:41.536] Timer tick, asking Buildbucket for the build status
[10:19:41.924] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:41.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:11:0) after 1m0s
[10:20:41.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:11:0)
[10:20:41.951] Timer tick, asking Buildbucket for the build status
[10:20:42.025] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:42.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:12:0) after 1m0s
[10:21:42.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:12:0)
[10:21:42.033] Timer tick, asking Buildbucket for the build status
[10:21:42.130] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:42.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:13:0) after 1m0s
[10:22:42.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:13:0)
[10:22:42.139] Timer tick, asking Buildbucket for the build status
[10:22:42.223] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:42.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:14:0) after 1m0s
[10:23:42.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:14:0)
[10:23:42.247] Timer tick, asking Buildbucket for the build status
[10:23:42.340] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:42.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:15:0) after 1m0s
[10:24:42.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:15:0)
[10:24:42.397] Timer tick, asking Buildbucket for the build status
[10:24:42.462] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:42.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:16:0) after 1m0s
[10:25:42.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:16:0)
[10:25:42.481] Timer tick, asking Buildbucket for the build status
[10:25:42.592] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:42.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:17:0) after 1m0s
[10:26:42.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:17:0)
[10:26:42.616] Timer tick, asking Buildbucket for the build status
[10:26:42.992] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:42.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:18:0) after 1m0s
[10:27:42.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:18:0)
[10:27:42.986] Timer tick, asking Buildbucket for the build status
[10:27:43.154] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:43.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:19:0) after 1m0s
[10:28:43.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:19:0)
[10:28:43.162] Timer tick, asking Buildbucket for the build status
[10:28:43.230] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:43.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:20:0) after 1m0s
[10:29:43.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:20:0)
[10:29:43.261] Timer tick, asking Buildbucket for the build status
[10:29:43.418] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:43.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:21:0) after 1m0s
[10:30:43.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:21:0)
[10:30:43.547] Timer tick, asking Buildbucket for the build status
[10:30:43.614] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:43.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:22:0) after 1m0s
[10:31:43.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:22:0)
[10:31:43.591] Timer tick, asking Buildbucket for the build status
[10:31:43.805] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:43.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:23:0) after 1m0s
[10:32:43.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:23:0)
[10:32:43.877] Timer tick, asking Buildbucket for the build status
[10:32:43.944] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:43.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:24:0) after 1m0s
[10:33:44.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:24:0)
[10:33:44.031] Timer tick, asking Buildbucket for the build status
[10:33:44.092] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:44.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:25:0) after 1m0s
[10:34:44.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:25:0)
[10:34:44.159] Timer tick, asking Buildbucket for the build status
[10:34:44.244] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:44.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:26:0) after 1m0s
[10:35:44.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:26:0)
[10:35:44.318] Timer tick, asking Buildbucket for the build status
[10:35:44.640] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:44.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:27:0) after 1m0s
[10:36:44.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:27:0)
[10:36:44.734] Timer tick, asking Buildbucket for the build status
[10:36:45.215] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:45.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:28:0) after 1m0s
[10:37:45.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:28:0)
[10:37:45.324] Timer tick, asking Buildbucket for the build status
[10:37:45.386] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:45.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:29:0) after 1m0s
[10:38:45.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:29:0)
[10:38:45.485] Timer tick, asking Buildbucket for the build status
[10:38:45.636] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:45.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:30:0) after 1m0s
[10:39:45.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:30:0)
[10:39:45.700] Timer tick, asking Buildbucket for the build status
[10:39:45.763] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:45.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:31:0) after 1m0s
[10:40:45.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:31:0)
[10:40:45.786] Timer tick, asking Buildbucket for the build status
[10:40:45.924] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:45.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:32:0) after 1m0s
[10:41:46.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:32:0)
[10:41:46.009] Timer tick, asking Buildbucket for the build status
[10:41:46.103] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:46.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:33:0) after 1m0s
[10:42:46.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:33:0)
[10:42:46.126] Timer tick, asking Buildbucket for the build status
[10:42:46.306] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:46.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:34:0) after 1m0s
[10:43:46.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:34:0)
[10:43:46.297] Timer tick, asking Buildbucket for the build status
[10:43:46.381] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:46.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:35:0) after 1m0s
[10:44:46.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:35:0)
[10:44:46.405] Timer tick, asking Buildbucket for the build status
[10:44:46.466] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:46.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:36:0) after 1m0s
[10:45:46.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:36:0)
[10:45:46.442] Timer tick, asking Buildbucket for the build status
[10:45:46.502] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:46.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:37:0) after 1m0s
[10:46:46.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:37:0)
[10:46:46.523] Timer tick, asking Buildbucket for the build status
[10:46:46.615] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:46.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:38:0) after 1m0s
[10:47:46.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:38:0)
[10:47:46.656] Timer tick, asking Buildbucket for the build status
[10:47:46.733] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:46.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:39:0) after 1m0s
[10:48:46.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:39:0)
[10:48:46.808] Timer tick, asking Buildbucket for the build status
[10:48:46.868] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:46.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:40:0) after 1m0s
[10:49:47.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:40:0)
[10:49:47.177] Timer tick, asking Buildbucket for the build status
[10:49:47.238] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:47.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:41:0) after 1m0s
[10:50:47.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:41:0)
[10:50:47.220] Timer tick, asking Buildbucket for the build status
[10:50:47.283] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:47.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:42:0) after 1m0s
[10:51:47.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:42:0)
[10:51:47.321] Timer tick, asking Buildbucket for the build status
[10:51:47.439] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:47.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:43:0) after 1m0s
[10:52:47.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:43:0)
[10:52:47.576] Timer tick, asking Buildbucket for the build status
[10:52:47.642] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:47.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:44:0) after 1m0s
[10:53:47.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:44:0)
[10:53:47.747] Timer tick, asking Buildbucket for the build status
[10:53:47.999] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:47.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:45:0) after 1m0s
[10:54:47.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:45:0)
[10:54:47.995] Timer tick, asking Buildbucket for the build status
[10:54:48.120] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:48.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:46:0) after 1m0s
[10:55:48.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:46:0)
[10:55:48.173] Timer tick, asking Buildbucket for the build status
[10:55:48.747] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:48.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:47:0) after 1m0s
[10:56:48.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:47:0)
[10:56:48.770] Timer tick, asking Buildbucket for the build status
[10:56:49.122] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:49.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:48:0) after 1m0s
[10:57:49.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:48:0)
[10:57:49.182] Timer tick, asking Buildbucket for the build status
[10:57:49.388] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:49.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:49:0) after 1m0s
[10:58:49.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:49:0)
[10:58:49.491] Timer tick, asking Buildbucket for the build status
[10:58:49.601] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:49.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:50:0) after 1m0s
[10:59:49.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:50:0)
[10:59:49.673] Timer tick, asking Buildbucket for the build status
[10:59:49.860] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:49.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:51:0) after 1m0s
[11:00:49.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:51:0)
[11:00:49.887] Timer tick, asking Buildbucket for the build status
[11:00:50.002] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:50.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:52:0) after 1m0s
[11:01:50.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:52:0)
[11:01:50.077] Timer tick, asking Buildbucket for the build status
[11:01:50.231] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:50.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:53:0) after 1m0s
[11:02:50.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:53:0)
[11:02:50.260] Timer tick, asking Buildbucket for the build status
[11:02:50.594] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:50.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:54:0) after 1m0s
[11:03:50.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:54:0)
[11:03:50.715] Timer tick, asking Buildbucket for the build status
[11:03:51.091] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:51.091] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:55:0) after 1m0s
[11:04:51.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:55:0)
[11:04:51.187] Timer tick, asking Buildbucket for the build status
[11:04:51.297] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:51.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:56:0) after 1m0s
[11:05:51.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:56:0)
[11:05:51.299] Timer tick, asking Buildbucket for the build status
[11:05:51.412] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:51.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:57:0) after 1m0s
[11:06:51.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:57:0)
[11:06:51.421] Timer tick, asking Buildbucket for the build status
[11:06:51.546] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:51.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:58:0) after 1m0s
[11:07:51.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:58:0)
[11:07:51.571] Timer tick, asking Buildbucket for the build status
[11:07:51.717] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:51.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:59:0) after 1m0s
[11:08:51.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:59:0)
[11:08:51.744] Timer tick, asking Buildbucket for the build status
[11:08:52.095] Build 8857593607169242096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:52.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023023669255807696:60:0) after 1m0s
[11:09:37.004] Received PubSub notification, asking Buildbucket for the build status
[11:09:37.305] Build 8857593607169242096: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:09:37.305] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611140976304851",
"created_by": "project:chromium",
"created_ts": "1611137493886780",
"failure_reason": "BUILD_FAILURE",
"id": "8857593607169242096",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"786e552717ee5d41921b627ce4babc02ae7c722e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- MediaSessionBrowserTest.MultiplePlayersPlayPause\"}, \"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\": \"786e552717ee5d41921b627ce4babc02ae7c722e\", \"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-65-yzph\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44759, \"got_angle_revision\": \"186fe99034047745c4a626935b3ac2cce557f8f3\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"786e552717ee5d41921b627ce4babc02ae7c722e\", \"got_revision_cp\": \"refs/heads/master@{#845117}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"22be66d4071284ffeeef424adc068e9e400835ab\", \"got_v8_revision\": \"0c46f3e0ded50952ed6f46254bbd16776f532aa0\", \"got_v8_revision_cp\": \"refs/heads/9.0.20@{#1}\", \"got_webrtc_revision\": \"5eb527cf7fb28e22d0687c0295cb9f1bb458160c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33034}\", \"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\": \"786e552717ee5d41921b627ce4babc02ae7c722e\", \"swarm_hashes_refs/heads/master(at){#845117}_without_patch\": {\"absl_hardening_tests\": \"4767f927d898e6fd5b931ab9ba7c7b063651b93c\", \"accessibility_unittests\": \"53f982f1632660607a5ccfc4a8f02dbc68734957\", \"angle_unittests\": \"51a81aad3db673847b58314a5e6ba684e600686c\", \"app_list_unittests\": \"9b2c747c863044521cfb3f5e5d2588df0f3f47aa\", \"app_shell_unittests\": \"02a6e3f6a4c7c9fec11b602fcc1d8250c7889f08\", \"ash_unittests\": \"0629087ee28c3a867a4f1c762e63982805cab4e2\", \"aura_unittests\": \"0beec4b973a7c754c4e076238f8174fb081f7577\", \"base_unittests\": \"22a3da8659c4b04b924b8a23d920c654fa077f3d\", \"base_util_unittests\": \"f997a83cfd9d6aa6af78e38edf59725b845a69d4\", \"blink_common_unittests\": \"772cf34db20d6e07ff26f9643e504b5d4c1b2f2c\", \"blink_fuzzer_unittests\": \"01d3085db5726bf914ca4c8767dba64f4f955a2a\", \"blink_heap_unittests\": \"87a9f267210d8f714e835b8de6d1695982fe0c95\", \"blink_platform_unittests\": \"2cf2969c77dc42213bd6b9efec47c5a0f976ab43\", \"blink_unittests\": \"17b01a7444d96c4c52649a48934aa723f582c216\", \"boringssl_crypto_tests\": \"607aeda92f0a65ed54f5277d0014f4c93b429942\", \"boringssl_ssl_tests\": \"e49e5de5a0ccd0e64126f1b02db682ade2596fde\", \"browser_tests\": \"99fcdf4ec8b7a62d1ea6c20dd003718957d537b7\", \"capture_unittests\": \"242f40a5956e406630f085a0bdcfb75d1c9b6b2a\", \"cast_unittests\": \"8ead81ab92ee80bdf6e5b1f0803b8cd7fd3c07c8\", \"cc_unittests\": \"d8630f39095b6e6400108e80809f802adeea39dd\", \"chrome_app_unittests\": \"bf891b839a3419e79a3069cefc3e967356f094d5\", \"chromedriver_unittests\": \"c9a630c0a4a4c44dadaa7e86d097c9e9277d8d30\", \"chromeos_components_unittests\": \"f77a7d8e9b745919f8f55149ca204be16cae5e41\", \"chromeos_unittests\": \"e5bea6da2ba66945dd6f72ac0187467d046b5a36\", \"components_browsertests\": \"11aa83b66d885043cdfe677134f3e764dedb4aa6\", \"components_unittests\": \"a933dbfc3ff296607f17cde46b480f8decd0f333\", \"compositor_unittests\": \"81a0668d000078bac583adf3f09720297617020b\", \"content_browsertests\": \"823d76286b08c3fc2a0be37e1d4b167369a4b551\", \"content_unittests\": \"d0351532ec51355b69bc1803e5eb1cd7590f24b6\", \"crashpad_tests\": \"b33fb818123c9dfbe776b3e17bd355320c5fb41c\", \"crypto_unittests\": \"43be19d903c8f56137a631d541c68dd9ddccceea\", \"dbus_unittests\": \"d9856378c21d3e8a6a76a2008496e5abc5de4027\", \"device_unittests\": \"a15646cac1246015a58d561711ca0fb56db1fdaf\", \"display_unittests\": \"f03cf868bd7e537c35897038edc37621e12d5b80\", \"events_unittests\": \"397c04cfaaa3fbfc5ba33e588426ac84106d04b4\", \"exo_unittests\": \"9d0a4d697ba03535b3aafa2a47e6c8fd9e805220\", \"extensions_browsertests\": \"dc1835235a8467c435a5925ef27de88a33f53054\", \"extensions_unittests\": \"fb4ccd06c506d81c33cb87ead57ebd9839db7dd5\", \"filesystem_service_unittests\": \"c97d17a4221db2f02829897d7b3390d398792a07\", \"gcm_unit_tests\": \"58412b7b5439fcd71fedf9a90dd2a0e9e4cff9cb\", \"gfx_unittests\": \"f2cbef8b03f6e2b45b9de8bda538e6ec7352ffe0\", \"gin_unittests\": \"2e84be59da04902f95f124f6b0b204aa83ff025a\", \"gl_unittests_ozone\": \"79ed7fce5ddde6c9e3a4cc3f4b8986f747eccf22\", \"google_apis_unittests\": \"5b5ffd7e876225342f28eaffb5482e985915c495\", \"gpu_unittests\": \"80416f8d993819a72efc244cae94d9c779e00625\", \"gwp_asan_unittests\": \"ce06764402fcba2e4b55dec0279c3f70c0818ad7\", \"interactive_ui_tests\": \"d76ed56eeb564f00940cf2a93525b3411e353dc3\", \"ipc_tests\": \"937faa48aa788eaf941f18a9b8c22db77e773ea7\", \"jingle_unittests\": \"924fdb1cf5f9142993774deb27b54f2108069713\", \"keyboard_unittests\": \"b9c4ee15572c3fe9e3f77d1007aaa8f94ad128ae\", \"latency_unittests\": \"262f0213a8901fb3166692d8741d72e522bbb44a\", \"libjingle_xmpp_unittests\": \"c11faac12f1c20ac7eba0721a6ac93b77611a610\", \"liburlpattern_unittests\": \"a6bfbf9d2e627cdaec9a4ec0f1e79418c813b938\", \"media_blink_unittests\": \"7184e91a539b559df67259a86122ec8fb1b95fef\", \"media_unittests\": \"654b2aa35eb16d8e907c6ea5fa4d6ceeb930f2b1\", \"message_center_unittests\": \"4f987607c6b084c3526360698214351be607e02e\", \"midi_unittests\": \"45d3d0228125533f3a492f70b80a068ff2be3121\", \"mojo_core_unittests\": \"ac8263f90395096a6238bf45c654ec6123830aaf\", \"mojo_unittests\": \"36a333acb794880fbb21399fac964b214b6f4d51\", \"nacl_helper_nonsfi_unittests\": \"34bdd76a5dfa2e0c6deac254a2236e146c103472\", \"nacl_loader_unittests\": \"82e222afe340245a449370c747826765f24553ad\", \"native_theme_unittests\": \"ae2deb1d493aa28fb15dda29f76b7b3be1fb2afe\", \"net_unittests\": \"fdb79a0ee7addac45de2f0349ce21e62ddf6c24c\", \"ozone_gl_unittests\": \"e73bc6084f6c279f837676a783be3ebf4672111b\", \"ozone_unittests\": \"0503b6a612b9eee8abd285143b0e28566bd81012\", \"ozone_x11_unittests\": \"d5cac2fbc326b45bd0ca1d433dffae134ccda9a7\", \"pdf_unittests\": \"b9dcfbf765da9191c77c539248945a882bc41ebb\", \"perfetto_unittests\": \"d6b58341369a218ce386244fd703c85942cd4097\", \"ppapi_unittests\": \"72a611b916aea15b0b4825d178c5ec8707ff94db\", \"printing_unittests\": \"2953bf1ce0b3d116ccad1b9636ec88f4ac14f38d\", \"remoting_unittests\": \"63e6c182f819e3313b36a9114b500419ad7b7437\", \"sandbox_linux_unittests\": \"f7d6383e207fb40d3cae571fd0d29c16c02c45cc\", \"service_manager_unittests\": \"8d7050f808c79cf72d8d58c46ffdc8682e169a58\", \"services_unittests\": \"b96d0b450258d98e3120d0ac835ff7bc2481e2b2\", \"shell_dialogs_unittests\": \"d8b155b1cf4515ae45fb217ecdaa4579d427e273\", \"shell_encryption_unittests\": \"17fc0f877c05ae9d47c5512d00a4e178642e21d9\", \"skia_unittests\": \"56c1b1bc6e2b5728719dcfe3943b61a685f4b71d\", \"snapshot_unittests\": \"ff912b8eb51b53ed4dd8256c9651b76f05389d02\", \"sql_unittests\": \"a56e2a2978fba995525a6ee1e96bcece0f0e588c\", \"storage_unittests\": \"321bc6badf281275bf556befaff1d4c94378b174\", \"sync_integration_tests\": \"4adccf80d388cf530ea1f209c40765c49aebebcb\", \"traffic_annotation_auditor_unittests\": \"97f69a3d4e4ad582284dad579afb40518bdd66dd\", \"ui_base_unittests\": \"c16f2f39167ec5ef197ccf91408d9f1db13e2a10\", \"ui_chromeos_unittests\": \"1bd68ad0399a7b1aedb771502e289f27fddc6672\", \"ui_touch_selection_unittests\": \"ee5afb10cdcde331f206e5f6fd4ffc7ce01cbd29\", \"unit_tests\": \"18e2f0689a932dca67f537219f47746e99e97799\", \"url_unittests\": \"e9fdbfc58b66e5853ff2af5b99e0edc452be2bf0\", \"usage_time_limit_unittests\": \"aa6124a8188db762afa83f28915927d38bf7ada9\", \"views_unittests\": \"c0fd53ff13b808cb51233a94c0fced15e3322606\", \"viz_unittests\": \"e361a134a022a8921ae9b814297ee05fe4b7d4af\", \"wayland_client_perftests\": \"c946bed913618596133a32fda32e9c9a9c2bb8d4\", \"wm_unittests\": \"67e15f7fb860d8d055ab17f99180b5945060aff8\", \"wtf_unittests\": \"9ba1eee0f37671b4736af66148111fc16f489343\", \"zlib_unittests\": \"dff1d2dad7cdcce911d3f679ff07a49374997c16\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_6012964051d5021e7307eb12ad598b318c657120f21d3654d81218fef56916e6_v2\", \"builder_8f1f1a811acb8ac7cee91f001f34cb98db55e889611ed2a31914ce7c854c1541_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9ab1612fdb00db29a8d60b17a842600a8e29ecb37944be685dafbc3ddd01329a_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"builder_ce22d0fc5c297d03141230de59f52ebaf9005395a667e8b14df59e499796620f_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-65-yzph\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- MediaSessionBrowserTest.MultiplePlayersPlayPause\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611137495721685",
"status": "COMPLETED",
"status_changed_ts": "1611137496084394",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44759",
"builder:linux-chromeos-rel",
"buildset:commit/git/786e552717ee5d41921b627ce4babc02ae7c722e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/786e552717ee5d41921b627ce4babc02ae7c722e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023023669255807696",
"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/8857593607169242096/+/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:513818b83640cb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611140976679452",
"url": "https://ci.chromium.org/b/8857593607169242096",
"utcnow_ts": "1611140977247537"
}
}
[11:09:37.305] Invocation finished in 58m4.792857866s with status FAILED