[22:29:44.638] New invocation is queued and will start shortly
[22:29:45.761] Starting the invocation (attempt 1)
[22:29:45.793] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:29:45.793] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:29:45.793] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021618271114521568",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"452c90af4c81e7d745ad272280cf8eb9bd1bb457\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021618271114521568",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/452c90af4c81e7d745ad272280cf8eb9bd1bb457",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/452c90af4c81e7d745ad272280cf8eb9bd1bb457",
"gitiles_ref:refs/heads/master"
]
}
[22:29:46.403] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612477785929630",
"id": "8856188209099711024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"452c90af4c81e7d745ad272280cf8eb9bd1bb457\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612477786278086",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45148",
"builder:linux-chromeos-rel",
"buildset:commit/git/452c90af4c81e7d745ad272280cf8eb9bd1bb457",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/452c90af4c81e7d745ad272280cf8eb9bd1bb457",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021618271114521568",
"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/8856188209099711024/+/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": "1612477786278216",
"url": "https://ci.chromium.org/b/8856188209099711024",
"utcnow_ts": "1612477786393894"
}
}
[22:29:46.403] Task URL: https://ci.chromium.org/b/8856188209099711024
[22:29:46.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:2:0) after 1m0s
[22:30:46.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:2:0)
[22:30:46.494] Timer tick, asking Buildbucket for the build status
[22:30:46.592] Build 8856188209099711024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:46.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:3:0) after 1m0s
[22:31:46.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:3:0)
[22:31:46.665] Timer tick, asking Buildbucket for the build status
[22:31:46.737] Build 8856188209099711024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:46.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:4:0) after 1m0s
[22:32:46.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:4:0)
[22:32:46.818] Timer tick, asking Buildbucket for the build status
[22:32:46.900] Build 8856188209099711024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:46.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:5:0) after 1m0s
[22:33:46.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:5:0)
[22:33:46.967] Timer tick, asking Buildbucket for the build status
[22:33:47.099] Build 8856188209099711024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:47.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:6:0) after 1m0s
[22:34:05.647] Received PubSub notification, asking Buildbucket for the build status
[22:34:05.813] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:47.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:6:0)
[22:34:47.186] Timer tick, asking Buildbucket for the build status
[22:34:47.341] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:47.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:8:0) after 1m0s
[22:35:47.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:8:0)
[22:35:47.494] Timer tick, asking Buildbucket for the build status
[22:35:47.611] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:47.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:9:0) after 1m0s
[22:36:47.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:9:0)
[22:36:47.844] Timer tick, asking Buildbucket for the build status
[22:36:48.886] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:48.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:10:0) after 1m0s
[22:37:49.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:10:0)
[22:37:49.085] Timer tick, asking Buildbucket for the build status
[22:37:49.157] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:49.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:11:0) after 1m0s
[22:38:49.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:11:0)
[22:38:49.340] Timer tick, asking Buildbucket for the build status
[22:38:49.398] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:49.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:12:0) after 1m0s
[22:39:49.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:12:0)
[22:39:49.480] Timer tick, asking Buildbucket for the build status
[22:39:49.911] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:49.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:13:0) after 1m0s
[22:40:50.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:13:0)
[22:40:50.186] Timer tick, asking Buildbucket for the build status
[22:40:50.295] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:50.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:14:0) after 1m0s
[22:41:50.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:14:0)
[22:41:50.429] Timer tick, asking Buildbucket for the build status
[22:41:50.519] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:50.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:15:0) after 1m0s
[22:42:50.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:15:0)
[22:42:50.707] Timer tick, asking Buildbucket for the build status
[22:42:50.815] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:50.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:16:0) after 1m0s
[22:43:50.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:16:0)
[22:43:50.916] Timer tick, asking Buildbucket for the build status
[22:43:51.002] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:51.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:17:0) after 1m0s
[22:44:51.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:17:0)
[22:44:51.062] Timer tick, asking Buildbucket for the build status
[22:44:51.466] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:51.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:18:0) after 1m0s
[22:45:51.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:18:0)
[22:45:51.584] Timer tick, asking Buildbucket for the build status
[22:45:51.735] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:51.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:19:0) after 1m0s
[22:46:51.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:19:0)
[22:46:51.797] Timer tick, asking Buildbucket for the build status
[22:46:51.923] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:51.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:20:0) after 1m0s
[22:47:52.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:20:0)
[22:47:52.362] Timer tick, asking Buildbucket for the build status
[22:47:52.503] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:52.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:21:0) after 1m0s
[22:48:52.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:21:0)
[22:48:52.587] Timer tick, asking Buildbucket for the build status
[22:48:52.651] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:52.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:22:0) after 1m0s
[22:49:52.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:22:0)
[22:49:52.830] Timer tick, asking Buildbucket for the build status
[22:49:52.927] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:52.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:23:0) after 1m0s
[22:50:53.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:23:0)
[22:50:53.084] Timer tick, asking Buildbucket for the build status
[22:50:53.291] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:53.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:24:0) after 1m0s
[22:51:53.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:24:0)
[22:51:53.357] Timer tick, asking Buildbucket for the build status
[22:51:53.438] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:53.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:25:0) after 1m0s
[22:52:53.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:25:0)
[22:52:53.532] Timer tick, asking Buildbucket for the build status
[22:52:53.593] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:53.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:26:0) after 1m0s
[22:53:53.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:26:0)
[22:53:53.675] Timer tick, asking Buildbucket for the build status
[22:53:53.791] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:53.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:27:0) after 1m0s
[22:54:53.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:27:0)
[22:54:53.915] Timer tick, asking Buildbucket for the build status
[22:54:54.162] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:54.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:28:0) after 1m0s
[22:55:54.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:28:0)
[22:55:54.242] Timer tick, asking Buildbucket for the build status
[22:55:54.331] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:54.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:29:0) after 1m0s
[22:56:54.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:29:0)
[22:56:54.374] Timer tick, asking Buildbucket for the build status
[22:56:54.599] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:54.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:30:0) after 1m0s
[22:57:54.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:30:0)
[22:57:54.646] Timer tick, asking Buildbucket for the build status
[22:57:54.712] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:54.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:31:0) after 1m0s
[22:58:54.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:31:0)
[22:58:54.819] Timer tick, asking Buildbucket for the build status
[22:58:55.011] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:55.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:32:0) after 1m0s
[22:59:55.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:32:0)
[22:59:55.026] Timer tick, asking Buildbucket for the build status
[22:59:55.147] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:55.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:33:0) after 1m0s
[23:00:55.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:33:0)
[23:00:55.327] Timer tick, asking Buildbucket for the build status
[23:00:55.404] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:55.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:34:0) after 1m0s
[23:01:55.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:34:0)
[23:01:55.429] Timer tick, asking Buildbucket for the build status
[23:01:55.548] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:55.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:35:0) after 1m0s
[23:02:55.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:35:0)
[23:02:55.580] Timer tick, asking Buildbucket for the build status
[23:02:56.618] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:56.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:36:0) after 1m0s
[23:03:56.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:36:0)
[23:03:56.684] Timer tick, asking Buildbucket for the build status
[23:03:56.765] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:56.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:37:0) after 1m0s
[23:04:56.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:37:0)
[23:04:56.823] Timer tick, asking Buildbucket for the build status
[23:04:56.944] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:56.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:38:0) after 1m0s
[23:05:57.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:38:0)
[23:05:57.084] Timer tick, asking Buildbucket for the build status
[23:05:57.239] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:57.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:39:0) after 1m0s
[23:06:57.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:39:0)
[23:06:57.398] Timer tick, asking Buildbucket for the build status
[23:06:57.601] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:57.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:40:0) after 1m0s
[23:07:57.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:40:0)
[23:07:57.602] Timer tick, asking Buildbucket for the build status
[23:07:57.734] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:57.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:41:0) after 1m0s
[23:08:57.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:41:0)
[23:08:57.757] Timer tick, asking Buildbucket for the build status
[23:08:58.116] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:58.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:42:0) after 1m0s
[23:09:58.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:42:0)
[23:09:58.201] Timer tick, asking Buildbucket for the build status
[23:09:58.612] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:58.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:43:0) after 1m0s
[23:10:58.629] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:43:0)
[23:10:58.629] Timer tick, asking Buildbucket for the build status
[23:10:58.755] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:58.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:44:0) after 1m0s
[23:11:58.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:44:0)
[23:11:58.842] Timer tick, asking Buildbucket for the build status
[23:11:59.043] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:59.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:45:0) after 1m0s
[23:12:59.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:45:0)
[23:12:59.245] Timer tick, asking Buildbucket for the build status
[23:12:59.310] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:59.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:46:0) after 1m0s
[23:13:59.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:46:0)
[23:13:59.436] Timer tick, asking Buildbucket for the build status
[23:13:59.510] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:59.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:47:0) after 1m0s
[23:14:59.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:47:0)
[23:14:59.551] Timer tick, asking Buildbucket for the build status
[23:14:59.668] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:59.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:48:0) after 1m0s
[23:15:59.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:48:0)
[23:15:59.780] Timer tick, asking Buildbucket for the build status
[23:15:59.838] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:59.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:49:0) after 1m0s
[23:16:59.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:49:0)
[23:16:59.915] Timer tick, asking Buildbucket for the build status
[23:17:00.133] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:00.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:50:0) after 1m0s
[23:18:00.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:50:0)
[23:18:00.157] Timer tick, asking Buildbucket for the build status
[23:18:00.314] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:00.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:51:0) after 1m0s
[23:19:00.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:51:0)
[23:19:00.339] Timer tick, asking Buildbucket for the build status
[23:19:00.430] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:00.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:52:0) after 1m0s
[23:20:01.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:52:0)
[23:20:01.092] Timer tick, asking Buildbucket for the build status
[23:20:01.418] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:01.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:53:0) after 1m0s
[23:21:01.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:53:0)
[23:21:01.589] Timer tick, asking Buildbucket for the build status
[23:21:01.727] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:01.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:54:0) after 1m0s
[23:22:01.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:54:0)
[23:22:01.857] Timer tick, asking Buildbucket for the build status
[23:22:01.990] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:01.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:55:0) after 1m0s
[23:23:02.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:55:0)
[23:23:02.025] Timer tick, asking Buildbucket for the build status
[23:23:02.203] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:02.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:56:0) after 1m0s
[23:24:02.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:56:0)
[23:24:02.203] Timer tick, asking Buildbucket for the build status
[23:24:02.261] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:02.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:57:0) after 1m0s
[23:25:02.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:57:0)
[23:25:02.288] Timer tick, asking Buildbucket for the build status
[23:25:03.100] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:03.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:58:0) after 1m0s
[23:26:03.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:58:0)
[23:26:03.135] Timer tick, asking Buildbucket for the build status
[23:26:03.609] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:03.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:59:0) after 1m0s
[23:27:03.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:59:0)
[23:27:03.664] Timer tick, asking Buildbucket for the build status
[23:27:03.734] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:03.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:60:0) after 1m0s
[23:28:03.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:60:0)
[23:28:03.827] Timer tick, asking Buildbucket for the build status
[23:28:03.938] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:03.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:61:0) after 1m0s
[23:29:03.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:61:0)
[23:29:03.947] Timer tick, asking Buildbucket for the build status
[23:29:04.017] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:04.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:62:0) after 1m0s
[23:30:04.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:62:0)
[23:30:04.106] Timer tick, asking Buildbucket for the build status
[23:30:04.424] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:04.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:63:0) after 1m0s
[23:31:04.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:63:0)
[23:31:04.772] Timer tick, asking Buildbucket for the build status
[23:31:04.897] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:04.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:64:0) after 1m0s
[23:32:04.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:64:0)
[23:32:04.950] Timer tick, asking Buildbucket for the build status
[23:32:05.132] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:05.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:65:0) after 1m0s
[23:33:05.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:65:0)
[23:33:05.194] Timer tick, asking Buildbucket for the build status
[23:33:05.248] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:05.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:66:0) after 1m0s
[23:34:05.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:66:0)
[23:34:05.257] Timer tick, asking Buildbucket for the build status
[23:34:05.340] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:05.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:67:0) after 1m0s
[23:35:05.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:67:0)
[23:35:05.406] Timer tick, asking Buildbucket for the build status
[23:35:05.569] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:05.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:68:0) after 1m0s
[23:36:05.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:68:0)
[23:36:05.731] Timer tick, asking Buildbucket for the build status
[23:36:06.094] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:06.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:69:0) after 1m0s
[23:37:06.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:69:0)
[23:37:06.127] Timer tick, asking Buildbucket for the build status
[23:37:06.191] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:06.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:70:0) after 1m0s
[23:38:06.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:70:0)
[23:38:06.242] Timer tick, asking Buildbucket for the build status
[23:38:06.509] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:06.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:71:0) after 1m0s
[23:39:06.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:71:0)
[23:39:06.616] Timer tick, asking Buildbucket for the build status
[23:39:06.683] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:06.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:72:0) after 1m0s
[23:40:06.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:72:0)
[23:40:06.783] Timer tick, asking Buildbucket for the build status
[23:40:06.906] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:06.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:73:0) after 1m0s
[23:41:07.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:73:0)
[23:41:07.190] Timer tick, asking Buildbucket for the build status
[23:41:07.267] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:07.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:74:0) after 1m0s
[23:42:07.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:74:0)
[23:42:07.387] Timer tick, asking Buildbucket for the build status
[23:42:07.451] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:07.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:75:0) after 1m0s
[23:43:07.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:75:0)
[23:43:07.486] Timer tick, asking Buildbucket for the build status
[23:43:07.565] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:07.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:76:0) after 1m0s
[23:44:07.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:76:0)
[23:44:07.557] Timer tick, asking Buildbucket for the build status
[23:44:07.645] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:07.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:77:0) after 1m0s
[23:45:07.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:77:0)
[23:45:07.736] Timer tick, asking Buildbucket for the build status
[23:45:07.940] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:07.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:78:0) after 1m0s
[23:46:07.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:78:0)
[23:46:07.959] Timer tick, asking Buildbucket for the build status
[23:46:08.052] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:08.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:79:0) after 1m0s
[23:47:08.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:79:0)
[23:47:08.045] Timer tick, asking Buildbucket for the build status
[23:47:08.137] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:08.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:80:0) after 1m0s
[23:48:08.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:80:0)
[23:48:08.141] Timer tick, asking Buildbucket for the build status
[23:48:08.197] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:08.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:81:0) after 1m0s
[23:49:08.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:81:0)
[23:49:08.224] Timer tick, asking Buildbucket for the build status
[23:49:08.284] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:08.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:82:0) after 1m0s
[23:50:08.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:82:0)
[23:50:08.403] Timer tick, asking Buildbucket for the build status
[23:50:09.021] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:09.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:83:0) after 1m0s
[23:51:09.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:83:0)
[23:51:09.089] Timer tick, asking Buildbucket for the build status
[23:51:09.161] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:09.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:84:0) after 1m0s
[23:52:09.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:84:0)
[23:52:09.291] Timer tick, asking Buildbucket for the build status
[23:52:09.401] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:09.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:85:0) after 1m0s
[23:53:09.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:85:0)
[23:53:09.411] Timer tick, asking Buildbucket for the build status
[23:53:09.642] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:09.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:86:0) after 1m0s
[23:54:09.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:86:0)
[23:54:09.700] Timer tick, asking Buildbucket for the build status
[23:54:09.794] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:09.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:87:0) after 1m0s
[23:55:09.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:87:0)
[23:55:09.839] Timer tick, asking Buildbucket for the build status
[23:55:10.532] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:10.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:88:0) after 1m0s
[23:56:10.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:88:0)
[23:56:10.560] Timer tick, asking Buildbucket for the build status
[23:56:10.692] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:10.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:89:0) after 1m0s
[23:57:10.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:89:0)
[23:57:10.699] Timer tick, asking Buildbucket for the build status
[23:57:10.815] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:10.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:90:0) after 1m0s
[23:58:10.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:90:0)
[23:58:10.868] Timer tick, asking Buildbucket for the build status
[23:58:11.004] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:11.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:91:0) after 1m0s
[23:59:11.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:91:0)
[23:59:11.051] Timer tick, asking Buildbucket for the build status
[23:59:11.192] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:11.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:92:0) after 1m0s
[00:00:11.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:92:0)
[00:00:11.278] Timer tick, asking Buildbucket for the build status
[00:00:11.683] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:11.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:93:0) after 1m0s
[00:01:13.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:93:0)
[00:01:13.472] Timer tick, asking Buildbucket for the build status
[00:01:13.636] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:13.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:94:0) after 1m0s
[00:02:13.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:94:0)
[00:02:13.763] Timer tick, asking Buildbucket for the build status
[00:02:13.911] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:13.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:95:0) after 1m0s
[00:03:14.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:95:0)
[00:03:14.059] Timer tick, asking Buildbucket for the build status
[00:03:14.596] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:14.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:96:0) after 1m0s
[00:04:14.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:96:0)
[00:04:14.680] Timer tick, asking Buildbucket for the build status
[00:04:15.098] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:15.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:97:0) after 1m0s
[00:05:15.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:97:0)
[00:05:15.173] Timer tick, asking Buildbucket for the build status
[00:05:15.377] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:15.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:98:0) after 1m0s
[00:06:15.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:98:0)
[00:06:15.427] Timer tick, asking Buildbucket for the build status
[00:06:15.586] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:15.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:99:0) after 1m0s
[00:07:15.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:99:0)
[00:07:15.656] Timer tick, asking Buildbucket for the build status
[00:07:15.786] Build 8856188209099711024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:15.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021618271114521568:100:0) after 1m0s
[00:07:43.666] Received PubSub notification, asking Buildbucket for the build status
[00:07:43.781] Build 8856188209099711024: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:07:43.781] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612483662524307",
"created_by": "project:chromium",
"created_ts": "1612477785929630",
"failure_reason": "BUILD_FAILURE",
"id": "8856188209099711024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"452c90af4c81e7d745ad272280cf8eb9bd1bb457\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TestAsNormalAndGuestUser/SpokenFeedbackTest.ChromeVoxNavigateAndSelect/1\"}, \"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\": \"452c90af4c81e7d745ad272280cf8eb9bd1bb457\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-11-1hhh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45148, \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"bdbf98afca9fba5ca863c2c963ab10edae45b1cc\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"452c90af4c81e7d745ad272280cf8eb9bd1bb457\", \"got_revision_cp\": \"refs/heads/master@{#850813}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"a71154a1ede8ce4ce90eb23ede108aef7e94691d\", \"got_v8_revision_cp\": \"refs/heads/9.0.133@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"452c90af4c81e7d745ad272280cf8eb9bd1bb457\", \"swarm_hashes_refs/heads/master(at){#850813}_without_patch\": {\"absl_hardening_tests\": \"d810faafda222eb37a64d589118572365d6af184\", \"accessibility_unittests\": \"92401ef25dd99283d796616d294219b409427526\", \"angle_unittests\": \"475454449104a651e933f937f3d9a468e6f442eb\", \"app_list_unittests\": \"f0759238e44f57665e1c8388eb1da416462e718e\", \"app_shell_unittests\": \"032fb3331213c6c82bd51b8719d76492daef68ea\", \"ash_unittests\": \"93d5a97894f1d3a499e631f52f7f5ebf35d5ba3c\", \"aura_unittests\": \"c9ae418b91af5ca9804e7fcea31d1a54893c84ab\", \"base_unittests\": \"221c02d7b2ab69986b9bce30925a2d542a156b85\", \"base_util_unittests\": \"0e7ccc62d49bc2480bc7c313e8c0ed912ff08405\", \"blink_common_unittests\": \"7757f14952c10c67e97668d5f821120c9bd38639\", \"blink_fuzzer_unittests\": \"e43e14015b8f78e85401ae9c79b677c443847ac6\", \"blink_heap_unittests\": \"616378050b0809f4c0bf957b6dac12b2adaa6816\", \"blink_platform_unittests\": \"7a04879c04fe1ea3b9700f2657f7438dc52884cf\", \"blink_unittests\": \"7cd16a7c77d425126726e01a2b7e7e6a9f151afd\", \"boringssl_crypto_tests\": \"283aa5da8dfd509cea31ca4167426c5eb6a46376\", \"boringssl_ssl_tests\": \"d9b8494c869dbe91d945e2a2eec501e65dec9f01\", \"browser_tests\": \"5102e11182c62f294bc3cda916c71afb11e03452\", \"capture_unittests\": \"1a6bef73c8a16a39ff70d7b8901558592d99a387\", \"cast_unittests\": \"65bade7e98752c80e9211b6f2b6f490f7f287154\", \"cc_unittests\": \"462f47d6304d8e98ffa886e79811d3dc3a462a92\", \"chrome_app_unittests\": \"2de0d76003f3aad6ce51dd5ac405e84afb465bca\", \"chromedriver_unittests\": \"234ad758a5839e8f9db483878bde5e361242b1b8\", \"chromeos_components_unittests\": \"62ab04ebbdd4cea85de03f3dc6608e3f8731816a\", \"chromeos_unittests\": \"cfa21e63ac9da7185523f9911eee7bfa65190e58\", \"components_browsertests\": \"f14e0e2b4c28c8f858949e7ef4dd08bf28658141\", \"components_unittests\": \"c7799e3431773489b300f6d24208d501ea1714d7\", \"compositor_unittests\": \"ecb96b3fe2d0bf4fa1fb2b0f3a8a84cf71d136f5\", \"content_browsertests\": \"10cf88cbfb15ad4b57b81325416184bc300aee44\", \"content_unittests\": \"76270b03e4397ad51430dd0774c4cb4fae4eb4df\", \"crashpad_tests\": \"04db6aedc4ca88fdc2740d4588d699aa51f2dd95\", \"crypto_unittests\": \"fa76d1d3d68c737d43ae59425777841409c1569e\", \"dbus_unittests\": \"0348b9c4facb2a743d8848baaaadc0b855e86a9d\", \"device_unittests\": \"2a6091031860f00d1af610edffcf0f4e6fc526c6\", \"display_unittests\": \"8294a1944f90b30555e01206429000ecf632ac3d\", \"events_unittests\": \"cff6462b390667ba825af428e1da518ded7342dc\", \"exo_unittests\": \"9ae9b759f3c771db998a3eac870cc117342ac92e\", \"extensions_browsertests\": \"a8f4955f89c12ffec7806a61ada46316024c6fbf\", \"extensions_unittests\": \"e29be9d86aea46cc21f3d4fe29cd10cf3bdde9b4\", \"filesystem_service_unittests\": \"59ff2df16f73ae3153e9f836e85631946b40b374\", \"gcm_unit_tests\": \"4d377f6843c15874fbd01cb5d081f4593011cad3\", \"gfx_unittests\": \"0fe7147639aeebf4228cff20f089aa968b84cd8d\", \"gin_unittests\": \"a2af085ca71e2a17a1fc6a17f3df13f80879d544\", \"gl_unittests_ozone\": \"68ecd98ba673181c01f5ca89b976470e37a66b3f\", \"google_apis_unittests\": \"8d622daed45715c3c44c2b270a30f7fbf6203ebc\", \"gpu_unittests\": \"6b07dfd3ce53c7423741643adb3f2bbd1ed81a0b\", \"gwp_asan_unittests\": \"f5dedd93ff1c2168478fb5f044c9cfdbf203d674\", \"interactive_ui_tests\": \"9eb1bf75ef76ae292ea0d0c30114d3b00a5ffb55\", \"ipc_tests\": \"c91ddbf87bc9677dbfaab6776617b98156d4dae4\", \"jingle_unittests\": \"67594fa388518ab5e0f70e40fb3add9aacc83afb\", \"keyboard_unittests\": \"31acc82fefa7ec92c3a45ece5b758b0e44be9a47\", \"latency_unittests\": \"43398f5459dec335fc5a1d4b7936f136ab993275\", \"libjingle_xmpp_unittests\": \"c65035349c803f041c0903388528f922eea06920\", \"liburlpattern_unittests\": \"1fbf5a44b87c43f41ac67d61581b63e251dae108\", \"media_blink_unittests\": \"9dc1e832e43b8c84bdbcecaa192dacee5d7be061\", \"media_unittests\": \"8e5efca9eb2fc1b7a657b1df07998033ab8d4447\", \"message_center_unittests\": \"839374b954a300555cf0b86bed100a430ad381f1\", \"midi_unittests\": \"d81a8c331149edf7eb271109b5a4906c0a99a5f9\", \"mojo_core_unittests\": \"8e4ba3f82f755c7c39fd0cdb32f89d844353a4ac\", \"mojo_unittests\": \"fda0ebcaf0b3d68160d9407de95f326f8340ce48\", \"nacl_helper_nonsfi_unittests\": \"7840496afb426daab51cae99d47e41bb4e6ad041\", \"nacl_loader_unittests\": \"6f2c5d769a86a2070df9832c0170a45ea63c27ff\", \"native_theme_unittests\": \"f07d2cec86a3e508fff5045628c4543f8f88b12a\", \"net_unittests\": \"b2f19fdf89cdfd6ce6e84c15bbe94f36635f760d\", \"ozone_gl_unittests\": \"9cd5e28d8540e89da0a926b48f472289859445cb\", \"ozone_unittests\": \"8fda073bbff22dea4d5d423377e3b06af464a6dd\", \"ozone_x11_unittests\": \"570bf1c14233b818e507a2c6be388d484e2a7f64\", \"pdf_unittests\": \"22884f4a91687d261eb3a1dc839ab6b825d83789\", \"perfetto_unittests\": \"32ccc9765a9121aff96f223cea4384b6046e4062\", \"ppapi_unittests\": \"87bca7a707bd6078786c5f6fe5d65ad7d0e828d4\", \"printing_unittests\": \"752336bcd30d31f0513bf475937d6052c9561520\", \"remoting_unittests\": \"42b213894eb072b47c122e674f74019d30aad55e\", \"sandbox_linux_unittests\": \"338f431768e13b1836fe4c35b7859537c7581a19\", \"service_manager_unittests\": \"ba3a04688fa3a64483d81f36841ec1a51e5c5613\", \"services_unittests\": \"dea0fc1d7de1ddf390223dfbe7ef39c338ebcf27\", \"shell_dialogs_unittests\": \"05138531be5018c34e47df2e5ab56ef5eb79f704\", \"shell_encryption_unittests\": \"34f3cfd9d8440934c27dbf30b005e342729a826f\", \"skia_unittests\": \"332eb91072a143251dd31133ad8aa99a45ed2257\", \"snapshot_unittests\": \"f252560a7d3b1b433f439c76a72aca9c08cf6c67\", \"sql_unittests\": \"0d82cc7ae5ca62361dfb40c9454eb9f3fd6e6893\", \"storage_unittests\": \"040caf38cde287ae53d4ffa127e5b4931c732886\", \"sync_integration_tests\": \"d2489a4384b4d6e3e176b62a06ed230f24f82cb7\", \"traffic_annotation_auditor_unittests\": \"b1112c140bb3de72e0caaaa8211decc8c2aebfb4\", \"ui_base_unittests\": \"cf1dc9ba6dd3b4ff55ce9aea58ddfb815cb0e25a\", \"ui_chromeos_unittests\": \"eb51a01e11935bb8cb134a3d8d8c7e6780a43657\", \"ui_touch_selection_unittests\": \"b66d8b282c29abc1b941e3def0c77d11d3983710\", \"unit_tests\": \"8ecf926028b029ca56057e88142b6c5c30234155\", \"url_unittests\": \"e3c46648d5917221901a502daadca9482e9feb25\", \"usage_time_limit_unittests\": \"cb1662a57739fd316b642e564eea3f7564da8397\", \"views_unittests\": \"6a31930b5028db6729797c217f51a7110f299437\", \"viz_unittests\": \"80851c2413ad4002655f0cdc61f4827dc6920ec4\", \"wayland_client_perftests\": \"ec00750cf6fe881d17872183246da3aec7bfb616\", \"wm_unittests\": \"bc0de15bca49aa6d5878f0c158cefc7fb632a74b\", \"wtf_unittests\": \"9b52aa948b985520a6f17dcae062c4a8589e33df\", \"zlib_unittests\": \"403d76a9541ec246b7f802fe1d1d23af278351b0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4026221a727cf2e856e94035b8eeb49c4c65d62e8974e693170551540c198531_v2\", \"builder_47c3ec4238d3b3d0448e41826f5b2c1435e77a4255b8c93aa27077a59a7a5a67_v2\", \"builder_b13f895f2fa532c56bcd54736f154d6ea330771f795358111cd0095a10831ab4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-11-1hhh\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TestAsNormalAndGuestUser/SpokenFeedbackTest.ChromeVoxNavigateAndSelect/1\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612478044709413",
"status": "COMPLETED",
"status_changed_ts": "1612478045198005",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45148",
"builder:linux-chromeos-rel",
"buildset:commit/git/452c90af4c81e7d745ad272280cf8eb9bd1bb457",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/452c90af4c81e7d745ad272280cf8eb9bd1bb457",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021618271114521568",
"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/8856188209099711024/+/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:5187fbf446b79410",
"user_agent:luci-scheduler"
],
"updated_ts": "1612483663032941",
"url": "https://ci.chromium.org/b/8856188209099711024",
"utcnow_ts": "1612483663772006"
}
}
[00:07:43.781] Invocation finished in 1h37m59.156924737s with status FAILED