[22:18:44.491] New invocation is queued and will start shortly
[22:18:45.607] Starting the invocation (attempt 1)
[22:18:45.671] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:18:45.676] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:18:45.676] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022706126927278208",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"207cede07df0b19e7df6db4e1a1a765fdc9075e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022706126927278208",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/207cede07df0b19e7df6db4e1a1a765fdc9075e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/207cede07df0b19e7df6db4e1a1a765fdc9075e5",
"gitiles_ref:refs/heads/master"
]
}
[22:18:46.387] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611440325781099",
"id": "8857276064912423376",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"207cede07df0b19e7df6db4e1a1a765fdc9075e5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611440326172427",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44852",
"builder:linux-chromeos-rel",
"buildset:commit/git/207cede07df0b19e7df6db4e1a1a765fdc9075e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/207cede07df0b19e7df6db4e1a1a765fdc9075e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022706126927278208",
"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/8857276064912423376/+/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": "1611440326172536",
"url": "https://ci.chromium.org/b/8857276064912423376",
"utcnow_ts": "1611440326379985"
}
}
[22:18:46.388] Task URL: https://ci.chromium.org/b/8857276064912423376
[22:18:46.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:2:0) after 1m0s
[22:19:46.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:2:0)
[22:19:46.406] Timer tick, asking Buildbucket for the build status
[22:19:46.693] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:46.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:3:0) after 1m0s
[22:20:46.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:3:0)
[22:20:46.745] Timer tick, asking Buildbucket for the build status
[22:20:46.864] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:46.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:4:0) after 1m0s
[22:21:46.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:4:0)
[22:21:46.918] Timer tick, asking Buildbucket for the build status
[22:21:46.988] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:46.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:5:0) after 1m0s
[22:22:47.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:5:0)
[22:22:47.044] Timer tick, asking Buildbucket for the build status
[22:22:47.129] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:47.129] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:6:0) after 1m0s
[22:23:47.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:6:0)
[22:23:47.159] Timer tick, asking Buildbucket for the build status
[22:23:47.310] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:47.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:7:0) after 1m0s
[22:24:47.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:7:0)
[22:24:47.333] Timer tick, asking Buildbucket for the build status
[22:24:47.433] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:47.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:8:0) after 1m0s
[22:25:47.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:8:0)
[22:25:47.477] Timer tick, asking Buildbucket for the build status
[22:25:47.572] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:47.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:9:0) after 1m0s
[22:26:47.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:9:0)
[22:26:47.594] Timer tick, asking Buildbucket for the build status
[22:26:47.678] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:47.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:10:0) after 1m0s
[22:27:47.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:10:0)
[22:27:47.707] Timer tick, asking Buildbucket for the build status
[22:27:47.848] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:47.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:11:0) after 1m0s
[22:28:47.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:11:0)
[22:28:47.865] Timer tick, asking Buildbucket for the build status
[22:28:48.057] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:48.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:12:0) after 1m0s
[22:29:48.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:12:0)
[22:29:48.077] Timer tick, asking Buildbucket for the build status
[22:29:48.432] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:48.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:13:0) after 1m0s
[22:30:48.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:13:0)
[22:30:48.451] Timer tick, asking Buildbucket for the build status
[22:30:48.576] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:48.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:14:0) after 1m0s
[22:31:48.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:14:0)
[22:31:48.601] Timer tick, asking Buildbucket for the build status
[22:31:48.702] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:48.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:15:0) after 1m0s
[22:32:48.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:15:0)
[22:32:48.753] Timer tick, asking Buildbucket for the build status
[22:32:48.847] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:48.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:16:0) after 1m0s
[22:33:48.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:16:0)
[22:33:48.861] Timer tick, asking Buildbucket for the build status
[22:33:49.208] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:49.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:17:0) after 1m0s
[22:34:49.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:17:0)
[22:34:49.258] Timer tick, asking Buildbucket for the build status
[22:34:49.338] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:49.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:18:0) after 1m0s
[22:35:49.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:18:0)
[22:35:49.375] Timer tick, asking Buildbucket for the build status
[22:35:49.705] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:49.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:19:0) after 1m0s
[22:36:49.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:19:0)
[22:36:49.805] Timer tick, asking Buildbucket for the build status
[22:36:49.878] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:49.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:20:0) after 1m0s
[22:37:49.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:20:0)
[22:37:49.904] Timer tick, asking Buildbucket for the build status
[22:37:50.104] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:50.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:21:0) after 1m0s
[22:38:50.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:21:0)
[22:38:50.129] Timer tick, asking Buildbucket for the build status
[22:38:50.408] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:50.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:22:0) after 1m0s
[22:39:50.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:22:0)
[22:39:50.480] Timer tick, asking Buildbucket for the build status
[22:39:50.548] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:50.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:23:0) after 1m0s
[22:40:50.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:23:0)
[22:40:50.613] Timer tick, asking Buildbucket for the build status
[22:40:50.680] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:50.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:24:0) after 1m0s
[22:41:50.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:24:0)
[22:41:50.726] Timer tick, asking Buildbucket for the build status
[22:41:50.806] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:50.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:25:0) after 1m0s
[22:42:50.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:25:0)
[22:42:50.868] Timer tick, asking Buildbucket for the build status
[22:42:50.953] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:50.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:26:0) after 1m0s
[22:43:51.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:26:0)
[22:43:51.022] Timer tick, asking Buildbucket for the build status
[22:43:51.162] Build 8857276064912423376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:51.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:27:0) after 1m0s
[22:44:40.767] Received PubSub notification, asking Buildbucket for the build status
[22:44:41.072] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:51.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:27:0)
[22:44:51.243] Timer tick, asking Buildbucket for the build status
[22:44:51.341] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:51.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:29:0) after 1m0s
[22:45:51.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:29:0)
[22:45:51.374] Timer tick, asking Buildbucket for the build status
[22:45:51.454] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:51.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:30:0) after 1m0s
[22:46:51.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:30:0)
[22:46:51.570] Timer tick, asking Buildbucket for the build status
[22:46:51.667] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:51.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:31:0) after 1m0s
[22:47:51.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:31:0)
[22:47:51.871] Timer tick, asking Buildbucket for the build status
[22:47:51.989] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:51.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:32:0) after 1m0s
[22:48:52.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:32:0)
[22:48:52.211] Timer tick, asking Buildbucket for the build status
[22:48:52.317] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:52.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:33:0) after 1m0s
[22:49:52.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:33:0)
[22:49:52.691] Timer tick, asking Buildbucket for the build status
[22:49:52.780] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:52.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:34:0) after 1m0s
[22:50:52.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:34:0)
[22:50:52.798] Timer tick, asking Buildbucket for the build status
[22:50:52.855] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:52.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:35:0) after 1m0s
[22:51:52.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:35:0)
[22:51:52.877] Timer tick, asking Buildbucket for the build status
[22:51:52.933] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:52.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:36:0) after 1m0s
[22:52:53.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:36:0)
[22:52:53.001] Timer tick, asking Buildbucket for the build status
[22:52:53.061] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:53.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:37:0) after 1m0s
[22:53:53.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:37:0)
[22:53:53.123] Timer tick, asking Buildbucket for the build status
[22:53:53.403] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:53.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:38:0) after 1m0s
[22:54:53.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:38:0)
[22:54:53.516] Timer tick, asking Buildbucket for the build status
[22:54:53.571] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:53.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:39:0) after 1m0s
[22:55:53.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:39:0)
[22:55:53.676] Timer tick, asking Buildbucket for the build status
[22:55:53.733] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:53.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:40:0) after 1m0s
[22:56:53.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:40:0)
[22:56:53.751] Timer tick, asking Buildbucket for the build status
[22:56:53.995] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:41:0) after 1m0s
[22:57:54.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:41:0)
[22:57:54.012] Timer tick, asking Buildbucket for the build status
[22:57:54.070] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:54.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:42:0) after 1m0s
[22:58:54.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:42:0)
[22:58:54.089] Timer tick, asking Buildbucket for the build status
[22:58:54.514] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:54.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:43:0) after 1m0s
[22:59:54.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:43:0)
[22:59:54.589] Timer tick, asking Buildbucket for the build status
[22:59:55.003] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:55.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:44:0) after 1m0s
[23:00:55.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:44:0)
[23:00:55.046] Timer tick, asking Buildbucket for the build status
[23:00:55.206] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:55.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:45:0) after 1m0s
[23:01:55.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:45:0)
[23:01:55.225] Timer tick, asking Buildbucket for the build status
[23:01:55.341] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:55.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:46:0) after 1m0s
[23:02:55.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:46:0)
[23:02:55.326] Timer tick, asking Buildbucket for the build status
[23:02:55.396] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:55.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:47:0) after 1m0s
[23:03:55.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:47:0)
[23:03:55.485] Timer tick, asking Buildbucket for the build status
[23:03:55.639] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:55.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:48:0) after 1m0s
[23:04:55.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:48:0)
[23:04:55.696] Timer tick, asking Buildbucket for the build status
[23:04:55.767] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:55.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:49:0) after 1m0s
[23:05:55.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:49:0)
[23:05:55.759] Timer tick, asking Buildbucket for the build status
[23:05:55.936] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:55.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:50:0) after 1m0s
[23:06:55.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:50:0)
[23:06:55.986] Timer tick, asking Buildbucket for the build status
[23:06:56.037] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:56.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:51:0) after 1m0s
[23:07:56.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:51:0)
[23:07:56.072] Timer tick, asking Buildbucket for the build status
[23:07:56.153] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:56.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:52:0) after 1m0s
[23:08:56.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:52:0)
[23:08:56.244] Timer tick, asking Buildbucket for the build status
[23:08:56.350] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:56.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:53:0) after 1m0s
[23:09:56.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:53:0)
[23:09:56.396] Timer tick, asking Buildbucket for the build status
[23:09:56.499] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:56.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:54:0) after 1m0s
[23:10:56.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:54:0)
[23:10:56.542] Timer tick, asking Buildbucket for the build status
[23:10:56.622] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:56.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:55:0) after 1m0s
[23:11:56.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:55:0)
[23:11:56.654] Timer tick, asking Buildbucket for the build status
[23:11:56.712] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:56.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:56:0) after 1m0s
[23:12:56.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:56:0)
[23:12:56.791] Timer tick, asking Buildbucket for the build status
[23:12:56.937] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:56.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:57:0) after 1m0s
[23:13:56.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:57:0)
[23:13:56.995] Timer tick, asking Buildbucket for the build status
[23:13:57.159] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:57.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:58:0) after 1m0s
[23:14:57.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:58:0)
[23:14:57.217] Timer tick, asking Buildbucket for the build status
[23:14:57.444] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:57.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:59:0) after 1m0s
[23:15:57.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:59:0)
[23:15:57.497] Timer tick, asking Buildbucket for the build status
[23:15:57.569] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:57.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:60:0) after 1m0s
[23:16:57.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:60:0)
[23:16:57.556] Timer tick, asking Buildbucket for the build status
[23:16:57.605] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:57.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:61:0) after 1m0s
[23:17:57.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:61:0)
[23:17:57.626] Timer tick, asking Buildbucket for the build status
[23:17:57.701] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:57.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:62:0) after 1m0s
[23:18:57.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:62:0)
[23:18:57.805] Timer tick, asking Buildbucket for the build status
[23:18:57.951] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:57.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:63:0) after 1m0s
[23:19:57.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:63:0)
[23:19:57.969] Timer tick, asking Buildbucket for the build status
[23:19:58.052] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:58.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:64:0) after 1m0s
[23:20:58.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:64:0)
[23:20:58.176] Timer tick, asking Buildbucket for the build status
[23:20:58.234] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:58.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:65:0) after 1m0s
[23:21:58.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:65:0)
[23:21:58.272] Timer tick, asking Buildbucket for the build status
[23:21:58.346] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:58.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:66:0) after 1m0s
[23:22:58.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:66:0)
[23:22:58.347] Timer tick, asking Buildbucket for the build status
[23:22:58.445] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:58.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:67:0) after 1m0s
[23:23:58.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:67:0)
[23:23:58.486] Timer tick, asking Buildbucket for the build status
[23:23:58.615] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:58.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:68:0) after 1m0s
[23:24:58.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:68:0)
[23:24:58.713] Timer tick, asking Buildbucket for the build status
[23:24:58.767] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:58.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:69:0) after 1m0s
[23:25:58.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:69:0)
[23:25:58.802] Timer tick, asking Buildbucket for the build status
[23:25:58.892] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:58.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:70:0) after 1m0s
[23:26:59.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:70:0)
[23:26:59.044] Timer tick, asking Buildbucket for the build status
[23:26:59.114] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:59.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:71:0) after 1m0s
[23:27:59.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:71:0)
[23:27:59.166] Timer tick, asking Buildbucket for the build status
[23:27:59.222] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:59.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:72:0) after 1m0s
[23:28:59.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:72:0)
[23:28:59.276] Timer tick, asking Buildbucket for the build status
[23:28:59.409] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:59.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:73:0) after 1m0s
[23:29:59.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:73:0)
[23:29:59.432] Timer tick, asking Buildbucket for the build status
[23:29:59.489] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:59.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:74:0) after 1m0s
[23:31:00.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:74:0)
[23:31:00.199] Timer tick, asking Buildbucket for the build status
[23:31:00.284] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:00.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:75:0) after 1m0s
[23:32:00.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:75:0)
[23:32:00.569] Timer tick, asking Buildbucket for the build status
[23:32:01.092] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:01.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:76:0) after 1m0s
[23:33:01.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:76:0)
[23:33:01.695] Timer tick, asking Buildbucket for the build status
[23:33:01.758] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:01.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:77:0) after 1m0s
[23:34:01.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:77:0)
[23:34:01.873] Timer tick, asking Buildbucket for the build status
[23:34:02.429] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:02.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:78:0) after 1m0s
[23:35:02.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:78:0)
[23:35:02.524] Timer tick, asking Buildbucket for the build status
[23:35:02.609] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:02.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:79:0) after 1m0s
[23:36:02.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:79:0)
[23:36:02.776] Timer tick, asking Buildbucket for the build status
[23:36:02.915] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:02.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:80:0) after 1m0s
[23:37:03.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:80:0)
[23:37:03.032] Timer tick, asking Buildbucket for the build status
[23:37:03.118] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:03.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:81:0) after 1m0s
[23:38:03.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:81:0)
[23:38:03.175] Timer tick, asking Buildbucket for the build status
[23:38:03.311] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:03.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:82:0) after 1m0s
[23:39:03.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:82:0)
[23:39:03.333] Timer tick, asking Buildbucket for the build status
[23:39:03.724] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:03.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:83:0) after 1m0s
[23:40:03.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:83:0)
[23:40:03.788] Timer tick, asking Buildbucket for the build status
[23:40:03.925] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:03.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:84:0) after 1m0s
[23:41:03.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:84:0)
[23:41:03.978] Timer tick, asking Buildbucket for the build status
[23:41:04.628] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:04.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:85:0) after 1m0s
[23:42:04.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:85:0)
[23:42:04.795] Timer tick, asking Buildbucket for the build status
[23:42:04.861] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:04.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:86:0) after 1m0s
[23:43:05.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:86:0)
[23:43:05.169] Timer tick, asking Buildbucket for the build status
[23:43:05.367] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:05.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:87:0) after 1m0s
[23:44:05.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:87:0)
[23:44:05.609] Timer tick, asking Buildbucket for the build status
[23:44:05.820] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:05.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:88:0) after 1m0s
[23:45:06.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:88:0)
[23:45:06.003] Timer tick, asking Buildbucket for the build status
[23:45:06.083] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:06.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:89:0) after 1m0s
[23:46:06.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:89:0)
[23:46:06.091] Timer tick, asking Buildbucket for the build status
[23:46:06.238] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:06.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:90:0) after 1m0s
[23:47:06.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:90:0)
[23:47:06.259] Timer tick, asking Buildbucket for the build status
[23:47:06.326] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:06.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:91:0) after 1m0s
[23:48:06.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:91:0)
[23:48:06.346] Timer tick, asking Buildbucket for the build status
[23:48:06.413] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:06.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:92:0) after 1m0s
[23:49:06.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:92:0)
[23:49:06.447] Timer tick, asking Buildbucket for the build status
[23:49:06.537] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:06.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:93:0) after 1m0s
[23:50:06.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:93:0)
[23:50:06.582] Timer tick, asking Buildbucket for the build status
[23:50:06.672] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:06.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:94:0) after 1m0s
[23:51:06.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:94:0)
[23:51:06.695] Timer tick, asking Buildbucket for the build status
[23:51:06.778] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:06.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:95:0) after 1m0s
[23:52:06.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:95:0)
[23:52:06.884] Timer tick, asking Buildbucket for the build status
[23:52:06.942] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:06.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:96:0) after 1m0s
[23:53:07.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:96:0)
[23:53:07.004] Timer tick, asking Buildbucket for the build status
[23:53:07.123] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:07.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:97:0) after 1m0s
[23:54:07.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:97:0)
[23:54:07.258] Timer tick, asking Buildbucket for the build status
[23:54:07.405] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:07.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:98:0) after 1m0s
[23:55:07.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:98:0)
[23:55:07.390] Timer tick, asking Buildbucket for the build status
[23:55:07.550] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:07.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:99:0) after 1m0s
[23:56:07.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:99:0)
[23:56:07.600] Timer tick, asking Buildbucket for the build status
[23:56:07.995] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:07.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:100:0) after 1m0s
[23:57:07.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:100:0)
[23:57:07.993] Timer tick, asking Buildbucket for the build status
[23:57:08.085] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:08.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:101:0) after 1m0s
[23:58:08.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:101:0)
[23:58:08.137] Timer tick, asking Buildbucket for the build status
[23:58:08.235] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:08.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:102:0) after 1m0s
[23:59:08.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:102:0)
[23:59:08.299] Timer tick, asking Buildbucket for the build status
[23:59:08.600] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:08.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:103:0) after 1m0s
[00:00:08.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:103:0)
[00:00:08.647] Timer tick, asking Buildbucket for the build status
[00:00:08.867] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:08.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:104:0) after 1m0s
[00:01:10.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:104:0)
[00:01:10.276] Timer tick, asking Buildbucket for the build status
[00:01:10.392] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:10.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:105:0) after 1m0s
[00:02:10.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:105:0)
[00:02:10.827] Timer tick, asking Buildbucket for the build status
[00:02:11.063] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:11.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:106:0) after 1m0s
[00:03:11.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:106:0)
[00:03:11.143] Timer tick, asking Buildbucket for the build status
[00:03:11.329] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:11.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:107:0) after 1m0s
[00:04:11.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:107:0)
[00:04:11.386] Timer tick, asking Buildbucket for the build status
[00:04:11.538] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:11.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:108:0) after 1m0s
[00:05:11.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:108:0)
[00:05:11.640] Timer tick, asking Buildbucket for the build status
[00:05:11.767] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:11.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:109:0) after 1m0s
[00:06:11.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:109:0)
[00:06:11.895] Timer tick, asking Buildbucket for the build status
[00:06:12.016] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:12.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:110:0) after 1m0s
[00:07:12.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:110:0)
[00:07:12.141] Timer tick, asking Buildbucket for the build status
[00:07:12.242] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:12.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:111:0) after 1m0s
[00:08:12.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:111:0)
[00:08:12.369] Timer tick, asking Buildbucket for the build status
[00:08:12.685] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:12.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:112:0) after 1m0s
[00:09:12.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:112:0)
[00:09:12.792] Timer tick, asking Buildbucket for the build status
[00:09:12.947] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:12.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:113:0) after 1m0s
[00:10:13.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:113:0)
[00:10:13.082] Timer tick, asking Buildbucket for the build status
[00:10:13.344] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:13.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:114:0) after 1m0s
[00:11:13.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:114:0)
[00:11:13.352] Timer tick, asking Buildbucket for the build status
[00:11:13.552] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:13.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:115:0) after 1m0s
[00:12:13.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:115:0)
[00:12:13.713] Timer tick, asking Buildbucket for the build status
[00:12:14.223] Build 8857276064912423376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:14.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022706126927278208:116:0) after 1m0s
[00:13:08.303] Received PubSub notification, asking Buildbucket for the build status
[00:13:08.411] Build 8857276064912423376: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:13:08.411] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611447187553465",
"created_by": "project:chromium",
"created_ts": "1611440325781099",
"failure_reason": "BUILD_FAILURE",
"id": "8857276064912423376",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"207cede07df0b19e7df6db4e1a1a765fdc9075e5\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**components_unittests** failed because of:\\n\\n- RepeatableQueriesServiceTest.SignedIn_ErrorResponse\"}, \"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\": \"207cede07df0b19e7df6db4e1a1a765fdc9075e5\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-11-xz2m\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44852, \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"207cede07df0b19e7df6db4e1a1a765fdc9075e5\", \"got_revision_cp\": \"refs/heads/master@{#846540}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"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\": \"207cede07df0b19e7df6db4e1a1a765fdc9075e5\", \"swarm_hashes_refs/heads/master(at){#846540}_without_patch\": {\"absl_hardening_tests\": \"dc8b38ebc42465c367c58e6d3e8de1108c0485ea\", \"accessibility_unittests\": \"b9ad57a3c4bc93a08fbb911db162846d78b75ff3\", \"angle_unittests\": \"150b62cd2bb404a34f8a3847addd087549785132\", \"app_list_unittests\": \"999e74d3107fb187aa9169524828f54e29d7d8d3\", \"app_shell_unittests\": \"8055cc943f03b225d19892448ce5b3443fdc623a\", \"ash_unittests\": \"906db5fd044071c60f948e14f39d41e2f19c9a5f\", \"aura_unittests\": \"b589aa371963d620666cc218c554e080147988a2\", \"base_unittests\": \"ad61d12b62b807ed754ac21718f539363dec8040\", \"base_util_unittests\": \"1711cec61f972988ee4a3bc0a4fa5ba8f3a6254f\", \"blink_common_unittests\": \"b8959d3bf8eb779db86274bec724f826d1a63481\", \"blink_fuzzer_unittests\": \"f68755bed112b02132d5c4cd90ebc5a2cb30fbcf\", \"blink_heap_unittests\": \"8f4a273d3e36200c8de2794845aa9955b2ebf966\", \"blink_platform_unittests\": \"ba7f8c1bbfd0320c52d70333a47f7b7495064601\", \"blink_unittests\": \"9a879c7f0ff17f393b242c8ce2ef242ca9c94f1a\", \"boringssl_crypto_tests\": \"abb21a9d81ef53bf0aa894159ec6c993d37d86c5\", \"boringssl_ssl_tests\": \"893eaaf4e8dbe8dc13ffca109d8c377487c21d4b\", \"browser_tests\": \"e0aec7f2322e45e9958c2045e5946061d2b2336f\", \"capture_unittests\": \"0f4dbafcf419eb008675c3e1627ea007249f0cb0\", \"cast_unittests\": \"7e076a49e77cc659e00b077daf3697c9379080bd\", \"cc_unittests\": \"ab5ed529b3f8ac531b8e634686ceeb7b0f68df31\", \"chrome_app_unittests\": \"9de624ca216d7576dbddc96a8f19f540197719a5\", \"chromedriver_unittests\": \"c7348892a7da29b2e3e6c20df836eacc09a02e38\", \"chromeos_components_unittests\": \"1869f915991990f58e8f0d4df6ee948662e06f6e\", \"chromeos_unittests\": \"4a8fc13e6d4b6daefe8e8abf2b74a1b116ed80aa\", \"components_browsertests\": \"344f94dbb41d39525c1541682d098d4b52e831ab\", \"components_unittests\": \"766846344ca565798aabf1df154f879a0d8c86da\", \"compositor_unittests\": \"c4b0c0ec3b43bb5de9091ab4496b7e7d53f10cd7\", \"content_browsertests\": \"f9251cccc1c63bbf585b93b1f4cd29563d7c3382\", \"content_unittests\": \"378d7031f926f2c2a4b9f29a1a463e6934d7ea83\", \"crashpad_tests\": \"9b0ec78c8de3edbf9854f1759e7d6f0aa43016e2\", \"crypto_unittests\": \"d1924de36263a039e18e935829cd5e9b67652807\", \"dbus_unittests\": \"dad2c97c0f50008c7bfcc704b4a207e35b5ad83e\", \"device_unittests\": \"0a88452b5346f6b468faf7a0c07edd31b409a1ef\", \"display_unittests\": \"3a65532af0783629e2caa2588c57d7ca1506052f\", \"events_unittests\": \"d1df346bc136fe0c0f586a5ed14b341bf58f8879\", \"exo_unittests\": \"59c8ad6409eab5ffcbe4385778382661d794104a\", \"extensions_browsertests\": \"d68366c9542fc7de930c421798694cdf3a070b1d\", \"extensions_unittests\": \"7c2f6955d7396b3538b9f895817a202a7c75cf8a\", \"filesystem_service_unittests\": \"f3645f858beab7608ca334ff15b113cf9e9b42e8\", \"gcm_unit_tests\": \"de5e461d83db0f644a819ab8828254798d8bac6d\", \"gfx_unittests\": \"7d77e7c9a08bfbe33f1e772611ea18bfc647ad0f\", \"gin_unittests\": \"d2e5afbe6176c203345d4ca09d431e5b91d3baba\", \"gl_unittests_ozone\": \"7510d9a2a94f137248d4600685d70fcb54604f9d\", \"google_apis_unittests\": \"fa69fab7cab0c000ea2ad35cb0de44d4e28025de\", \"gpu_unittests\": \"6fbf2b3d03b9f6415f740454971218f1ecd54f61\", \"gwp_asan_unittests\": \"f1be1054495054629e5b1666c62451b9efd14910\", \"interactive_ui_tests\": \"0b7cb246ebc08dc7f2417c824cd2036d635473e9\", \"ipc_tests\": \"17177319d58a31df42cfe2c44ed8a99a3bdc4b56\", \"jingle_unittests\": \"c6a702f1a6fc0da5a00d87a9bbe2ceebf9847d97\", \"keyboard_unittests\": \"9271002094f32609e1b68e453902ba9112c0a9f2\", \"latency_unittests\": \"344bc5028fa0ffbb6374b17540575f3c464b6b3d\", \"libjingle_xmpp_unittests\": \"f92be00472da8c5bb4dcd6e8b46e7a9124ad8c30\", \"liburlpattern_unittests\": \"ebbcdd83367f4cc4f1ee0c8590d0f132f678d58a\", \"media_blink_unittests\": \"b2c2bd0516dd65fe6eb136b00102df19617dd957\", \"media_unittests\": \"276118a1f5f66c238fb4e8ae4f3c704b66315dfd\", \"message_center_unittests\": \"ccf66d86718faa506ef2e5455dd28018352d64b6\", \"midi_unittests\": \"00c270958fb03755e27064786c3f2f65cd82ee1e\", \"mojo_core_unittests\": \"072efe9d4d0051115acbb7e59472dd8bac1422f2\", \"mojo_unittests\": \"3447a6522ea38b0682a25ab481583401e3ee64b4\", \"nacl_helper_nonsfi_unittests\": \"1c91a45e57519f253e3e0eaa82fc0a196a74723f\", \"nacl_loader_unittests\": \"421ce6be46972ea4e62b6e2a39381962b49c086d\", \"native_theme_unittests\": \"c9951c6332a4251941058a811a7511ddb598209a\", \"net_unittests\": \"6c1514a870fe08884ead4e8500197b6f4daa9e0d\", \"ozone_gl_unittests\": \"ced67b43a2052c3a0937c1779a5789dbe883ceb2\", \"ozone_unittests\": \"19c92dd5b550560118a13b686a7445d0183e9748\", \"ozone_x11_unittests\": \"6d19466c4354c7efb3351ae53db5d6805d22be48\", \"pdf_unittests\": \"a61369be41afd2bd80a90c36cf595444ca4d6df5\", \"perfetto_unittests\": \"afb4b605b6316af17fe3f54831f593c5b49f6a52\", \"ppapi_unittests\": \"f02a4667b955baebfa9b05211df11e24935380f3\", \"printing_unittests\": \"e06a0240740258419ebc62d31b142250a9a06e12\", \"remoting_unittests\": \"b40a8b666311a5167952ce81d9f081a36f8da674\", \"sandbox_linux_unittests\": \"c753f31b215c943850c9af3838cacfb82cc24135\", \"service_manager_unittests\": \"7c7b4aa12d84dfd12806df3777ba3f7318f8085f\", \"services_unittests\": \"b129da915ea37385fbe779f3f70926d68e68840e\", \"shell_dialogs_unittests\": \"4a0ead8303b89ef018908fe0111ef31266ea0bd4\", \"shell_encryption_unittests\": \"5c6e7d145dcaf0909beb772831a165eb19f4301c\", \"skia_unittests\": \"b8c42f0e3fc2872e7118d53c122dcecd990812e4\", \"snapshot_unittests\": \"1bd8e06d22274316e50b3bcb62d0b98396712b85\", \"sql_unittests\": \"51b5c2ab9ad5fa676ca57ffc7a6fdea2b6eda0b7\", \"storage_unittests\": \"0c94469fb7b12cd171f3c4c2401f9dc396cebe90\", \"sync_integration_tests\": \"903bfde74fa25ce11cab47b8724917d836648b50\", \"traffic_annotation_auditor_unittests\": \"ff1512bf511fad188e762ec6e9468afb44720824\", \"ui_base_unittests\": \"527a5db51457337332f8995e76b5c586a53a3285\", \"ui_chromeos_unittests\": \"8596c04ac7d3abc2eebd6939def0d3ee0071a33c\", \"ui_touch_selection_unittests\": \"2ea6568f28a1c18cf8b09b6b0d139f5ea64e52c1\", \"unit_tests\": \"7f45a63fe43441452097d5d957e3f214603c198c\", \"url_unittests\": \"548723fc2aa32fe271c926ecca3d206a846c84cc\", \"usage_time_limit_unittests\": \"1d811a801c90e4bedbc5d6da943659ef3113f8a0\", \"views_unittests\": \"38da3928bcf457928b42291dcf17d41241a0d053\", \"viz_unittests\": \"b32a9de3ffad485daca0f2023e9e46dda0b66b0d\", \"wayland_client_perftests\": \"db35f597c2cddbe1f0f7e490856f75dbe5e95c2c\", \"wm_unittests\": \"b54f66a8039b5cf5bdd3ff51aa994d2cd09b7f7f\", \"wtf_unittests\": \"5847b5a1f333bd7c6d1d84ded9f872d27708ba1d\", \"zlib_unittests\": \"f294d5f47ce4afba382588c892ea0fa6666a16e0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_70f3607e3f7d84b5c3698c0eed74813ee5c700477d5aec13835ae2ecc5ee2d5b_v2\", \"builder_79ddf9721ca7b7ce79374811fb2c516b44ee31d85deee669f4d7a1736cefaf3b_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_v2\", \"builder_fc85390932f60be0e3ac2fe3fabccf73080e82ff5de9472ae253be8b859a725c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-11-xz2m\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**components_unittests** failed because of:\\n\\n- RepeatableQueriesServiceTest.SignedIn_ErrorResponse\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611441879609697",
"status": "COMPLETED",
"status_changed_ts": "1611441880309034",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44852",
"builder:linux-chromeos-rel",
"buildset:commit/git/207cede07df0b19e7df6db4e1a1a765fdc9075e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/207cede07df0b19e7df6db4e1a1a765fdc9075e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022706126927278208",
"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/8857276064912423376/+/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:514a2590e7054610",
"user_agent:luci-scheduler"
],
"updated_ts": "1611447187854891",
"url": "https://ci.chromium.org/b/8857276064912423376",
"utcnow_ts": "1611447188402672"
}
}
[00:13:08.411] Invocation finished in 1h54m23.935515963s with status FAILED