[19:41:40.276] New invocation is queued and will start shortly
[19:41:41.353] Starting the invocation (attempt 1)
[19:41:41.389] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:41:41.389] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:41:41.389] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027336454219126768",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c846f9b8f31bd37101d496c67fa829078a5c3451\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9027336454219126768",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/c846f9b8f31bd37101d496c67fa829078a5c3451",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c846f9b8f31bd37101d496c67fa829078a5c3451",
"gitiles_ref:refs/heads/master"
]
}
[19:41:42.540] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607024501492787",
"id": "8861906392282232368",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c846f9b8f31bd37101d496c67fa829078a5c3451\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607024502218144",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43420",
"builder:linux-chromeos-rel",
"buildset:commit/git/c846f9b8f31bd37101d496c67fa829078a5c3451",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c846f9b8f31bd37101d496c67fa829078a5c3451",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027336454219126768",
"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/8861906392282232368/+/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": "1607024502219968",
"url": "https://ci.chromium.org/b/8861906392282232368",
"utcnow_ts": "1607024502521145"
}
}
[19:41:42.540] Task URL: https://ci.chromium.org/b/8861906392282232368
[19:41:42.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:2:0) after 1m0s
[19:42:42.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:2:0)
[19:42:42.572] Timer tick, asking Buildbucket for the build status
[19:42:42.717] Build 8861906392282232368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:42.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:3:0) after 1m0s
[19:43:42.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:3:0)
[19:43:42.788] Timer tick, asking Buildbucket for the build status
[19:43:43.133] Build 8861906392282232368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:43.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:4:0) after 1m0s
[19:44:43.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:4:0)
[19:44:43.164] Timer tick, asking Buildbucket for the build status
[19:44:43.552] Build 8861906392282232368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:43.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:5:0) after 1m0s
[19:45:43.578] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:5:0)
[19:45:43.578] Timer tick, asking Buildbucket for the build status
[19:45:43.821] Build 8861906392282232368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:43.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:6:0) after 1m0s
[19:46:13.548] Received PubSub notification, asking Buildbucket for the build status
[19:46:13.642] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:43.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:6:0)
[19:46:43.872] Timer tick, asking Buildbucket for the build status
[19:46:44.014] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:44.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:8:0) after 1m0s
[19:47:44.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:8:0)
[19:47:44.195] Timer tick, asking Buildbucket for the build status
[19:47:44.554] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:44.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:9:0) after 1m0s
[19:48:44.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:9:0)
[19:48:44.636] Timer tick, asking Buildbucket for the build status
[19:48:44.745] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:44.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:10:0) after 1m0s
[19:49:44.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:10:0)
[19:49:44.792] Timer tick, asking Buildbucket for the build status
[19:49:45.291] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:45.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:11:0) after 1m0s
[19:50:45.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:11:0)
[19:50:45.345] Timer tick, asking Buildbucket for the build status
[19:50:45.558] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:45.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:12:0) after 1m0s
[19:51:45.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:12:0)
[19:51:45.589] Timer tick, asking Buildbucket for the build status
[19:51:45.717] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:45.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:13:0) after 1m0s
[19:52:45.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:13:0)
[19:52:45.927] Timer tick, asking Buildbucket for the build status
[19:52:46.054] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:46.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:14:0) after 1m0s
[19:53:46.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:14:0)
[19:53:46.154] Timer tick, asking Buildbucket for the build status
[19:53:46.272] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:46.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:15:0) after 1m0s
[19:54:46.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:15:0)
[19:54:46.349] Timer tick, asking Buildbucket for the build status
[19:54:46.491] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:46.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:16:0) after 1m0s
[19:55:46.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:16:0)
[19:55:46.499] Timer tick, asking Buildbucket for the build status
[19:55:46.555] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:46.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:17:0) after 1m0s
[19:56:46.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:17:0)
[19:56:46.599] Timer tick, asking Buildbucket for the build status
[19:56:46.814] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:46.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:18:0) after 1m0s
[19:57:46.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:18:0)
[19:57:46.835] Timer tick, asking Buildbucket for the build status
[19:57:46.953] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:46.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:19:0) after 1m0s
[19:58:47.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:19:0)
[19:58:47.012] Timer tick, asking Buildbucket for the build status
[19:58:47.211] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:47.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:20:0) after 1m0s
[19:59:47.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:20:0)
[19:59:47.253] Timer tick, asking Buildbucket for the build status
[19:59:47.565] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:47.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:21:0) after 1m0s
[20:00:58.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:21:0)
[20:00:58.016] Timer tick, asking Buildbucket for the build status
[20:00:58.235] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:58.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:22:0) after 1m0s
[20:01:58.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:22:0)
[20:01:58.258] Timer tick, asking Buildbucket for the build status
[20:01:58.439] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:58.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:23:0) after 1m0s
[20:02:58.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:23:0)
[20:02:58.661] Timer tick, asking Buildbucket for the build status
[20:02:58.764] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:58.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:24:0) after 1m0s
[20:03:58.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:24:0)
[20:03:58.852] Timer tick, asking Buildbucket for the build status
[20:03:58.946] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:58.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:25:0) after 1m0s
[20:04:59.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:25:0)
[20:04:59.000] Timer tick, asking Buildbucket for the build status
[20:04:59.273] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:59.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:26:0) after 1m0s
[20:05:59.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:26:0)
[20:05:59.514] Timer tick, asking Buildbucket for the build status
[20:06:01.603] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:01.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:27:0) after 1m0s
[20:07:02.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:27:0)
[20:07:02.354] Timer tick, asking Buildbucket for the build status
[20:07:02.511] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:02.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:28:0) after 1m0s
[20:08:02.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:28:0)
[20:08:02.867] Timer tick, asking Buildbucket for the build status
[20:08:03.886] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:03.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:29:0) after 1m0s
[20:09:03.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:29:0)
[20:09:03.917] Timer tick, asking Buildbucket for the build status
[20:09:04.117] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:04.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:30:0) after 1m0s
[20:10:04.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:30:0)
[20:10:04.336] Timer tick, asking Buildbucket for the build status
[20:10:04.717] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:04.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:31:0) after 1m0s
[20:11:04.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:31:0)
[20:11:04.869] Timer tick, asking Buildbucket for the build status
[20:11:05.650] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:05.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:32:0) after 1m0s
[20:12:05.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:32:0)
[20:12:05.722] Timer tick, asking Buildbucket for the build status
[20:12:06.578] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:06.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:33:0) after 1m0s
[20:13:06.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:33:0)
[20:13:06.771] Timer tick, asking Buildbucket for the build status
[20:13:06.885] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:06.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:34:0) after 1m0s
[20:14:07.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:34:0)
[20:14:07.002] Timer tick, asking Buildbucket for the build status
[20:14:07.095] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:07.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:35:0) after 1m0s
[20:15:07.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:35:0)
[20:15:07.321] Timer tick, asking Buildbucket for the build status
[20:15:08.163] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:08.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:36:0) after 1m0s
[20:16:08.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:36:0)
[20:16:08.320] Timer tick, asking Buildbucket for the build status
[20:16:08.936] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:08.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:37:0) after 1m0s
[20:17:09.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:37:0)
[20:17:09.059] Timer tick, asking Buildbucket for the build status
[20:17:09.284] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:09.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:38:0) after 1m0s
[20:18:09.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:38:0)
[20:18:09.445] Timer tick, asking Buildbucket for the build status
[20:18:11.304] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:11.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:39:0) after 1m0s
[20:19:11.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:39:0)
[20:19:11.389] Timer tick, asking Buildbucket for the build status
[20:19:11.475] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:11.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:40:0) after 1m0s
[20:20:11.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:40:0)
[20:20:11.562] Timer tick, asking Buildbucket for the build status
[20:20:12.778] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:12.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:41:0) after 1m0s
[20:21:12.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:41:0)
[20:21:12.915] Timer tick, asking Buildbucket for the build status
[20:21:13.407] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:13.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:42:0) after 1m0s
[20:22:13.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:42:0)
[20:22:13.424] Timer tick, asking Buildbucket for the build status
[20:22:13.614] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:13.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:43:0) after 1m0s
[20:23:13.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:43:0)
[20:23:13.656] Timer tick, asking Buildbucket for the build status
[20:23:13.860] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:13.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:44:0) after 1m0s
[20:24:13.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:44:0)
[20:24:13.887] Timer tick, asking Buildbucket for the build status
[20:24:13.987] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:13.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:45:0) after 1m0s
[20:25:14.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:45:0)
[20:25:14.248] Timer tick, asking Buildbucket for the build status
[20:25:14.609] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:14.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:46:0) after 1m0s
[20:26:14.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:46:0)
[20:26:14.717] Timer tick, asking Buildbucket for the build status
[20:26:14.817] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:14.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:47:0) after 1m0s
[20:27:14.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:47:0)
[20:27:14.838] Timer tick, asking Buildbucket for the build status
[20:27:15.129] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:15.129] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:48:0) after 1m0s
[20:28:15.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:48:0)
[20:28:15.204] Timer tick, asking Buildbucket for the build status
[20:28:16.468] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:16.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:49:0) after 1m0s
[20:29:16.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:49:0)
[20:29:16.886] Timer tick, asking Buildbucket for the build status
[20:29:18.173] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:18.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:50:0) after 1m0s
[20:30:18.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:50:0)
[20:30:18.213] Timer tick, asking Buildbucket for the build status
[20:30:18.326] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:18.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:51:0) after 1m0s
[20:31:18.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:51:0)
[20:31:18.478] Timer tick, asking Buildbucket for the build status
[20:31:18.614] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:18.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:52:0) after 1m0s
[20:32:18.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:52:0)
[20:32:18.977] Timer tick, asking Buildbucket for the build status
[20:32:19.104] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:19.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:53:0) after 1m0s
[20:33:19.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:53:0)
[20:33:19.434] Timer tick, asking Buildbucket for the build status
[20:33:19.640] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:19.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:54:0) after 1m0s
[20:34:19.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:54:0)
[20:34:19.796] Timer tick, asking Buildbucket for the build status
[20:34:19.866] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:19.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:55:0) after 1m0s
[20:35:20.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:55:0)
[20:35:20.004] Timer tick, asking Buildbucket for the build status
[20:35:20.078] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:20.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:56:0) after 1m0s
[20:36:20.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:56:0)
[20:36:20.263] Timer tick, asking Buildbucket for the build status
[20:36:20.345] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:20.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:57:0) after 1m0s
[20:37:20.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:57:0)
[20:37:20.452] Timer tick, asking Buildbucket for the build status
[20:37:20.644] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:20.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:58:0) after 1m0s
[20:38:20.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:58:0)
[20:38:20.808] Timer tick, asking Buildbucket for the build status
[20:38:20.951] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:20.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:59:0) after 1m0s
[20:39:21.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:59:0)
[20:39:21.169] Timer tick, asking Buildbucket for the build status
[20:39:21.290] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:21.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:60:0) after 1m0s
[20:40:21.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:60:0)
[20:40:21.438] Timer tick, asking Buildbucket for the build status
[20:40:21.632] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:21.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:61:0) after 1m0s
[20:41:21.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:61:0)
[20:41:21.718] Timer tick, asking Buildbucket for the build status
[20:41:21.796] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:21.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:62:0) after 1m0s
[20:42:21.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:62:0)
[20:42:21.804] Timer tick, asking Buildbucket for the build status
[20:42:21.967] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:21.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:63:0) after 1m0s
[20:43:22.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:63:0)
[20:43:22.875] Timer tick, asking Buildbucket for the build status
[20:43:23.055] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:23.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:64:0) after 1m0s
[20:44:23.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:64:0)
[20:44:23.122] Timer tick, asking Buildbucket for the build status
[20:44:23.933] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:23.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:65:0) after 1m0s
[20:45:23.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:65:0)
[20:45:23.948] Timer tick, asking Buildbucket for the build status
[20:45:24.015] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:24.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:66:0) after 1m0s
[20:46:24.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:66:0)
[20:46:24.066] Timer tick, asking Buildbucket for the build status
[20:46:25.101] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:25.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:67:0) after 1m0s
[20:47:25.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:67:0)
[20:47:25.119] Timer tick, asking Buildbucket for the build status
[20:47:25.546] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:25.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:68:0) after 1m0s
[20:48:25.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:68:0)
[20:48:25.621] Timer tick, asking Buildbucket for the build status
[20:48:25.716] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:25.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:69:0) after 1m0s
[20:49:25.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:69:0)
[20:49:25.778] Timer tick, asking Buildbucket for the build status
[20:49:25.838] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:25.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:70:0) after 1m0s
[20:50:25.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:70:0)
[20:50:25.901] Timer tick, asking Buildbucket for the build status
[20:50:26.163] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:26.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:71:0) after 1m0s
[20:51:26.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:71:0)
[20:51:26.230] Timer tick, asking Buildbucket for the build status
[20:51:26.300] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:26.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:72:0) after 1m0s
[20:52:26.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:72:0)
[20:52:26.362] Timer tick, asking Buildbucket for the build status
[20:52:26.476] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:26.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:73:0) after 1m0s
[20:53:26.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:73:0)
[20:53:26.603] Timer tick, asking Buildbucket for the build status
[20:53:26.702] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:26.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:74:0) after 1m0s
[20:54:26.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:74:0)
[20:54:26.726] Timer tick, asking Buildbucket for the build status
[20:54:26.832] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:26.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:75:0) after 1m0s
[20:55:26.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:75:0)
[20:55:26.891] Timer tick, asking Buildbucket for the build status
[20:55:27.097] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:27.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:76:0) after 1m0s
[20:56:27.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:76:0)
[20:56:27.198] Timer tick, asking Buildbucket for the build status
[20:56:28.450] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:28.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:77:0) after 1m0s
[20:57:28.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:77:0)
[20:57:28.484] Timer tick, asking Buildbucket for the build status
[20:57:28.607] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:28.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:78:0) after 1m0s
[20:58:28.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:78:0)
[20:58:28.656] Timer tick, asking Buildbucket for the build status
[20:58:28.743] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:28.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:79:0) after 1m0s
[20:59:28.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:79:0)
[20:59:28.793] Timer tick, asking Buildbucket for the build status
[20:59:28.885] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:28.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:80:0) after 1m0s
[21:00:29.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:80:0)
[21:00:29.107] Timer tick, asking Buildbucket for the build status
[21:00:30.416] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:30.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:81:0) after 1m0s
[21:01:30.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:81:0)
[21:01:30.563] Timer tick, asking Buildbucket for the build status
[21:01:32.113] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:32.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:82:0) after 1m0s
[21:02:32.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:82:0)
[21:02:32.146] Timer tick, asking Buildbucket for the build status
[21:02:32.307] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:32.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:83:0) after 1m0s
[21:03:33.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:83:0)
[21:03:33.176] Timer tick, asking Buildbucket for the build status
[21:03:34.790] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:34.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:84:0) after 1m0s
[21:04:34.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:84:0)
[21:04:34.831] Timer tick, asking Buildbucket for the build status
[21:04:35.147] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:35.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:85:0) after 1m0s
[21:05:35.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:85:0)
[21:05:35.209] Timer tick, asking Buildbucket for the build status
[21:05:35.378] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:35.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:86:0) after 1m0s
[21:06:35.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:86:0)
[21:06:35.394] Timer tick, asking Buildbucket for the build status
[21:06:35.528] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:35.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:87:0) after 1m0s
[21:07:35.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:87:0)
[21:07:35.612] Timer tick, asking Buildbucket for the build status
[21:07:36.036] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:36.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:88:0) after 1m0s
[21:08:36.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:88:0)
[21:08:36.066] Timer tick, asking Buildbucket for the build status
[21:08:37.116] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:37.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:89:0) after 1m0s
[21:09:37.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:89:0)
[21:09:37.217] Timer tick, asking Buildbucket for the build status
[21:09:37.408] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:37.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:90:0) after 1m0s
[21:10:37.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:90:0)
[21:10:37.465] Timer tick, asking Buildbucket for the build status
[21:10:39.719] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:39.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:91:0) after 1m0s
[21:11:40.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:91:0)
[21:11:40.025] Timer tick, asking Buildbucket for the build status
[21:11:40.194] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:40.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:92:0) after 1m0s
[21:12:40.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:92:0)
[21:12:40.360] Timer tick, asking Buildbucket for the build status
[21:12:40.766] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:40.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:93:0) after 1m0s
[21:13:41.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:93:0)
[21:13:41.101] Timer tick, asking Buildbucket for the build status
[21:13:41.268] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:41.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:94:0) after 1m0s
[21:14:41.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:94:0)
[21:14:41.355] Timer tick, asking Buildbucket for the build status
[21:14:41.485] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:41.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:95:0) after 1m0s
[21:15:41.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:95:0)
[21:15:41.528] Timer tick, asking Buildbucket for the build status
[21:15:41.660] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:41.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:96:0) after 1m0s
[21:16:41.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:96:0)
[21:16:41.685] Timer tick, asking Buildbucket for the build status
[21:16:42.125] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:42.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:97:0) after 1m0s
[21:17:42.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:97:0)
[21:17:42.264] Timer tick, asking Buildbucket for the build status
[21:17:42.424] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:42.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:98:0) after 1m0s
[21:18:42.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:98:0)
[21:18:42.513] Timer tick, asking Buildbucket for the build status
[21:18:45.444] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:45.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:99:0) after 1m0s
[21:19:45.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:99:0)
[21:19:45.488] Timer tick, asking Buildbucket for the build status
[21:19:45.680] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:45.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:100:0) after 1m0s
[21:20:45.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:100:0)
[21:20:45.764] Timer tick, asking Buildbucket for the build status
[21:20:45.899] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:45.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:101:0) after 1m0s
[21:21:45.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:101:0)
[21:21:45.980] Timer tick, asking Buildbucket for the build status
[21:21:48.454] Build 8861906392282232368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:48.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027336454219126768:102:0) after 1m0s
[21:21:51.442] Received PubSub notification, asking Buildbucket for the build status
[21:21:52.754] Build 8861906392282232368: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:21:52.754] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607030510015158",
"created_by": "project:chromium",
"created_ts": "1607024501492787",
"failure_reason": "BUILD_FAILURE",
"id": "8861906392282232368",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c846f9b8f31bd37101d496c67fa829078a5c3451\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToFocusedElement\"}, \"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\": \"c846f9b8f31bd37101d496c67fa829078a5c3451\", \"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-211-4w8r\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43420, \"got_angle_revision\": \"19a302fca592e3825998b78b7d7d075c51f3c4f6\", \"got_dawn_revision\": \"5d8a071433779a3da828e5e8577be8258ef957c3\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"c846f9b8f31bd37101d496c67fa829078a5c3451\", \"got_revision_cp\": \"refs/heads/master@{#833398}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"a6237ff2be9be3c84f26f23ddcd6e15c857672ab\", \"got_v8_revision_cp\": \"refs/heads/8.9.93@{#1}\", \"got_webrtc_revision\": \"091617dda85bdca0329102c432ab287760e15e37\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32753}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c846f9b8f31bd37101d496c67fa829078a5c3451\", \"swarm_hashes_refs/heads/master(at){#833398}_without_patch\": {\"absl_hardening_tests\": \"68e23b284cf5440c6e9e99dd5f8f434bba3f947d\", \"accessibility_unittests\": \"980dca08c1aebc7029c03d95fccb7e25a3508143\", \"angle_unittests\": \"cc38d7c12e089804c0d18c438b3127d4ec450b6d\", \"app_list_unittests\": \"77d1a780e820358d552677ce8fc70d7f3d9d7696\", \"app_shell_unittests\": \"122c30b817c214e12744ecaa871096607e4a1e2b\", \"ash_unittests\": \"38564399d501de1a4a52e6da69f6051411571f74\", \"aura_unittests\": \"6056fd7a952d8b97612393caf1e0712352abc63e\", \"base_unittests\": \"94ba7b5aa5dbee2d58f05c387fd8187c07493540\", \"base_util_unittests\": \"ee0c219bf93d445f2d04b805d054d6ca244585e7\", \"blink_common_unittests\": \"6596454ec79666eb2da4638529c1eace0bb54cfd\", \"blink_fuzzer_unittests\": \"e1d192e67aa2236ab289eb8e6bbabaf5a59dfc8e\", \"blink_heap_unittests\": \"dc1a8b90f64e95afe04647818b07e5124350ceca\", \"blink_platform_unittests\": \"709e50a50608f386fadb2701ef2d6afbce0e7880\", \"blink_unittests\": \"361efab6438f3023e50f8849ff79344cdc3126cc\", \"boringssl_crypto_tests\": \"e3ee57a23466161309341583470f75c2937d9134\", \"boringssl_ssl_tests\": \"7a14b61163384f99b4ba8bbca9f1b485e9bd5cfb\", \"browser_tests\": \"63d5c327edaf3f47fa49f4c2599a64d511775355\", \"capture_unittests\": \"19cb61e544b4a0e5025f83228dcb2ecbe3dbaa5e\", \"cast_unittests\": \"3beaf2728656b96613a0fe6c46f99d4bd12a349d\", \"cc_unittests\": \"14d71da76ad4978d07d651dce20612611613a27e\", \"chrome_app_unittests\": \"a1299672f1595f3b9a4bfff788993def30c79031\", \"chromedriver_unittests\": \"313beea8603f7fdd8179b4760f47ef5f17337bd6\", \"chromeos_components_unittests\": \"118039d73c500c74299ec56b5289ce0e74881b7e\", \"chromeos_unittests\": \"9813163c0eaa28e424c7283de89d1b683e264399\", \"components_browsertests\": \"3a6b2115f8669688cbd27a05b99094eeb4f4fbde\", \"components_unittests\": \"d881cab89ff9785cd6bcf8bd2c8dd8aedb7b8585\", \"compositor_unittests\": \"71466776d2314220f06ebd2d4e33e6cf3a2a6252\", \"content_browsertests\": \"b84b65fbc447f37c07d31e7308fc3b83250dd904\", \"content_unittests\": \"f9f02f5a66d0a5d45b5ae4899d1cbf9a2cf0cb88\", \"crashpad_tests\": \"04254f771046f7d1ff695eaf92457b2b07cd3177\", \"crypto_unittests\": \"fd4c727b9f79585f3709e4f69ce682e34b25dc0a\", \"dbus_unittests\": \"fa87025582cb4ca7c6fe250d42111a1e86adc964\", \"device_unittests\": \"da9c547a2f6e17ad50a42203ff50f7af24a5be55\", \"display_unittests\": \"49d2b4c86a20b63f3ec1f560202f1f93569e7b23\", \"events_unittests\": \"b6860913e522e220a57cccabbef7204a25f8e3da\", \"exo_unittests\": \"d9c67e2ae6a705eee3a9010c8fa16e65ac81400a\", \"extensions_browsertests\": \"bbad48d13ec76bd9e1ebbd17274de00096b8bf84\", \"extensions_unittests\": \"c9401ee2dc0bcb5e4b3beff18238b151b1e1fdd2\", \"filesystem_service_unittests\": \"d17a964c9ce560fe5a6eb65db349f104659a2963\", \"gcm_unit_tests\": \"410ab9564da94249fb819779520a032c8f53d6fa\", \"gfx_unittests\": \"78e83488ba9b95f936f666a0549f78f800e57ade\", \"gin_unittests\": \"b76266e93bf8f48e5c7f4cd711f254c09dd75815\", \"gl_unittests_ozone\": \"a0d91f8da6ded62ccbbd6325e4606516bacc6bc2\", \"google_apis_unittests\": \"dda731d11a9793ba404a4bc179382dc9f8d8e4c4\", \"gpu_unittests\": \"6a857efddabfd61d23489509471c76266197acea\", \"gwp_asan_unittests\": \"4b473c4e9d49567ca7d046c5768c528b131d824e\", \"interactive_ui_tests\": \"d649230ea776df855348300a78137345005f90dc\", \"ipc_tests\": \"ff1fb9b31b0e5a987152df491ac9481fac08e025\", \"jingle_unittests\": \"5758e932b162652efd22b574105498084e3762e4\", \"keyboard_unittests\": \"45b735127a1efcef57b8257d7188e554eb4c1375\", \"latency_unittests\": \"e105f3d021033c36444106e0e31c6a9d855bbc07\", \"libjingle_xmpp_unittests\": \"713d682cb930ac28cd3f275e4bf4086fe441a504\", \"liburlpattern_unittests\": \"3465a6b620f66b5e90c7c7db0af350126cbe6198\", \"media_blink_unittests\": \"ab030e92aba1a4ac52a9a7b8b6c7e7cbf57c1b1b\", \"media_unittests\": \"75c1543544554e037787ff4842b719ecb6527372\", \"message_center_unittests\": \"ddd21a1e85558a9fcf14720aaaded60dceabd4d6\", \"midi_unittests\": \"13b71d6a6459e9a17f28d52916553c34e6d2b798\", \"mojo_core_unittests\": \"3ae28b783593e71453107f54f9ca98c386aaac59\", \"mojo_unittests\": \"2bb2f42c6700069391a12f3cf407054ccfa3b683\", \"nacl_helper_nonsfi_unittests\": \"7b10723119a08efcbe91f7f325f188c11b2b0f90\", \"nacl_loader_unittests\": \"7c1334de44e154e6e67482fcf15e1972f7bd7ddd\", \"native_theme_unittests\": \"5527d5c967b756af316e03f7a3dd5b8e32378305\", \"net_unittests\": \"7db2e53512364c219497b28619553e1d5e2d308a\", \"ozone_gl_unittests\": \"3fe519f71bf9366c611973040e80312c0805c455\", \"ozone_unittests\": \"36c0e9544643cfb14d839084c7222291aa25839e\", \"ozone_x11_unittests\": \"dd473930703cd6f955d98bfc215231879f6b0831\", \"pdf_unittests\": \"0658f803bc13beaf8fe518ad07ee1892fe4c3d0e\", \"perfetto_unittests\": \"9ca972ac4225d0ca2813e917c9d7eeb4c7f83e18\", \"ppapi_unittests\": \"e908c837af2477e5261a92e5e013bb03ce7f5d82\", \"printing_unittests\": \"1ac455432769adb8e47b64fd3484ccb81835041f\", \"remoting_unittests\": \"5c10234af2ec746fee9c0bb2b4d3ed7ba487b802\", \"sandbox_linux_unittests\": \"74f8a16d1df19807c128350006efd74e5f8da648\", \"service_manager_unittests\": \"2c31a00335bb19a62a20f826b38555d80c41a868\", \"services_unittests\": \"62d1d8233d006f73f68236f5fc2ebd6d10d9d111\", \"shell_dialogs_unittests\": \"008a95d8a599dbe87017124a484794520a43eed7\", \"shell_encryption_unittests\": \"4ce2e55af885ce52e72d110cbb9672aa9f93dd8a\", \"skia_unittests\": \"f11531d5721951a4496ef392d6d3dd228ba570e2\", \"snapshot_unittests\": \"959ac18114704432bf36d6a6ff3e98cb9efe17cf\", \"sql_unittests\": \"c0a1576a50d1030dadf0cc7dbbed4c2ef4bb329d\", \"storage_unittests\": \"d8499939dd0ee40f859099a8a6c111da79ead05e\", \"sync_integration_tests\": \"d3957396e42c53eae411d904d31d75e2f7ee04a2\", \"traffic_annotation_auditor_unittests\": \"7f0c405838296d20179dd02a6805999907361c9f\", \"ui_base_unittests\": \"c1784db12fde977d1f0f918134ac89d08110fe2b\", \"ui_chromeos_unittests\": \"d81d8fb41f8805c8dc92f470be5bc31c273e486d\", \"ui_touch_selection_unittests\": \"46a8ae0fcd1439812c850872b01978207792b743\", \"unit_tests\": \"4a6215af5c5a252607de56d74a17d147ce9f43b6\", \"url_unittests\": \"4c43500b5af257af2b1f2113aa73a28c7b85776c\", \"usage_time_limit_unittests\": \"60786a3e2a97a27ac48dd2b70f3a1c0454e77d52\", \"views_unittests\": \"98ad476a61be9948d390981486c00e51707aca22\", \"viz_unittests\": \"5d9db532cc95e783195558911831186c87630416\", \"wayland_client_perftests\": \"9e32b8414fabed4c0a9999fd41c885c60ed62c94\", \"wm_unittests\": \"73967269df2b66ab52d3ae24a89fdc0e0514a507\", \"wtf_unittests\": \"1c06cf1c6ee5cb7cf7ba52a27db21c4f066f9f2a\", \"zlib_unittests\": \"7af2c3cc5af5673d1f440a3618a02a3364f6ca93\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_863d9d6073a39cfd51a17e8c0be606c54177e8c90f2c9a7c0e7333e92d562081_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_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-211-4w8r\"], \"image\": [\"chrome-xenial-20112200-f7cdea8df03\"], \"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\": [\"5640-d0eb544\"], \"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- MagnifierE2ETest.MovesScreenMagnifierToFocusedElement\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607024771447398",
"status": "COMPLETED",
"status_changed_ts": "1607024772501329",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43420",
"builder:linux-chromeos-rel",
"buildset:commit/git/c846f9b8f31bd37101d496c67fa829078a5c3451",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c846f9b8f31bd37101d496c67fa829078a5c3451",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027336454219126768",
"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/8861906392282232368/+/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:5042f170a339a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1607030510562639",
"url": "https://ci.chromium.org/b/8861906392282232368",
"utcnow_ts": "1607030512738881"
}
}
[21:21:52.754] Invocation finished in 1h40m12.493731328s with status FAILED