[23:14:47.011] New invocation is queued and will start shortly
[23:14:48.144] Starting the invocation (attempt 1)
[23:14:48.185] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:14:48.185] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:14:48.185] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026688867620236688",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8643e471def277bdc69b1ac0ec9ae40a5d6afc50\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9026688867620236688",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/8643e471def277bdc69b1ac0ec9ae40a5d6afc50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8643e471def277bdc69b1ac0ec9ae40a5d6afc50",
"gitiles_ref:refs/heads/master"
]
}
[23:14:49.094] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607642088296323",
"id": "8861258805580832416",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8643e471def277bdc69b1ac0ec9ae40a5d6afc50\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607642088745019",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43622",
"builder:linux-chromeos-rel",
"buildset:commit/git/8643e471def277bdc69b1ac0ec9ae40a5d6afc50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8643e471def277bdc69b1ac0ec9ae40a5d6afc50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026688867620236688",
"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/8861258805580832416/+/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": "1607642088745132",
"url": "https://ci.chromium.org/b/8861258805580832416",
"utcnow_ts": "1607642089084107"
}
}
[23:14:49.094] Task URL: https://ci.chromium.org/b/8861258805580832416
[23:14:49.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:2:0) after 1m0s
[23:14:58.467] Received PubSub notification, asking Buildbucket for the build status
[23:14:58.579] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:49.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:2:0)
[23:15:49.207] Timer tick, asking Buildbucket for the build status
[23:15:49.362] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:49.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:4:0) after 1m0s
[23:16:49.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:4:0)
[23:16:49.434] Timer tick, asking Buildbucket for the build status
[23:16:49.560] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:49.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:5:0) after 1m0s
[23:17:49.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:5:0)
[23:17:49.600] Timer tick, asking Buildbucket for the build status
[23:17:50.064] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:50.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:6:0) after 1m0s
[23:18:50.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:6:0)
[23:18:50.092] Timer tick, asking Buildbucket for the build status
[23:18:50.235] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:50.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:7:0) after 1m0s
[23:19:50.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:7:0)
[23:19:50.271] Timer tick, asking Buildbucket for the build status
[23:19:50.432] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:50.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:8:0) after 1m0s
[23:20:50.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:8:0)
[23:20:50.454] Timer tick, asking Buildbucket for the build status
[23:20:50.729] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:50.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:9:0) after 1m0s
[23:21:50.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:9:0)
[23:21:50.805] Timer tick, asking Buildbucket for the build status
[23:21:50.932] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:50.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:10:0) after 1m0s
[23:22:51.041] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:10:0)
[23:22:51.041] Timer tick, asking Buildbucket for the build status
[23:22:51.315] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:51.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:11:0) after 1m0s
[23:23:51.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:11:0)
[23:23:51.383] Timer tick, asking Buildbucket for the build status
[23:23:51.729] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:51.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:12:0) after 1m0s
[23:24:51.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:12:0)
[23:24:51.766] Timer tick, asking Buildbucket for the build status
[23:24:51.896] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:51.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:13:0) after 1m0s
[23:25:51.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:13:0)
[23:25:51.915] Timer tick, asking Buildbucket for the build status
[23:25:52.065] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:52.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:14:0) after 1m0s
[23:26:52.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:14:0)
[23:26:52.138] Timer tick, asking Buildbucket for the build status
[23:26:52.264] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:52.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:15:0) after 1m0s
[23:27:52.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:15:0)
[23:27:52.354] Timer tick, asking Buildbucket for the build status
[23:27:52.631] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:52.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:16:0) after 1m0s
[23:28:52.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:16:0)
[23:28:52.688] Timer tick, asking Buildbucket for the build status
[23:28:52.761] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:52.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:17:0) after 1m0s
[23:29:52.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:17:0)
[23:29:52.786] Timer tick, asking Buildbucket for the build status
[23:29:53.198] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:53.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:18:0) after 1m0s
[23:30:53.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:18:0)
[23:30:53.308] Timer tick, asking Buildbucket for the build status
[23:30:53.414] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:53.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:19:0) after 1m0s
[23:31:53.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:19:0)
[23:31:53.438] Timer tick, asking Buildbucket for the build status
[23:31:53.678] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:53.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:20:0) after 1m0s
[23:32:53.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:20:0)
[23:32:53.764] Timer tick, asking Buildbucket for the build status
[23:32:53.832] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:53.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:21:0) after 1m0s
[23:33:53.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:21:0)
[23:33:53.878] Timer tick, asking Buildbucket for the build status
[23:33:53.974] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:53.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:22:0) after 1m0s
[23:34:54.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:22:0)
[23:34:54.017] Timer tick, asking Buildbucket for the build status
[23:34:54.141] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:54.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:23:0) after 1m0s
[23:35:54.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:23:0)
[23:35:54.169] Timer tick, asking Buildbucket for the build status
[23:35:54.258] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:54.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:24:0) after 1m0s
[23:36:54.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:24:0)
[23:36:54.258] Timer tick, asking Buildbucket for the build status
[23:36:54.565] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:54.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:25:0) after 1m0s
[23:37:54.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:25:0)
[23:37:54.607] Timer tick, asking Buildbucket for the build status
[23:37:54.706] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:54.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:26:0) after 1m0s
[23:38:54.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:26:0)
[23:38:54.728] Timer tick, asking Buildbucket for the build status
[23:38:54.813] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:54.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:27:0) after 1m0s
[23:39:54.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:27:0)
[23:39:54.834] Timer tick, asking Buildbucket for the build status
[23:39:54.973] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:54.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:28:0) after 1m0s
[23:40:54.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:28:0)
[23:40:54.995] Timer tick, asking Buildbucket for the build status
[23:40:55.080] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:55.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:29:0) after 1m0s
[23:41:55.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:29:0)
[23:41:55.147] Timer tick, asking Buildbucket for the build status
[23:41:55.224] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:55.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:30:0) after 1m0s
[23:42:55.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:30:0)
[23:42:55.271] Timer tick, asking Buildbucket for the build status
[23:42:55.342] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:55.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:31:0) after 1m0s
[23:43:55.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:31:0)
[23:43:55.365] Timer tick, asking Buildbucket for the build status
[23:43:55.433] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:55.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:32:0) after 1m0s
[23:44:55.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:32:0)
[23:44:55.465] Timer tick, asking Buildbucket for the build status
[23:44:55.848] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:55.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:33:0) after 1m0s
[23:45:55.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:33:0)
[23:45:55.890] Timer tick, asking Buildbucket for the build status
[23:45:56.138] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:56.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:34:0) after 1m0s
[23:46:56.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:34:0)
[23:46:56.162] Timer tick, asking Buildbucket for the build status
[23:46:56.343] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:56.343] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:35:0) after 1m0s
[23:47:56.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:35:0)
[23:47:56.339] Timer tick, asking Buildbucket for the build status
[23:47:56.444] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:56.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:36:0) after 1m0s
[23:48:56.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:36:0)
[23:48:56.468] Timer tick, asking Buildbucket for the build status
[23:48:56.855] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:56.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:37:0) after 1m0s
[23:49:56.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:37:0)
[23:49:56.878] Timer tick, asking Buildbucket for the build status
[23:49:56.971] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:56.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:38:0) after 1m0s
[23:50:57.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:38:0)
[23:50:57.019] Timer tick, asking Buildbucket for the build status
[23:50:57.078] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:57.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:39:0) after 1m0s
[23:51:57.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:39:0)
[23:51:57.116] Timer tick, asking Buildbucket for the build status
[23:51:57.252] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:57.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:40:0) after 1m0s
[23:52:57.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:40:0)
[23:52:57.275] Timer tick, asking Buildbucket for the build status
[23:52:57.417] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:57.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:41:0) after 1m0s
[23:53:57.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:41:0)
[23:53:57.422] Timer tick, asking Buildbucket for the build status
[23:53:57.835] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:57.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:42:0) after 1m0s
[23:54:57.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:42:0)
[23:54:57.860] Timer tick, asking Buildbucket for the build status
[23:54:58.262] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:58.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:43:0) after 1m0s
[23:55:58.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:43:0)
[23:55:58.311] Timer tick, asking Buildbucket for the build status
[23:55:58.493] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:58.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:44:0) after 1m0s
[23:56:58.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:44:0)
[23:56:58.516] Timer tick, asking Buildbucket for the build status
[23:56:58.592] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:58.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:45:0) after 1m0s
[23:57:58.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:45:0)
[23:57:58.670] Timer tick, asking Buildbucket for the build status
[23:57:58.859] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:58.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:46:0) after 1m0s
[23:58:58.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:46:0)
[23:58:58.929] Timer tick, asking Buildbucket for the build status
[23:58:59.260] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:59.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:47:0) after 1m0s
[23:59:59.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:47:0)
[23:59:59.314] Timer tick, asking Buildbucket for the build status
[23:59:59.474] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:59.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:48:0) after 1m0s
[00:00:59.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:48:0)
[00:00:59.562] Timer tick, asking Buildbucket for the build status
[00:00:59.810] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:59.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:49:0) after 1m0s
[00:01:59.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:49:0)
[00:01:59.906] Timer tick, asking Buildbucket for the build status
[00:02:00.532] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:00.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:50:0) after 1m0s
[00:03:00.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:50:0)
[00:03:00.612] Timer tick, asking Buildbucket for the build status
[00:03:00.797] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:00.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:51:0) after 1m0s
[00:04:01.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:51:0)
[00:04:01.050] Timer tick, asking Buildbucket for the build status
[00:04:01.265] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:01.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:52:0) after 1m0s
[00:05:01.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:52:0)
[00:05:01.291] Timer tick, asking Buildbucket for the build status
[00:05:01.528] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:01.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:53:0) after 1m0s
[00:06:01.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:53:0)
[00:06:01.746] Timer tick, asking Buildbucket for the build status
[00:06:02.026] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:02.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:54:0) after 1m0s
[00:07:02.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:54:0)
[00:07:02.146] Timer tick, asking Buildbucket for the build status
[00:07:02.431] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:02.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:55:0) after 1m0s
[00:08:02.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:55:0)
[00:08:02.598] Timer tick, asking Buildbucket for the build status
[00:08:03.093] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:03.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:56:0) after 1m0s
[00:09:03.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:56:0)
[00:09:03.136] Timer tick, asking Buildbucket for the build status
[00:09:03.583] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:03.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:57:0) after 1m0s
[00:10:03.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:57:0)
[00:10:03.633] Timer tick, asking Buildbucket for the build status
[00:10:04.067] Build 8861258805580832416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:04.067] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026688867620236688:58:0) after 1m0s
[00:10:19.071] Received PubSub notification, asking Buildbucket for the build status
[00:10:19.158] Build 8861258805580832416: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:10:19.158] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607645417009648",
"created_by": "project:chromium",
"created_ts": "1607642088296323",
"failure_reason": "BUILD_FAILURE",
"id": "8861258805580832416",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8643e471def277bdc69b1ac0ec9ae40a5d6afc50\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- CheckSystemTokenAvailability/EnterprisePlatformKeysTest.Basic/0\\n\\n- FileDialog/FilesAppBrowserTest.Test/openFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/saveFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/saveFileDialogSingleFilterNoAcceptAll\\n\\n- ...1 more failure(s) (5 total)...\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"8643e471def277bdc69b1ac0ec9ae40a5d6afc50\", \"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-161-awyl\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43622, \"got_angle_revision\": \"f6df8692023c49bded07622e064f6f0a991c0938\", \"got_dawn_revision\": \"6bcd4aceef100810284d61bd9dad0e9bd05e4c92\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"8643e471def277bdc69b1ac0ec9ae40a5d6afc50\", \"got_revision_cp\": \"refs/heads/master@{#835875}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"0a64a974755521dbb2b16332002a927fb830a0d4\", \"got_v8_revision\": \"0937070d01bcf37e336b226612516ff2e86f7463\", \"got_v8_revision_cp\": \"refs/heads/8.9.130@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"8643e471def277bdc69b1ac0ec9ae40a5d6afc50\", \"swarm_hashes_refs/heads/master(at){#835875}_without_patch\": {\"absl_hardening_tests\": \"5fbc49ecbe6bf6a802573ed3e70c4450453a3617\", \"accessibility_unittests\": \"cab0db17c1ab75ed5ca2220e54e618c4ab912c34\", \"angle_unittests\": \"ae8c07c258ee2fc753788521cc0e4523fb85c4c7\", \"app_list_unittests\": \"bc1c1a6f41f99c14d8547544f4ebc5d0b3040f43\", \"app_shell_unittests\": \"5311fce0752fc65ca0e47d21356f59dcc4a28487\", \"ash_unittests\": \"7dfedb6f723a8bc32991ab4933ebdefd0e7059d6\", \"aura_unittests\": \"abbdaea17e5604cf292f7f9922120ec6addc2344\", \"base_unittests\": \"219c3404d3d656d81ceb2e68fa2d96aaa0e86b8c\", \"base_util_unittests\": \"cab61f69085aad99c93b22e58ae6cad4a4aa8d97\", \"blink_common_unittests\": \"333fd10ffa2f51740896aee53e7051d96246b132\", \"blink_fuzzer_unittests\": \"47ccb533ba1edf12b706f49d928fd97e4007ca78\", \"blink_heap_unittests\": \"21a6e55ca9d44a9809d0d299f074d12bf05639d6\", \"blink_platform_unittests\": \"67b004093e00566a0ca67e56d70f1bc2564554ba\", \"blink_unittests\": \"e821748874d4c7fc5fe8871229d694fa40f0b364\", \"boringssl_crypto_tests\": \"86ae9c20e2790c51c0f88adf50a8546ac05d0a01\", \"boringssl_ssl_tests\": \"4f3c19487464c7fb62159334e4cbbec5978cbcd5\", \"browser_tests\": \"aa0631286dfbf2222627abb811b9648df4a90348\", \"capture_unittests\": \"74b3fd2dfe253de339ad3432336c17b0652d914c\", \"cast_unittests\": \"1f1b0ca984e4611d468fc6d94ff7a4b1e855592f\", \"cc_unittests\": \"5471cef825ccacd03c9eb7eeea63422e3c520290\", \"chrome_app_unittests\": \"1e8e61d00b25d29b83e6efc11a6d1bd8cf6845ad\", \"chromedriver_unittests\": \"e6adacc93c0781d93025ddfe133637f73d9c7b76\", \"chromeos_components_unittests\": \"fb2d378eb067f6b57260cae9dbc9f7e919359408\", \"chromeos_unittests\": \"e2dc888cf254a93470d0efd0f1028a0e149b3afb\", \"components_browsertests\": \"db07d3b52d3c36af823e38725cf843ad9dd7c629\", \"components_unittests\": \"da06ed6b30f018640d3e8b9ae591ec53de426391\", \"compositor_unittests\": \"cdb4255b55cab8f76c69e7e471df1eae8a06d2c2\", \"content_browsertests\": \"ffd8ba0dbd27d3097e4c5c77b8fe44fc8a5af7d9\", \"content_unittests\": \"c95d690c76f0b5cbb6fef7466122c119a0b52ae2\", \"crashpad_tests\": \"27a9ca5e7b166041fea25b9da95492d9d68f7f4e\", \"crypto_unittests\": \"e256c9b054052d0bc5adc27325ec97c179334be2\", \"dbus_unittests\": \"6309cb8287be976d98e37465a9d57cb685db3239\", \"device_unittests\": \"7fd6dafa1b4cddc25a161136f7cffce3fb14d0a0\", \"display_unittests\": \"1f30ab68a6690b8e1ebd23fe9adc32a573300b7f\", \"events_unittests\": \"b4f76d0b61a84830f29d14cfedbbc1090e54f740\", \"exo_unittests\": \"d827b9693d5b01b819aae47d8de308656adf8574\", \"extensions_browsertests\": \"227de43985b0dfd39bc52b05bca3d1c3853ac00b\", \"extensions_unittests\": \"0d173444bae914aa7ba87beba6edaa3af4775111\", \"filesystem_service_unittests\": \"588197d8ca8cadb0ec117ac8a2d282cbec0a1f4b\", \"gcm_unit_tests\": \"f2969c7c3b066d68f4680916f0f41f779cf0c093\", \"gfx_unittests\": \"60c0c637181124b8a879508a25e4c0f0fc5dd75c\", \"gin_unittests\": \"96a15eb79bb22db4e931736de674584fdf4f9490\", \"gl_unittests_ozone\": \"7935437c4dbe22dbfbdc8462cd5f508d68b0683b\", \"google_apis_unittests\": \"27d39357f0b543b00fd491d5a4873fb7c96a5440\", \"gpu_unittests\": \"09dddeb9691af0221a51dd69a03e902bb227b337\", \"gwp_asan_unittests\": \"770ba070d2e3f5fdbaf887331c802ab02dc58113\", \"interactive_ui_tests\": \"8754cb6fc045a50df52480a199256a4699e74b8a\", \"ipc_tests\": \"edba1fbbe227de77a0023837beba2c32155d93b3\", \"jingle_unittests\": \"9336f2d37c2f8f515c7bceb6c582cd95c5af4a95\", \"keyboard_unittests\": \"22c4027046435d4700fe869f63a6726beeee7d1f\", \"latency_unittests\": \"bf3bc9eb4e76ddf12215485558d1c46958771e5b\", \"libjingle_xmpp_unittests\": \"502b12cdc41f0497fe2241b5f86757d7267a800c\", \"liburlpattern_unittests\": \"51e7e1cf36f685fc745ffdec792bfb19043df4d7\", \"media_blink_unittests\": \"67429bc54fb9112da35575bdbbe76d3785b843e1\", \"media_unittests\": \"2952293bd51d36a40b633110a15cd54841eae19c\", \"message_center_unittests\": \"c51bd42afcb7c4bd3307a8d905ec1007ec396be3\", \"midi_unittests\": \"3d8f1b21a56134a7ee3a041144b4479c2f9492c0\", \"mojo_core_unittests\": \"a3d52b670111e2af223889a19f78fa66a0484080\", \"mojo_unittests\": \"519e08555bb6c90a3caf73d5b0ffa70051ac4916\", \"nacl_helper_nonsfi_unittests\": \"4b4c44e4b1e40cf5e835ccd0b3260ed7adc5c24a\", \"nacl_loader_unittests\": \"dd1c0d60b9ade924a176b8ffe250a92f2fe0deb9\", \"native_theme_unittests\": \"718e96fe9c0be68a156e1e4a2945653ad0ad8d2d\", \"net_unittests\": \"d4da51c785723b74fd95e8f13c6c9e79277916e0\", \"ozone_gl_unittests\": \"a68bcc11314a32535c495f039a80a3f057689812\", \"ozone_unittests\": \"6d955e438cf62787c6a4d6c6d66de4362a51ade8\", \"ozone_x11_unittests\": \"76a362c5a0a6b5b86b9195651829c19583028bac\", \"pdf_unittests\": \"8cdd504c1d905059b468fc7612e6a011bfce37fa\", \"perfetto_unittests\": \"2bb70df1ca2cb66f3b7d95162cee1488c0576267\", \"ppapi_unittests\": \"959aefae236c027ac9a17327bae06da99f39471a\", \"printing_unittests\": \"34f02ecc11d2c25ef5c8a6f1d1598259fca16c9f\", \"remoting_unittests\": \"6ec640c95c76f80ab97726a2f46e2590e8962fa7\", \"sandbox_linux_unittests\": \"0ac9db16a603df8baa6a1d1ea65f49c95f091ca9\", \"service_manager_unittests\": \"072904df7bd7af73d71aeb4c2fb7aca48d93f922\", \"services_unittests\": \"79be43da60fc3a580d68a1c20572f8f6d8c58461\", \"shell_dialogs_unittests\": \"e7717904c7f3fb8a381c3d593c60169a93973188\", \"shell_encryption_unittests\": \"573908cdc869437e78752e5a121ede9a463e0a85\", \"skia_unittests\": \"716ca5ea51951128052be852a51a18a8809466c2\", \"snapshot_unittests\": \"c463bc39fc9c48e498ab338a7d80dc60a3ffbdf7\", \"sql_unittests\": \"6b8fcbcc38517b38a31ae723579da302a6996d33\", \"storage_unittests\": \"786b2e09e5babe6fe834f2fdcacbadff478139ed\", \"sync_integration_tests\": \"f8e0b9847d30673f79641cb0569ebc0b7c26ef58\", \"traffic_annotation_auditor_unittests\": \"359586add3922f51b013b9f37188418ed002db8d\", \"ui_base_unittests\": \"ce8e496bd7d5795872c1aa137bd2de0184f90abc\", \"ui_chromeos_unittests\": \"6928852cf4bdb9605e5903e9793ed0764bee8b2d\", \"ui_touch_selection_unittests\": \"0130316701cc7daa0c70cd83871e61a38e5cf767\", \"unit_tests\": \"c00701f748deb8c1fa117c1547b88ded618421da\", \"url_unittests\": \"bca13c19ac36a0ededfbb87fd139f7d49ce30712\", \"usage_time_limit_unittests\": \"15e0aac61c9dd97009d6d8238f8eca77d9bfa2ea\", \"views_unittests\": \"d7a1442e35879932e2e1d4fb6c365cec65b2760d\", \"viz_unittests\": \"af142a2f9ce0ecf86f91019fdf4c137c1318b25b\", \"wayland_client_perftests\": \"aaa3368ba6dc18ad311162612a6d8f1c8f46f98e\", \"wm_unittests\": \"5cc2f41f2c8d3376001ae6a98aa60616ac263e63\", \"wtf_unittests\": \"50b11d99bdd653ca679f76c7087faa7bde5b76e0\", \"zlib_unittests\": \"ddb60349ff6780f1b808b03f598c48717eecf5be\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_36c8846ac28dcb92a60d8a88629bcaff3f7fc5fe5317cfbc320487dfb1197cd9_v2\", \"builder_4107b14b7476412be0315d38f8e370eb2ac2c1b388bb8ffb59d7bdd8f08032e9_v2\", \"builder_4f0964162888fb5bae249a111360c6881d9714a6e065febf968887beea0fb93f_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_abc31495eda36a292af84de2d2556c5a9957390958fb6f471694dbdb8b84ffff_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-161-awyl\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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**browser_tests** failed because of:\\n\\n- CheckSystemTokenAvailability/EnterprisePlatformKeysTest.Basic/0\\n\\n- FileDialog/FilesAppBrowserTest.Test/openFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/saveFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/saveFileDialogSingleFilterNoAcceptAll\\n\\n- ...1 more failure(s) (5 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607642097828757",
"status": "COMPLETED",
"status_changed_ts": "1607642098275987",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43622",
"builder:linux-chromeos-rel",
"buildset:commit/git/8643e471def277bdc69b1ac0ec9ae40a5d6afc50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8643e471def277bdc69b1ac0ec9ae40a5d6afc50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026688867620236688",
"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/8861258805580832416/+/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:5067c1102d567210",
"user_agent:luci-scheduler"
],
"updated_ts": "1607645418315465",
"url": "https://ci.chromium.org/b/8861258805580832416",
"utcnow_ts": "1607645419148203"
}
}
[00:10:19.158] Invocation finished in 55m32.190585815s with status FAILED