[15:58:10.192] New invocation is queued and will start shortly
[15:58:11.343] Starting the invocation (attempt 1)
[15:58:11.387] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:58:11.387] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:58:11.387] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019740370533967408",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6ae002709a6e0df5f61428d962e44a62920e76e1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019740370533967408",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/6ae002709a6e0df5f61428d962e44a62920e76e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ae002709a6e0df5f61428d962e44a62920e76e1",
"gitiles_ref:refs/heads/master"
]
}
[15:58:13.015] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614268691584911",
"id": "8854310308411418736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614268692681168",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45728",
"builder:linux-chromeos-rel",
"buildset:commit/git/6ae002709a6e0df5f61428d962e44a62920e76e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ae002709a6e0df5f61428d962e44a62920e76e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019740370533967408",
"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/8854310308411418736/+/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": "1614268692681284",
"url": "https://ci.chromium.org/b/8854310308411418736",
"utcnow_ts": "1614268692987820"
}
}
[15:58:13.015] Task URL: https://ci.chromium.org/b/8854310308411418736
[15:58:13.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:2:0) after 1m0s
[15:59:13.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:2:0)
[15:59:13.058] Timer tick, asking Buildbucket for the build status
[15:59:13.162] Build 8854310308411418736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:59:13.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:3:0) after 1m0s
[16:00:13.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:3:0)
[16:00:13.188] Timer tick, asking Buildbucket for the build status
[16:00:13.355] Build 8854310308411418736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:00:13.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:4:0) after 1m0s
[16:01:13.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:4:0)
[16:01:13.465] Timer tick, asking Buildbucket for the build status
[16:01:13.547] Build 8854310308411418736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:01:13.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:5:0) after 1m0s
[16:02:13.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:5:0)
[16:02:13.600] Timer tick, asking Buildbucket for the build status
[16:02:13.878] Build 8854310308411418736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:02:13.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:6:0) after 1m0s
[16:03:07.546] Received PubSub notification, asking Buildbucket for the build status
[16:03:07.658] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:13.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:6:0)
[16:03:13.896] Timer tick, asking Buildbucket for the build status
[16:03:13.973] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:13.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:8:0) after 1m0s
[16:04:13.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:8:0)
[16:04:13.983] Timer tick, asking Buildbucket for the build status
[16:04:14.117] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:14.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:9:0) after 1m0s
[16:05:14.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:9:0)
[16:05:14.132] Timer tick, asking Buildbucket for the build status
[16:05:14.470] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:14.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:10:0) after 1m0s
[16:06:14.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:10:0)
[16:06:14.559] Timer tick, asking Buildbucket for the build status
[16:06:14.651] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:14.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:11:0) after 1m0s
[16:07:14.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:11:0)
[16:07:14.667] Timer tick, asking Buildbucket for the build status
[16:07:14.748] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:14.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:12:0) after 1m0s
[16:08:14.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:12:0)
[16:08:14.768] Timer tick, asking Buildbucket for the build status
[16:08:14.940] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:14.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:13:0) after 1m0s
[16:09:14.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:13:0)
[16:09:14.971] Timer tick, asking Buildbucket for the build status
[16:09:15.049] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:15.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:14:0) after 1m0s
[16:10:15.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:14:0)
[16:10:15.185] Timer tick, asking Buildbucket for the build status
[16:10:15.268] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:15.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:15:0) after 1m0s
[16:11:15.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:15:0)
[16:11:15.423] Timer tick, asking Buildbucket for the build status
[16:11:15.615] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:15.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:16:0) after 1m0s
[16:12:15.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:16:0)
[16:12:15.680] Timer tick, asking Buildbucket for the build status
[16:12:15.755] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:15.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:17:0) after 1m0s
[16:13:15.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:17:0)
[16:13:15.884] Timer tick, asking Buildbucket for the build status
[16:13:15.959] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:15.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:18:0) after 1m0s
[16:14:15.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:18:0)
[16:14:15.980] Timer tick, asking Buildbucket for the build status
[16:14:16.056] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:16.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:19:0) after 1m0s
[16:15:16.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:19:0)
[16:15:16.083] Timer tick, asking Buildbucket for the build status
[16:15:16.246] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:16.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:20:0) after 1m0s
[16:16:16.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:20:0)
[16:16:16.278] Timer tick, asking Buildbucket for the build status
[16:16:16.436] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:16.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:21:0) after 1m0s
[16:17:16.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:21:0)
[16:17:16.474] Timer tick, asking Buildbucket for the build status
[16:17:16.679] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:16.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:22:0) after 1m0s
[16:18:16.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:22:0)
[16:18:16.688] Timer tick, asking Buildbucket for the build status
[16:18:16.762] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:16.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:23:0) after 1m0s
[16:19:16.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:23:0)
[16:19:16.823] Timer tick, asking Buildbucket for the build status
[16:19:16.914] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:16.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:24:0) after 1m0s
[16:20:17.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:24:0)
[16:20:17.034] Timer tick, asking Buildbucket for the build status
[16:20:17.156] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:17.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:25:0) after 1m0s
[16:21:17.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:25:0)
[16:21:17.297] Timer tick, asking Buildbucket for the build status
[16:21:17.392] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:17.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:26:0) after 1m0s
[16:22:17.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:26:0)
[16:22:17.484] Timer tick, asking Buildbucket for the build status
[16:22:17.561] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:17.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:27:0) after 1m0s
[16:23:17.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:27:0)
[16:23:17.631] Timer tick, asking Buildbucket for the build status
[16:23:17.768] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:17.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:28:0) after 1m0s
[16:24:17.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:28:0)
[16:24:17.845] Timer tick, asking Buildbucket for the build status
[16:24:18.071] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:18.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:29:0) after 1m0s
[16:25:18.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:29:0)
[16:25:18.097] Timer tick, asking Buildbucket for the build status
[16:25:18.357] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:18.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:30:0) after 1m0s
[16:26:18.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:30:0)
[16:26:18.400] Timer tick, asking Buildbucket for the build status
[16:26:18.475] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:18.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:31:0) after 1m0s
[16:27:18.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:31:0)
[16:27:18.494] Timer tick, asking Buildbucket for the build status
[16:27:18.582] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:18.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:32:0) after 1m0s
[16:28:18.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:32:0)
[16:28:18.601] Timer tick, asking Buildbucket for the build status
[16:28:18.674] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:18.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:33:0) after 1m0s
[16:29:18.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:33:0)
[16:29:18.732] Timer tick, asking Buildbucket for the build status
[16:29:18.805] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:18.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:34:0) after 1m0s
[16:30:18.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:34:0)
[16:30:18.880] Timer tick, asking Buildbucket for the build status
[16:30:18.970] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:18.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:35:0) after 1m0s
[16:31:18.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:35:0)
[16:31:18.990] Timer tick, asking Buildbucket for the build status
[16:31:19.066] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:19.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:36:0) after 1m0s
[16:32:19.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:36:0)
[16:32:19.089] Timer tick, asking Buildbucket for the build status
[16:32:19.262] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:19.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:37:0) after 1m0s
[16:33:19.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:37:0)
[16:33:19.338] Timer tick, asking Buildbucket for the build status
[16:33:19.704] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:19.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:38:0) after 1m0s
[16:34:19.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:38:0)
[16:34:19.779] Timer tick, asking Buildbucket for the build status
[16:34:19.869] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:19.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:39:0) after 1m0s
[16:35:20.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:39:0)
[16:35:20.104] Timer tick, asking Buildbucket for the build status
[16:35:20.203] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:20.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:40:0) after 1m0s
[16:36:20.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:40:0)
[16:36:20.278] Timer tick, asking Buildbucket for the build status
[16:36:21.018] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:21.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:41:0) after 1m0s
[16:37:21.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:41:0)
[16:37:21.077] Timer tick, asking Buildbucket for the build status
[16:37:21.596] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:21.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:42:0) after 1m0s
[16:38:21.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:42:0)
[16:38:21.654] Timer tick, asking Buildbucket for the build status
[16:38:22.159] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:22.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:43:0) after 1m0s
[16:39:22.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:43:0)
[16:39:22.237] Timer tick, asking Buildbucket for the build status
[16:39:22.369] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:22.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:44:0) after 1m0s
[16:40:22.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:44:0)
[16:40:22.463] Timer tick, asking Buildbucket for the build status
[16:40:22.543] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:22.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:45:0) after 1m0s
[16:41:22.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:45:0)
[16:41:22.614] Timer tick, asking Buildbucket for the build status
[16:41:22.739] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:22.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:46:0) after 1m0s
[16:42:22.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:46:0)
[16:42:22.758] Timer tick, asking Buildbucket for the build status
[16:42:22.855] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:22.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:47:0) after 1m0s
[16:43:22.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:47:0)
[16:43:22.944] Timer tick, asking Buildbucket for the build status
[16:43:23.051] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:23.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:48:0) after 1m0s
[16:44:23.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:48:0)
[16:44:23.083] Timer tick, asking Buildbucket for the build status
[16:44:23.199] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:23.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:49:0) after 1m0s
[16:45:23.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:49:0)
[16:45:23.184] Timer tick, asking Buildbucket for the build status
[16:45:23.346] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:23.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:50:0) after 1m0s
[16:46:23.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:50:0)
[16:46:23.365] Timer tick, asking Buildbucket for the build status
[16:46:23.432] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:23.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:51:0) after 1m0s
[16:47:23.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:51:0)
[16:47:23.447] Timer tick, asking Buildbucket for the build status
[16:47:23.517] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:23.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:52:0) after 1m0s
[16:48:23.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:52:0)
[16:48:23.548] Timer tick, asking Buildbucket for the build status
[16:48:24.028] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:24.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:53:0) after 1m0s
[16:49:24.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:53:0)
[16:49:24.046] Timer tick, asking Buildbucket for the build status
[16:49:24.174] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:24.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:54:0) after 1m0s
[16:50:24.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:54:0)
[16:50:24.193] Timer tick, asking Buildbucket for the build status
[16:50:24.264] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:24.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:55:0) after 1m0s
[16:51:24.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:55:0)
[16:51:24.358] Timer tick, asking Buildbucket for the build status
[16:51:25.187] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:25.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:56:0) after 1m0s
[16:52:25.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:56:0)
[16:52:25.161] Timer tick, asking Buildbucket for the build status
[16:52:25.438] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:25.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:57:0) after 1m0s
[16:53:25.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:57:0)
[16:53:25.432] Timer tick, asking Buildbucket for the build status
[16:53:25.514] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:25.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:58:0) after 1m0s
[16:54:25.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:58:0)
[16:54:25.586] Timer tick, asking Buildbucket for the build status
[16:54:25.660] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:25.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:59:0) after 1m0s
[16:55:25.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:59:0)
[16:55:25.913] Timer tick, asking Buildbucket for the build status
[16:55:26.009] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:26.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:60:0) after 1m0s
[16:56:26.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:60:0)
[16:56:26.138] Timer tick, asking Buildbucket for the build status
[16:56:26.294] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:26.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:61:0) after 1m0s
[16:57:26.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:61:0)
[16:57:26.367] Timer tick, asking Buildbucket for the build status
[16:57:26.484] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:26.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:62:0) after 1m0s
[16:58:26.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:62:0)
[16:58:26.541] Timer tick, asking Buildbucket for the build status
[16:58:26.693] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:26.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:63:0) after 1m0s
[16:59:26.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:63:0)
[16:59:26.746] Timer tick, asking Buildbucket for the build status
[16:59:26.820] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:26.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:64:0) after 1m0s
[17:00:26.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:64:0)
[17:00:26.899] Timer tick, asking Buildbucket for the build status
[17:00:27.045] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:27.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:65:0) after 1m0s
[17:01:27.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:65:0)
[17:01:27.079] Timer tick, asking Buildbucket for the build status
[17:01:27.243] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:27.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:66:0) after 1m0s
[17:02:27.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:66:0)
[17:02:27.275] Timer tick, asking Buildbucket for the build status
[17:02:27.360] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:27.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:67:0) after 1m0s
[17:03:27.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:67:0)
[17:03:27.387] Timer tick, asking Buildbucket for the build status
[17:03:27.615] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:27.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:68:0) after 1m0s
[17:04:27.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:68:0)
[17:04:27.759] Timer tick, asking Buildbucket for the build status
[17:04:28.171] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:28.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:69:0) after 1m0s
[17:05:28.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:69:0)
[17:05:28.248] Timer tick, asking Buildbucket for the build status
[17:05:29.106] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:29.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:70:0) after 1m0s
[17:06:29.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:70:0)
[17:06:29.126] Timer tick, asking Buildbucket for the build status
[17:06:29.365] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:29.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:71:0) after 1m0s
[17:07:29.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:71:0)
[17:07:29.400] Timer tick, asking Buildbucket for the build status
[17:07:29.549] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:29.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:72:0) after 1m0s
[17:08:29.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:72:0)
[17:08:29.620] Timer tick, asking Buildbucket for the build status
[17:08:29.715] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:29.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:73:0) after 1m0s
[17:09:29.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:73:0)
[17:09:29.760] Timer tick, asking Buildbucket for the build status
[17:09:29.851] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:29.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:74:0) after 1m0s
[17:10:29.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:74:0)
[17:10:29.875] Timer tick, asking Buildbucket for the build status
[17:10:29.999] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:29.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:75:0) after 1m0s
[17:11:30.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:75:0)
[17:11:30.079] Timer tick, asking Buildbucket for the build status
[17:11:30.199] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:30.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:76:0) after 1m0s
[17:12:30.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:76:0)
[17:12:30.254] Timer tick, asking Buildbucket for the build status
[17:12:30.531] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:30.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:77:0) after 1m0s
[17:13:30.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:77:0)
[17:13:30.572] Timer tick, asking Buildbucket for the build status
[17:13:30.657] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:30.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:78:0) after 1m0s
[17:14:30.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:78:0)
[17:14:30.779] Timer tick, asking Buildbucket for the build status
[17:14:30.990] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:30.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:79:0) after 1m0s
[17:15:31.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:79:0)
[17:15:31.042] Timer tick, asking Buildbucket for the build status
[17:15:31.115] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:31.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:80:0) after 1m0s
[17:16:31.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:80:0)
[17:16:31.168] Timer tick, asking Buildbucket for the build status
[17:16:31.243] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:31.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:81:0) after 1m0s
[17:17:31.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:81:0)
[17:17:31.271] Timer tick, asking Buildbucket for the build status
[17:17:31.378] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:31.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:82:0) after 1m0s
[17:18:31.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:82:0)
[17:18:31.504] Timer tick, asking Buildbucket for the build status
[17:18:31.665] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:31.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:83:0) after 1m0s
[17:19:31.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:83:0)
[17:19:31.758] Timer tick, asking Buildbucket for the build status
[17:19:31.842] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:31.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:84:0) after 1m0s
[17:20:31.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:84:0)
[17:20:31.873] Timer tick, asking Buildbucket for the build status
[17:20:32.101] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:32.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:85:0) after 1m0s
[17:21:32.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:85:0)
[17:21:32.182] Timer tick, asking Buildbucket for the build status
[17:21:32.463] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:32.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:86:0) after 1m0s
[17:22:32.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:86:0)
[17:22:32.528] Timer tick, asking Buildbucket for the build status
[17:22:32.902] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:32.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:87:0) after 1m0s
[17:23:32.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:87:0)
[17:23:32.995] Timer tick, asking Buildbucket for the build status
[17:23:33.097] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:33.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:88:0) after 1m0s
[17:24:33.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:88:0)
[17:24:33.238] Timer tick, asking Buildbucket for the build status
[17:24:33.479] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:33.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:89:0) after 1m0s
[17:25:33.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:89:0)
[17:25:33.548] Timer tick, asking Buildbucket for the build status
[17:25:33.624] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:33.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:90:0) after 1m0s
[17:26:33.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:90:0)
[17:26:33.614] Timer tick, asking Buildbucket for the build status
[17:26:34.316] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:34.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:91:0) after 1m0s
[17:27:34.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:91:0)
[17:27:34.424] Timer tick, asking Buildbucket for the build status
[17:27:34.515] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:34.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:92:0) after 1m0s
[17:28:34.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:92:0)
[17:28:34.662] Timer tick, asking Buildbucket for the build status
[17:28:34.761] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:34.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:93:0) after 1m0s
[17:29:34.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:93:0)
[17:29:34.830] Timer tick, asking Buildbucket for the build status
[17:29:34.976] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:34.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:94:0) after 1m0s
[17:30:35.098] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:94:0)
[17:30:35.098] Timer tick, asking Buildbucket for the build status
[17:30:35.371] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:35.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:95:0) after 1m0s
[17:31:35.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:95:0)
[17:31:35.442] Timer tick, asking Buildbucket for the build status
[17:31:35.725] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:35.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:96:0) after 1m0s
[17:32:35.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:96:0)
[17:32:35.780] Timer tick, asking Buildbucket for the build status
[17:32:36.053] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:36.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:97:0) after 1m0s
[17:33:36.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:97:0)
[17:33:36.084] Timer tick, asking Buildbucket for the build status
[17:33:36.194] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:36.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:98:0) after 1m0s
[17:34:36.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:98:0)
[17:34:36.329] Timer tick, asking Buildbucket for the build status
[17:34:36.615] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:36.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:99:0) after 1m0s
[17:35:36.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:99:0)
[17:35:36.737] Timer tick, asking Buildbucket for the build status
[17:35:37.886] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:37.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:100:0) after 1m0s
[17:36:39.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:100:0)
[17:36:39.733] Timer tick, asking Buildbucket for the build status
[17:36:39.917] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:39.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:101:0) after 1m0s
[17:37:40.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:101:0)
[17:37:40.210] Timer tick, asking Buildbucket for the build status
[17:37:40.305] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:40.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:102:0) after 1m0s
[17:38:40.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:102:0)
[17:38:40.492] Timer tick, asking Buildbucket for the build status
[17:38:40.593] Build 8854310308411418736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:40.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019740370533967408:103:0) after 1m0s
[17:38:51.118] Received PubSub notification, asking Buildbucket for the build status
[17:38:51.215] Build 8854310308411418736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:38:51.215] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614274730452702",
"created_by": "project:chromium",
"created_ts": "1614268691584911",
"failure_reason": "BUILD_FAILURE",
"id": "8854310308411418736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- CommerceHintAgentTest.CartPriority\"}, \"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\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\", \"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-66-e7rm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45728, \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\", \"got_revision_cp\": \"refs/heads/master@{#857676}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd5127b11e04b7f49031b2412625c36e4a86c3da\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33340}\", \"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\": \"6ae002709a6e0df5f61428d962e44a62920e76e1\", \"swarm_hashes_refs/heads/master(at){#857676}_without_patch\": {\"absl_hardening_tests\": \"5fc0ad562c99899cf31c9f76eb5747976d65aa3b\", \"accessibility_unittests\": \"6277c0fb91a38d67df6c90ab915aef5731b03eda\", \"angle_unittests\": \"483b93cda1819bb4ba48281c711b6e59b49a9af8\", \"app_list_unittests\": \"af20a7484c271b1fe37d6c3e22828d4fca94a61a\", \"app_shell_unittests\": \"b69b438a831a28e140c0368603ad66b9d634efa8\", \"ash_unittests\": \"d71fb10d6747e95b77af64a544df3b28933e0a79\", \"aura_unittests\": \"bebae30c4fad9b040e29fa199189f0bbfa43a25d\", \"base_unittests\": \"96bcd5c3fa103b5ad034f569aa34c6e91897e68e\", \"base_util_unittests\": \"0d6421149bef8b60ab7ef933a3bb5ab899e42f27\", \"blink_common_unittests\": \"0640e33f672d7ecc57be4f37e214f33f85dc50ac\", \"blink_fuzzer_unittests\": \"7d2009b1d5117f867f2f873738d0d5da0557cd68\", \"blink_heap_unittests\": \"54340990972873c4ab514777ac66dcc736f84215\", \"blink_platform_unittests\": \"3459d29e2ed8cf5fa0d5aded4d917f1f294baebe\", \"blink_unittests\": \"c27b33a53301ce41e45b79a156faaccb92a4e690\", \"boringssl_crypto_tests\": \"afa394d2a893d11bd4981df06160eac4c4cb5ad5\", \"boringssl_ssl_tests\": \"ee698c33a9ce4944886a562721d9fd8b353b79ff\", \"browser_tests\": \"69d930208b7124b70246b55f56d5e4504caf9268\", \"capture_unittests\": \"48c8d0f80b765950077c837f2fcc29d97e2c85ea\", \"cast_unittests\": \"6e9f2636346739a2a3377d3609816a167beb9b9e\", \"cc_unittests\": \"d0e5a43b90683625d6d83978c511c243a8e2882b\", \"chrome_app_unittests\": \"09073f34d3091e47e97edd8b43d648b982711141\", \"chromedriver_unittests\": \"fabf6127f96a6164e1bf51edcb1c9cba55c9fae6\", \"chromeos_components_unittests\": \"6d72c6ff1b5c28b973222fe2776b5e67794ac4ef\", \"chromeos_unittests\": \"a2663ca6f2fcc9b25c6abf90bfea790f4af7f581\", \"color_unittests\": \"525c54b6974c1564efc87ff5354cd7c50487cfff\", \"components_browsertests\": \"2cd4a6b70832bb23f0d3699866cfc6e46543312d\", \"components_unittests\": \"f8a74ec56d8d8e0c7c97c322d260ef798c0ce7f5\", \"compositor_unittests\": \"a8921732c1be73badd00b7dda2d281304d9c298d\", \"content_browsertests\": \"cc08e37200d5fc0f4c841b20a6c16eaf36471a38\", \"content_unittests\": \"2e76ed39bc93263ca863addf77baa04122ad5406\", \"crashpad_tests\": \"07dda6507a318a918fb9bbd6d5b492777498e23a\", \"crypto_unittests\": \"aa8832f5f3a0692855ce196dafb1a67d8b9b6abb\", \"dbus_unittests\": \"399bfde8113b564b01ba458c6e80f13a27f5a798\", \"device_unittests\": \"4585dc9d330adcec6414fe177b264c0f8485c3d6\", \"display_unittests\": \"4cbf3b9e1885bf5b3f81ddd2a31d013f1ae82f40\", \"events_unittests\": \"d147fcd523f6504b0e5e33a03a3a39ba9de08fce\", \"exo_unittests\": \"049ac3d6a3a2cebfdce3ba2d7072ebe01029027f\", \"extensions_browsertests\": \"bb5bc544bd2e3575e6ccb1b0ad8f05b2756e8851\", \"extensions_unittests\": \"03b6bc1aa2a8ed79a6c29f1b4d3da411dad156c8\", \"filesystem_service_unittests\": \"2a7b083ecf67cb035ae29e69e2784a5539bf1251\", \"gcm_unit_tests\": \"7c9052e41211e54d240d501baf392096f5b01e02\", \"gfx_unittests\": \"0461c6823312776ea8a058f8f873c424422a25e5\", \"gin_unittests\": \"84c49dc8e2ccca4c8b74d4bf562d070f936e39c3\", \"gl_unittests_ozone\": \"93c87419d7005356b6447af632549e59488dee47\", \"google_apis_unittests\": \"8987eb4df563f7a6e5a0b71a48c37aaa4ed63647\", \"gpu_unittests\": \"858434f0a77984436007a34325d51bd2d773195e\", \"gwp_asan_unittests\": \"e1d3d4558b321c257d0b7134bdac6362f75a7ced\", \"interactive_ui_tests\": \"ed6b1aad2a3e5fb5a3709e748fdb1ca83a3fc711\", \"ipc_tests\": \"f46c733e227ab5b232df76324e6f55076a65aafb\", \"jingle_unittests\": \"2ea2d005f9770aca7469c28e15988203ebd910ad\", \"keyboard_unittests\": \"561b2d6d5fbb17d7a32b9c33adcbc96dd629183f\", \"latency_unittests\": \"4c3e6304d18e4323a0aeb8179714d0d76fe951bd\", \"libjingle_xmpp_unittests\": \"d82cf64416e5c9eb536e34738e6af83f5efa0928\", \"liburlpattern_unittests\": \"868a4d3a68da851b404ee722d177430bc86ef40c\", \"media_blink_unittests\": \"08d514a56315702f07bda9a81bc0a6102ecf80db\", \"media_unittests\": \"5a2c61add5078ff54c500e05ed2a0dc5a2b33929\", \"message_center_unittests\": \"590c2cb00c1b8945ad81c71fa16865bb4cae6724\", \"midi_unittests\": \"3febc61788466dc9c481cb9bf7e1e0aacfa7fb95\", \"mojo_core_unittests\": \"080daf3f96a919c16216cd93f3ad85055836ba05\", \"mojo_unittests\": \"fba8387663ec980f3f7cb8aa56bdff374f789db6\", \"nacl_helper_nonsfi_unittests\": \"f619ce9f1596efba1baeba7c2385c0a949eccb1a\", \"nacl_loader_unittests\": \"be7ab7e64e0356d2b02415ba1fc144689637b39a\", \"native_theme_unittests\": \"7f2b163e06eb04538bcf3ab645c797d4125ccaa1\", \"net_unittests\": \"365d7128a9da036b9ec145990d90826b023a2cce\", \"ozone_gl_unittests\": \"72948651103c7b4d1499ec2e9a282bae74b926f3\", \"ozone_unittests\": \"c0ebc73398c89bc70fcf762eaab162336ddfd613\", \"ozone_x11_unittests\": \"f3b3317f7745d8b32fd91b84b6eeb44ab8f2e357\", \"pdf_unittests\": \"757dca214749d033f7640b15076a8b6f2aa682ec\", \"perfetto_unittests\": \"66cd2f89b9d3a522952e3d8494b202c0934393f4\", \"ppapi_unittests\": \"bdba8dc895c65cf50e5bbecebd92dffeadef89bc\", \"printing_unittests\": \"bb1445c3ba9112799fff199c4249454909833067\", \"remoting_unittests\": \"4a2d54989e6b1fff3711bce0578c2af4d7511e18\", \"sandbox_linux_unittests\": \"af9e41d86da0b84c6bf1c7974a5f1d2dd6f3c3ff\", \"service_manager_unittests\": \"35a882691c6c34107d22d2e217890fd74f6d8f3e\", \"services_unittests\": \"6f69276737e5135aa04f6b1889b637e8adbb6e60\", \"shell_dialogs_unittests\": \"ac3be8a5c6a44a3dc71c471db0fd7af9c396d06b\", \"shell_encryption_unittests\": \"afb61535330ac669da3e07932bbb5b78c6e7ae2e\", \"skia_unittests\": \"a29470a2cc6e0e1995696232e94b87b324bc66a7\", \"snapshot_unittests\": \"896c99828607bf77bc3beabba269bfa31f2b8d01\", \"sql_unittests\": \"dcbcb9c131e501ecf13487c1fa6aefd55bd134e4\", \"storage_unittests\": \"d109d832defbf207835e827781017068c6672bfa\", \"sync_integration_tests\": \"22ac3a6e85efd544e90e1e8456f5b735cf93e669\", \"traffic_annotation_auditor_unittests\": \"cf0f0805f954e3c64f61eec6ca880675cd4dbb93\", \"ui_base_unittests\": \"bf6a4cdca7d39250171baaed8f264406ffc235f1\", \"ui_chromeos_unittests\": \"1e28c074ba53a26a19a1f2e87727e801258a6642\", \"ui_touch_selection_unittests\": \"0bb71ff3cf9083b0a91d4da3ed889beaf760c4c6\", \"unit_tests\": \"21d02abc28cedf3f5b287f00ad08f9ea0e51cea7\", \"url_unittests\": \"96bf6f3255e77f05211ba727b76b74b7cf033067\", \"usage_time_limit_unittests\": \"005fb93373366295a1814df5f231a7a8d527acf4\", \"views_unittests\": \"ebb3f827f487eef12678022d94faed49c3405a5a\", \"viz_unittests\": \"deb50c7bd4d47593e7f01292cd918e553b11b587\", \"wayland_client_perftests\": \"39b404ba1c81a820997c815500d11597107965e2\", \"wayland_client_tests\": \"78ebba37c48065d3001a26ba87dd2abe4b0c2caf\", \"wm_unittests\": \"ece92d6181133444ddd6e0490b59ea67fe022ca5\", \"wtf_unittests\": \"f4446f14ca3125c2182a1eb71174f3989cbc388f\", \"zlib_unittests\": \"0d105847e165d3204ee716a9e02b0f84e5904f77\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-66-e7rm\"], \"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\": [\"5712-339372b\"], \"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- CommerceHintAgentTest.CartPriority\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614268986711133",
"status": "COMPLETED",
"status_changed_ts": "1614268987331455",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45728",
"builder:linux-chromeos-rel",
"buildset:commit/git/6ae002709a6e0df5f61428d962e44a62920e76e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ae002709a6e0df5f61428d962e44a62920e76e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019740370533967408",
"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/8854310308411418736/+/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:51f2bb05cb971c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614274730488412",
"url": "https://ci.chromium.org/b/8854310308411418736",
"utcnow_ts": "1614274731207548"
}
}
[17:38:51.215] Invocation finished in 1h40m41.038150001s with status FAILED