[01:42:16.425] New invocation is queued and will start shortly
[01:42:17.529] Starting the invocation (attempt 1)
[01:42:17.564] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:42:17.564] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:42:17.564] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022240336956770800",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f669e3ee990bf1bba9d532ca31c4c248ae35c6c3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022240336956770800",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/f669e3ee990bf1bba9d532ca31c4c248ae35c6c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f669e3ee990bf1bba9d532ca31c4c248ae35c6c3",
"gitiles_ref:refs/heads/master"
]
}
[01:42:18.322] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611884537680756",
"id": "8856810274975582448",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f669e3ee990bf1bba9d532ca31c4c248ae35c6c3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611884538144000",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44987",
"builder:linux-chromeos-rel",
"buildset:commit/git/f669e3ee990bf1bba9d532ca31c4c248ae35c6c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f669e3ee990bf1bba9d532ca31c4c248ae35c6c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022240336956770800",
"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/8856810274975582448/+/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": "1611884538144109",
"url": "https://ci.chromium.org/b/8856810274975582448",
"utcnow_ts": "1611884538315001"
}
}
[01:42:18.322] Task URL: https://ci.chromium.org/b/8856810274975582448
[01:42:18.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:2:0) after 1m0s
[01:43:18.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:2:0)
[01:43:18.329] Timer tick, asking Buildbucket for the build status
[01:43:18.438] Build 8856810274975582448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:18.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:3:0) after 1m0s
[01:44:18.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:3:0)
[01:44:18.549] Timer tick, asking Buildbucket for the build status
[01:44:18.896] Build 8856810274975582448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:18.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:4:0) after 1m0s
[01:45:19.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:4:0)
[01:45:19.009] Timer tick, asking Buildbucket for the build status
[01:45:19.089] Build 8856810274975582448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:19.089] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:5:0) after 1m0s
[01:46:19.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:5:0)
[01:46:19.226] Timer tick, asking Buildbucket for the build status
[01:46:19.496] Build 8856810274975582448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:19.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:6:0) after 1m0s
[01:47:06.243] Received PubSub notification, asking Buildbucket for the build status
[01:47:06.515] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:19.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:6:0)
[01:47:19.556] Timer tick, asking Buildbucket for the build status
[01:47:19.622] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:19.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:8:0) after 1m0s
[01:48:19.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:8:0)
[01:48:19.669] Timer tick, asking Buildbucket for the build status
[01:48:19.938] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:19.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:9:0) after 1m0s
[01:49:19.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:9:0)
[01:49:19.918] Timer tick, asking Buildbucket for the build status
[01:49:20.241] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:20.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:10:0) after 1m0s
[01:50:20.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:10:0)
[01:50:20.331] Timer tick, asking Buildbucket for the build status
[01:50:20.405] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:20.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:11:0) after 1m0s
[01:51:20.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:11:0)
[01:51:20.539] Timer tick, asking Buildbucket for the build status
[01:51:20.636] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:20.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:12:0) after 1m0s
[01:52:20.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:12:0)
[01:52:20.652] Timer tick, asking Buildbucket for the build status
[01:52:20.848] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:20.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:13:0) after 1m0s
[01:53:20.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:13:0)
[01:53:20.899] Timer tick, asking Buildbucket for the build status
[01:53:20.965] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:20.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:14:0) after 1m0s
[01:54:20.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:14:0)
[01:54:20.999] Timer tick, asking Buildbucket for the build status
[01:54:21.083] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:21.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:15:0) after 1m0s
[01:55:21.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:15:0)
[01:55:21.190] Timer tick, asking Buildbucket for the build status
[01:55:21.375] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:21.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:16:0) after 1m0s
[01:56:21.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:16:0)
[01:56:21.442] Timer tick, asking Buildbucket for the build status
[01:56:21.576] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:21.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:17:0) after 1m0s
[01:57:21.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:17:0)
[01:57:21.596] Timer tick, asking Buildbucket for the build status
[01:57:21.903] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:21.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:18:0) after 1m0s
[01:58:21.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:18:0)
[01:58:21.923] Timer tick, asking Buildbucket for the build status
[01:58:21.994] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:21.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:19:0) after 1m0s
[01:59:22.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:19:0)
[01:59:22.048] Timer tick, asking Buildbucket for the build status
[01:59:22.605] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:22.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:20:0) after 1m0s
[02:00:22.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:20:0)
[02:00:22.676] Timer tick, asking Buildbucket for the build status
[02:00:22.750] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:22.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:21:0) after 1m0s
[02:01:22.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:21:0)
[02:01:22.827] Timer tick, asking Buildbucket for the build status
[02:01:22.954] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:22.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:22:0) after 1m0s
[02:02:23.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:22:0)
[02:02:23.018] Timer tick, asking Buildbucket for the build status
[02:02:23.156] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:23.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:23:0) after 1m0s
[02:03:23.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:23:0)
[02:03:23.222] Timer tick, asking Buildbucket for the build status
[02:03:23.382] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:23.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:24:0) after 1m0s
[02:04:23.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:24:0)
[02:04:23.382] Timer tick, asking Buildbucket for the build status
[02:04:23.546] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:23.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:25:0) after 1m0s
[02:05:23.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:25:0)
[02:05:23.610] Timer tick, asking Buildbucket for the build status
[02:05:23.686] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:23.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:26:0) after 1m0s
[02:06:23.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:26:0)
[02:06:23.725] Timer tick, asking Buildbucket for the build status
[02:06:23.778] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:23.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:27:0) after 1m0s
[02:07:23.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:27:0)
[02:07:23.827] Timer tick, asking Buildbucket for the build status
[02:07:24.037] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:24.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:28:0) after 1m0s
[02:08:24.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:28:0)
[02:08:24.170] Timer tick, asking Buildbucket for the build status
[02:08:24.241] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:24.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:29:0) after 1m0s
[02:09:24.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:29:0)
[02:09:24.252] Timer tick, asking Buildbucket for the build status
[02:09:24.315] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:24.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:30:0) after 1m0s
[02:10:24.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:30:0)
[02:10:24.341] Timer tick, asking Buildbucket for the build status
[02:10:24.815] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:24.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:31:0) after 1m0s
[02:11:24.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:31:0)
[02:11:24.912] Timer tick, asking Buildbucket for the build status
[02:11:24.979] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:24.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:32:0) after 1m0s
[02:12:25.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:32:0)
[02:12:25.013] Timer tick, asking Buildbucket for the build status
[02:12:25.135] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:25.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:33:0) after 1m0s
[02:13:25.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:33:0)
[02:13:25.187] Timer tick, asking Buildbucket for the build status
[02:13:25.326] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:25.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:34:0) after 1m0s
[02:14:25.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:34:0)
[02:14:25.378] Timer tick, asking Buildbucket for the build status
[02:14:25.456] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:25.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:35:0) after 1m0s
[02:15:25.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:35:0)
[02:15:25.552] Timer tick, asking Buildbucket for the build status
[02:15:25.650] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:25.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:36:0) after 1m0s
[02:16:25.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:36:0)
[02:16:25.847] Timer tick, asking Buildbucket for the build status
[02:16:26.040] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:26.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:37:0) after 1m0s
[02:17:26.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:37:0)
[02:17:26.157] Timer tick, asking Buildbucket for the build status
[02:17:26.816] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:26.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:38:0) after 1m0s
[02:18:26.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:38:0)
[02:18:26.925] Timer tick, asking Buildbucket for the build status
[02:18:27.017] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:27.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:39:0) after 1m0s
[02:19:27.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:39:0)
[02:19:27.147] Timer tick, asking Buildbucket for the build status
[02:19:27.438] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:27.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:40:0) after 1m0s
[02:20:27.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:40:0)
[02:20:27.424] Timer tick, asking Buildbucket for the build status
[02:20:27.497] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:27.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:41:0) after 1m0s
[02:21:27.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:41:0)
[02:21:27.623] Timer tick, asking Buildbucket for the build status
[02:21:27.744] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:27.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:42:0) after 1m0s
[02:22:27.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:42:0)
[02:22:27.744] Timer tick, asking Buildbucket for the build status
[02:22:27.847] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:27.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:43:0) after 1m0s
[02:23:27.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:43:0)
[02:23:27.912] Timer tick, asking Buildbucket for the build status
[02:23:28.358] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:28.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:44:0) after 1m0s
[02:24:28.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:44:0)
[02:24:28.404] Timer tick, asking Buildbucket for the build status
[02:24:28.471] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:28.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:45:0) after 1m0s
[02:25:28.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:45:0)
[02:25:28.529] Timer tick, asking Buildbucket for the build status
[02:25:28.590] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:28.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:46:0) after 1m0s
[02:26:28.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:46:0)
[02:26:28.633] Timer tick, asking Buildbucket for the build status
[02:26:28.691] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:28.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:47:0) after 1m0s
[02:27:28.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:47:0)
[02:27:28.760] Timer tick, asking Buildbucket for the build status
[02:27:28.870] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:28.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:48:0) after 1m0s
[02:28:28.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:48:0)
[02:28:28.892] Timer tick, asking Buildbucket for the build status
[02:28:28.969] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:28.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:49:0) after 1m0s
[02:29:28.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:49:0)
[02:29:28.985] Timer tick, asking Buildbucket for the build status
[02:29:29.056] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:29.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:50:0) after 1m0s
[02:30:29.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:50:0)
[02:30:29.129] Timer tick, asking Buildbucket for the build status
[02:30:29.215] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:29.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:51:0) after 1m0s
[02:31:29.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:51:0)
[02:31:29.285] Timer tick, asking Buildbucket for the build status
[02:31:29.341] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:29.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:52:0) after 1m0s
[02:32:29.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:52:0)
[02:32:29.404] Timer tick, asking Buildbucket for the build status
[02:32:29.501] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:29.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:53:0) after 1m0s
[02:33:29.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:53:0)
[02:33:29.517] Timer tick, asking Buildbucket for the build status
[02:33:29.636] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:29.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:54:0) after 1m0s
[02:34:29.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:54:0)
[02:34:29.659] Timer tick, asking Buildbucket for the build status
[02:34:29.761] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:29.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:55:0) after 1m0s
[02:35:30.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:55:0)
[02:35:30.006] Timer tick, asking Buildbucket for the build status
[02:35:30.088] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:30.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:56:0) after 1m0s
[02:36:30.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:56:0)
[02:36:30.262] Timer tick, asking Buildbucket for the build status
[02:36:30.521] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:30.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:57:0) after 1m0s
[02:37:30.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:57:0)
[02:37:30.533] Timer tick, asking Buildbucket for the build status
[02:37:30.637] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:30.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:58:0) after 1m0s
[02:38:30.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:58:0)
[02:38:30.910] Timer tick, asking Buildbucket for the build status
[02:38:30.966] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:30.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:59:0) after 1m0s
[02:39:31.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:59:0)
[02:39:31.045] Timer tick, asking Buildbucket for the build status
[02:39:31.407] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:31.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:60:0) after 1m0s
[02:40:31.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:60:0)
[02:40:31.476] Timer tick, asking Buildbucket for the build status
[02:40:31.540] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:31.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:61:0) after 1m0s
[02:41:31.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:61:0)
[02:41:31.617] Timer tick, asking Buildbucket for the build status
[02:41:31.693] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:31.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:62:0) after 1m0s
[02:42:31.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:62:0)
[02:42:31.804] Timer tick, asking Buildbucket for the build status
[02:42:31.943] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:31.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:63:0) after 1m0s
[02:43:32.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:63:0)
[02:43:32.029] Timer tick, asking Buildbucket for the build status
[02:43:32.141] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:32.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:64:0) after 1m0s
[02:44:32.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:64:0)
[02:44:32.281] Timer tick, asking Buildbucket for the build status
[02:44:32.356] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:32.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:65:0) after 1m0s
[02:45:32.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:65:0)
[02:45:32.422] Timer tick, asking Buildbucket for the build status
[02:45:32.554] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:32.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:66:0) after 1m0s
[02:46:32.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:66:0)
[02:46:32.649] Timer tick, asking Buildbucket for the build status
[02:46:32.825] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:32.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:67:0) after 1m0s
[02:47:32.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:67:0)
[02:47:32.892] Timer tick, asking Buildbucket for the build status
[02:47:33.054] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:33.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:68:0) after 1m0s
[02:48:33.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:68:0)
[02:48:33.084] Timer tick, asking Buildbucket for the build status
[02:48:33.155] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:33.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:69:0) after 1m0s
[02:49:33.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:69:0)
[02:49:33.378] Timer tick, asking Buildbucket for the build status
[02:49:33.731] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:33.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:70:0) after 1m0s
[02:50:33.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:70:0)
[02:50:33.828] Timer tick, asking Buildbucket for the build status
[02:50:33.925] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:33.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:71:0) after 1m0s
[02:51:34.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:71:0)
[02:51:34.177] Timer tick, asking Buildbucket for the build status
[02:51:34.278] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:34.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:72:0) after 1m0s
[02:52:34.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:72:0)
[02:52:34.407] Timer tick, asking Buildbucket for the build status
[02:52:34.523] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:34.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:73:0) after 1m0s
[02:53:34.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:73:0)
[02:53:34.680] Timer tick, asking Buildbucket for the build status
[02:53:34.823] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:34.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:74:0) after 1m0s
[02:54:34.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:74:0)
[02:54:34.865] Timer tick, asking Buildbucket for the build status
[02:54:35.001] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:35.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:75:0) after 1m0s
[02:55:35.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:75:0)
[02:55:35.116] Timer tick, asking Buildbucket for the build status
[02:55:35.275] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:35.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:76:0) after 1m0s
[02:56:35.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:76:0)
[02:56:35.337] Timer tick, asking Buildbucket for the build status
[02:56:35.397] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:35.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:77:0) after 1m0s
[02:57:35.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:77:0)
[02:57:35.515] Timer tick, asking Buildbucket for the build status
[02:57:35.837] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:35.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:78:0) after 1m0s
[02:58:37.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:78:0)
[02:58:37.739] Timer tick, asking Buildbucket for the build status
[02:58:38.009] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:38.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:79:0) after 1m0s
[02:59:38.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:79:0)
[02:59:38.051] Timer tick, asking Buildbucket for the build status
[02:59:38.515] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:38.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:80:0) after 1m0s
[03:00:38.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:80:0)
[03:00:38.611] Timer tick, asking Buildbucket for the build status
[03:00:38.698] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:38.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:81:0) after 1m0s
[03:01:38.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:81:0)
[03:01:38.731] Timer tick, asking Buildbucket for the build status
[03:01:38.799] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:38.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:82:0) after 1m0s
[03:02:38.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:82:0)
[03:02:38.822] Timer tick, asking Buildbucket for the build status
[03:02:38.990] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:38.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:83:0) after 1m0s
[03:03:39.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:83:0)
[03:03:39.086] Timer tick, asking Buildbucket for the build status
[03:03:39.165] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:39.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:84:0) after 1m0s
[03:04:39.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:84:0)
[03:04:39.266] Timer tick, asking Buildbucket for the build status
[03:04:39.343] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:39.343] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:85:0) after 1m0s
[03:05:39.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:85:0)
[03:05:39.503] Timer tick, asking Buildbucket for the build status
[03:05:39.576] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:39.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:86:0) after 1m0s
[03:06:39.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:86:0)
[03:06:39.624] Timer tick, asking Buildbucket for the build status
[03:06:39.684] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:39.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:87:0) after 1m0s
[03:07:39.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:87:0)
[03:07:39.755] Timer tick, asking Buildbucket for the build status
[03:07:39.881] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:39.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:88:0) after 1m0s
[03:08:39.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:88:0)
[03:08:39.989] Timer tick, asking Buildbucket for the build status
[03:08:40.119] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:40.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:89:0) after 1m0s
[03:09:40.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:89:0)
[03:09:40.151] Timer tick, asking Buildbucket for the build status
[03:09:40.203] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:40.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:90:0) after 1m0s
[03:10:40.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:90:0)
[03:10:40.233] Timer tick, asking Buildbucket for the build status
[03:10:40.310] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:40.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:91:0) after 1m0s
[03:11:40.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:91:0)
[03:11:40.383] Timer tick, asking Buildbucket for the build status
[03:11:40.486] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:40.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:92:0) after 1m0s
[03:12:40.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:92:0)
[03:12:40.537] Timer tick, asking Buildbucket for the build status
[03:12:41.217] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:41.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:93:0) after 1m0s
[03:13:41.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:93:0)
[03:13:41.300] Timer tick, asking Buildbucket for the build status
[03:13:41.530] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:41.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:94:0) after 1m0s
[03:14:41.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:94:0)
[03:14:41.909] Timer tick, asking Buildbucket for the build status
[03:14:42.069] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:42.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:95:0) after 1m0s
[03:15:42.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:95:0)
[03:15:42.472] Timer tick, asking Buildbucket for the build status
[03:15:42.547] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:42.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:96:0) after 1m0s
[03:16:42.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:96:0)
[03:16:42.696] Timer tick, asking Buildbucket for the build status
[03:16:42.829] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:42.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:97:0) after 1m0s
[03:17:43.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:97:0)
[03:17:43.002] Timer tick, asking Buildbucket for the build status
[03:17:43.511] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:43.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:98:0) after 1m0s
[03:18:43.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:98:0)
[03:18:43.622] Timer tick, asking Buildbucket for the build status
[03:18:43.813] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:43.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:99:0) after 1m0s
[03:19:43.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:99:0)
[03:19:43.948] Timer tick, asking Buildbucket for the build status
[03:19:44.034] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:44.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:100:0) after 1m0s
[03:20:44.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:100:0)
[03:20:44.099] Timer tick, asking Buildbucket for the build status
[03:20:44.196] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:44.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:101:0) after 1m0s
[03:21:44.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:101:0)
[03:21:44.260] Timer tick, asking Buildbucket for the build status
[03:21:44.438] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:44.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:102:0) after 1m0s
[03:22:44.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:102:0)
[03:22:44.488] Timer tick, asking Buildbucket for the build status
[03:22:44.664] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:44.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:103:0) after 1m0s
[03:23:44.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:103:0)
[03:23:44.650] Timer tick, asking Buildbucket for the build status
[03:23:44.746] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:44.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:104:0) after 1m0s
[03:24:44.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:104:0)
[03:24:44.746] Timer tick, asking Buildbucket for the build status
[03:24:44.940] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:44.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:105:0) after 1m0s
[03:25:44.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:105:0)
[03:25:44.915] Timer tick, asking Buildbucket for the build status
[03:25:45.109] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:106:0) after 1m0s
[03:26:45.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:106:0)
[03:26:45.157] Timer tick, asking Buildbucket for the build status
[03:26:45.238] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:45.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:107:0) after 1m0s
[03:27:45.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:107:0)
[03:27:45.268] Timer tick, asking Buildbucket for the build status
[03:27:45.613] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:45.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:108:0) after 1m0s
[03:28:45.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:108:0)
[03:28:45.633] Timer tick, asking Buildbucket for the build status
[03:28:45.786] Build 8856810274975582448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:45.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022240336956770800:109:0) after 1m0s
[03:29:07.507] Received PubSub notification, asking Buildbucket for the build status
[03:29:07.613] Build 8856810274975582448: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:29:07.613] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611890946961763",
"created_by": "project:chromium",
"created_ts": "1611884537680756",
"failure_reason": "BUILD_FAILURE",
"id": "8856810274975582448",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f669e3ee990bf1bba9d532ca31c4c248ae35c6c3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToFocusedElement\\n\\n- HoldingSpace/FilesAppBrowserTest.Test/holdingSpaceWelcomeBannerWithFeatureEnabled\\n\\n- SelectFileDialogExtensionBrowserTest/SelectFileDialogExtensionBrowserTest.SelectFileVirtualKeyboard_TabletMode/0\"}, \"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\": \"f669e3ee990bf1bba9d532ca31c4c248ae35c6c3\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-150-xtob\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44987, \"got_angle_revision\": \"a685db2e9745b615987b1b514dc23cc2fafff7bd\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"f669e3ee990bf1bba9d532ca31c4c248ae35c6c3\", \"got_revision_cp\": \"refs/heads/master@{#848371}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"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\": \"f669e3ee990bf1bba9d532ca31c4c248ae35c6c3\", \"swarm_hashes_refs/heads/master(at){#848371}_without_patch\": {\"absl_hardening_tests\": \"2df76819cb9a2138699bc893fda7ce980eebdf04\", \"accessibility_unittests\": \"34c8304d6a54d3140f30c207a543469a7db1b234\", \"angle_unittests\": \"cd1a99266d26cf0f06677f67fa26591484c0dd24\", \"app_list_unittests\": \"af689d00922a5f6ac1b4a9b74665bd375cb529a9\", \"app_shell_unittests\": \"62040972c33a52160062708fe83af018f1e5e262\", \"ash_unittests\": \"c73740f63980456483fdb4b6174a53e3d2015414\", \"aura_unittests\": \"d33ec5a61dfa208dcc304a0a6ad64bdc9621fe27\", \"base_unittests\": \"a0e2ee80a7dc5886db461973a72c7ef4005625e4\", \"base_util_unittests\": \"a0c9c4123a52c4461f26f1bd7ee8b08eb80f4470\", \"blink_common_unittests\": \"3967bb4ef6a20193ec81e1b539a532cfe7777d77\", \"blink_fuzzer_unittests\": \"9e3eed9d11efdf47f51acb4be2ca8488b003d95d\", \"blink_heap_unittests\": \"aceacfba95b380ea6dc611c27c45fc94f78d7379\", \"blink_platform_unittests\": \"3e94cf5735b02f73b59d5add5329c38b78cccf1a\", \"blink_unittests\": \"277b69aa0f9d399c5b0799eee10bfb76ca79956a\", \"boringssl_crypto_tests\": \"adc1fdb7fc017fdbb9bb003026e675478ea3ca58\", \"boringssl_ssl_tests\": \"c88a8ade75c69361ca8f8164312a728790fba76e\", \"browser_tests\": \"8d72925a713b29f2c1d1da285b89a033bfa98b3f\", \"capture_unittests\": \"872cc272d8fb3a31f13bedc53ca85b63cb53a294\", \"cast_unittests\": \"e5d338373eb391494e18b0eec621dce67e85d7ed\", \"cc_unittests\": \"2820f6b148a03718aa6f37bbe66e92d24ba88076\", \"chrome_app_unittests\": \"ade00972e999f18ab318eb0410387013e2dd150b\", \"chromedriver_unittests\": \"66e42fa493458ad0923aaf02c9bcdc73e528345b\", \"chromeos_components_unittests\": \"b59eb8cc7b9fe790b43816d1c28c8bb70fe5945e\", \"chromeos_unittests\": \"16e8cf1832853551317e913f657b6ccf1c4e2a87\", \"components_browsertests\": \"aabc857ab5eda67b7f8408c799f120440b17da74\", \"components_unittests\": \"328073fdd983e159b1d949c7ff44df9882adcdb2\", \"compositor_unittests\": \"b1ad97324899e36c22bb9bc50163a6829c7a161c\", \"content_browsertests\": \"60064a7bc44c009f1a0cfe8860215ea3cccadb30\", \"content_unittests\": \"de6fb8746e50d05ce15de6987f7239770a5e7b4a\", \"crashpad_tests\": \"49eeb39a33a7d0bf6ced8b037a4606caa5d49363\", \"crypto_unittests\": \"94a96352557aae7c1fe327bff644f98fe838e05e\", \"dbus_unittests\": \"95ce06b47086c199fb9fdb7436e42f3dadd3d1ae\", \"device_unittests\": \"4976cc6bc688487f9e787017210ad4fe3ae30791\", \"display_unittests\": \"26bcb5c39e17f05c94cd03d903ac96335dfe40c2\", \"events_unittests\": \"4a646b24068faff665960e67d557510449200c01\", \"exo_unittests\": \"7c68ada5cc090dacd7d1ce3ab39008f60a6b86f2\", \"extensions_browsertests\": \"d21cbe5f41b69bc5e874199180891c7b24e8c247\", \"extensions_unittests\": \"4c700dad02259a29e7d7524e78746c409085ca0e\", \"filesystem_service_unittests\": \"6cb589a8234b887af6ee541d8dad7621a1b7b930\", \"gcm_unit_tests\": \"8181f75d9e1aafa88348fe60df0cec6b0633609a\", \"gfx_unittests\": \"ecc656c4866bedb18805464315fac5b64386088a\", \"gin_unittests\": \"809d5818991b36013fb748de9c74d61037e99d3b\", \"gl_unittests_ozone\": \"f701740678d641029bfe4dfe802890878f133f86\", \"google_apis_unittests\": \"0504dffd9c48a7cb9f0b3a61cafe140b0b8b8679\", \"gpu_unittests\": \"ccde89d575be2e0d0d6d94eb956e54996980afbf\", \"gwp_asan_unittests\": \"ea9d9844471a240cc7a7cbe2213868b0b6d451bb\", \"interactive_ui_tests\": \"21758ceef54a1fc9148325e7c3396f596d801c5c\", \"ipc_tests\": \"74b3b2baac6010f3e1567424bf7a0ee6ea613b89\", \"jingle_unittests\": \"ec6a7f24e59902a8a3703d056b9ed2206727a04a\", \"keyboard_unittests\": \"b4255fb610f35302ae4fff2c1d08400d4d5b27ce\", \"latency_unittests\": \"c8dacf15695a40d51a004ee2ad33e90129254538\", \"libjingle_xmpp_unittests\": \"becc01338e0fa725313f78a07fab17982187807e\", \"liburlpattern_unittests\": \"be69176f3e5b77b7766284048aedfeb44dbeb102\", \"media_blink_unittests\": \"c06e398ccabc152a92ccf5c1dbbb40064d7e53b6\", \"media_unittests\": \"29cf2d45cab2b192ca652636c2d6918e04bfc701\", \"message_center_unittests\": \"e0ec4d6b8d55a99f7e16a0d8ca38a0225fc0e461\", \"midi_unittests\": \"ac540e25456201d6341104ca4e1d7942e9f21109\", \"mojo_core_unittests\": \"4d8a03efd81fd2b80ee3a830d7ac4e1cac92f3be\", \"mojo_unittests\": \"9f68027c469e236e3df5b41d53b7bf0c93637767\", \"nacl_helper_nonsfi_unittests\": \"a2a5dbdf27feee0b010714068cdfe27ddc67a882\", \"nacl_loader_unittests\": \"deffa104f0ccce78b77b5a06000ab18d18a4f06d\", \"native_theme_unittests\": \"1879eb871394e31853012b3e0102c1225f3a038c\", \"net_unittests\": \"aa12008cbe1bcd1d0562d16cffd09e1ecb3cc544\", \"ozone_gl_unittests\": \"7821d6d1283e0e0ad46f151bc044a257a9fa8243\", \"ozone_unittests\": \"2b769c0fc6017b07183b637eb3e6e3f25546d07a\", \"ozone_x11_unittests\": \"7590c58dbc3aa2421dc966a0ffe4b0e1f43a1f26\", \"pdf_unittests\": \"8858adcb1458bba991b8d51506d5640f6c6fc5cc\", \"perfetto_unittests\": \"188e9f69e69db98820640988ddd450fa2018f21a\", \"ppapi_unittests\": \"4530d0b0dd0d63996c676a42ce8c67523c48f39f\", \"printing_unittests\": \"f12cbd1d15a1a98db98c8199631c9f7ccd1bec1b\", \"remoting_unittests\": \"31efc798a552b59eba0b15166f0c6edf16a151e9\", \"sandbox_linux_unittests\": \"f55ab7a87cb4c859f7fc449c52406c50f04da37f\", \"service_manager_unittests\": \"a9b98e83cf97ffdaf559a8c9aeb0911c1f5c12d9\", \"services_unittests\": \"3731505acd8d1dad12724e3471c770b20d109e37\", \"shell_dialogs_unittests\": \"b4dd5dde83fd487150288ae60cd0e018e755fe37\", \"shell_encryption_unittests\": \"75d86c81f99c16c05e10176af1eed295e2ff1c49\", \"skia_unittests\": \"1507f0825c247561fb0202ca14f62205f01ef6a5\", \"snapshot_unittests\": \"768c75a5f8e2708585b15ec86324c51989be0dd5\", \"sql_unittests\": \"cb189f5566ca2ee1ff63d619ed8774f1496276af\", \"storage_unittests\": \"b053b7bdec6aa7fdb32bcff6851b6c062f2393cb\", \"sync_integration_tests\": \"72cd2b6d4a1c6f0675a48eec2ca68d654eee87cf\", \"traffic_annotation_auditor_unittests\": \"f3d279a25063c47bae0e2eb1c52a298bdf0516df\", \"ui_base_unittests\": \"6d171bc8ad0751a5fbfae1831f4b5c4f93134c92\", \"ui_chromeos_unittests\": \"efda7b9e50f299b24adbf3fc08f51e8fd5509517\", \"ui_touch_selection_unittests\": \"b17da07219de832ae105623c77de50eb7d488dff\", \"unit_tests\": \"1e7464d7016041770d888bb8f9bb156b459cdbad\", \"url_unittests\": \"bff0fea1a3fdb82d32466f834055b178eb68b5c7\", \"usage_time_limit_unittests\": \"3aca77cd09fcea5c19694047b88e5738357b49b3\", \"views_unittests\": \"e128fd056e71756c5474fe12c879460899102f56\", \"viz_unittests\": \"159b4cd5b621eb3c81f85cd0d8e1433d0b5d8a31\", \"wayland_client_perftests\": \"6140435b5b81fcdb4b85ad01a04e656dd326cfe6\", \"wm_unittests\": \"9a9853476cd74bb94e0e96773e96498a72f1bcb7\", \"wtf_unittests\": \"fb7b253635d9a5f9e6322f99165e3c166bb576f9\", \"zlib_unittests\": \"1452f105bddac85b5d42c367583038b542e027c1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_32ded518776cda8cac391977d607693e2958c39df4b87a6f7ec8056ca6add5b1_v2\", \"builder_67bed7503890836458cdb06475180566d2d2cc5e3d31ceae5acef503f97becf0_v2\", \"builder_7701d7ffc8ab5fe38a709c77d4f18478e80f1a1d01dd8053f239f119334d81d1_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_a1c3d05f1886c3c1a4b693e8245da857a9c63a0cda58ad280c9a386162f50760_v2\", \"builder_b624744545b53f30069a9e0f9d655dd80cb0688eb6f8753c5d4d1f02e2478691_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_dcef017f754690f3e0b2809d85023c2a50e42950d4504e0de4071c06ccf1a2cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-150-xtob\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToFocusedElement\\n\\n- HoldingSpace/FilesAppBrowserTest.Test/holdingSpaceWelcomeBannerWithFeatureEnabled\\n\\n- SelectFileDialogExtensionBrowserTest/SelectFileDialogExtensionBrowserTest.SelectFileVirtualKeyboard_TabletMode/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611884825548213",
"status": "COMPLETED",
"status_changed_ts": "1611884826045518",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44987",
"builder:linux-chromeos-rel",
"buildset:commit/git/f669e3ee990bf1bba9d532ca31c4c248ae35c6c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f669e3ee990bf1bba9d532ca31c4c248ae35c6c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022240336956770800",
"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/8856810274975582448/+/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:51649fb3d5feb610",
"user_agent:luci-scheduler"
],
"updated_ts": "1611890947219592",
"url": "https://ci.chromium.org/b/8856810274975582448",
"utcnow_ts": "1611890947602446"
}
}
[03:29:07.613] Invocation finished in 1h46m51.20641743s with status FAILED