[17:20:10.735] New invocation is queued and will start shortly
[17:20:11.845] Starting the invocation (attempt 1)
[17:20:11.891] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:20:11.892] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:20:11.892] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021094165468130128",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"55d634a021287f142579d032e63012577daa9f5c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021094165468130128",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/55d634a021287f142579d032e63012577daa9f5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/55d634a021287f142579d032e63012577daa9f5c",
"gitiles_ref:refs/heads/master"
]
}
[17:20:13.796] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612977612135289",
"id": "8855664103336511568",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"55d634a021287f142579d032e63012577daa9f5c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612977613437466",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45305",
"builder:linux-chromeos-rel",
"buildset:commit/git/55d634a021287f142579d032e63012577daa9f5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/55d634a021287f142579d032e63012577daa9f5c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021094165468130128",
"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/8855664103336511568/+/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": "1612977613437575",
"url": "https://ci.chromium.org/b/8855664103336511568",
"utcnow_ts": "1612977613763925"
}
}
[17:20:13.796] Task URL: https://ci.chromium.org/b/8855664103336511568
[17:20:13.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:2:0) after 1m0s
[17:21:13.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:2:0)
[17:21:13.990] Timer tick, asking Buildbucket for the build status
[17:21:14.403] Build 8855664103336511568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:21:14.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:3:0) after 1m0s
[17:22:14.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:3:0)
[17:22:14.885] Timer tick, asking Buildbucket for the build status
[17:22:15.225] Build 8855664103336511568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:22:15.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:4:0) after 1m0s
[17:23:15.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:4:0)
[17:23:15.308] Timer tick, asking Buildbucket for the build status
[17:23:15.598] Build 8855664103336511568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:23:15.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:5:0) after 1m0s
[17:24:15.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:5:0)
[17:24:15.824] Timer tick, asking Buildbucket for the build status
[17:24:16.237] Build 8855664103336511568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:24:16.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:6:0) after 1m0s
[17:25:07.496] Received PubSub notification, asking Buildbucket for the build status
[17:25:07.708] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:16.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:6:0)
[17:25:16.281] Timer tick, asking Buildbucket for the build status
[17:25:16.507] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:16.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:8:0) after 1m0s
[17:26:16.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:8:0)
[17:26:16.664] Timer tick, asking Buildbucket for the build status
[17:26:16.911] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:16.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:9:0) after 1m0s
[17:27:17.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:9:0)
[17:27:17.186] Timer tick, asking Buildbucket for the build status
[17:27:17.326] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:17.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:10:0) after 1m0s
[17:28:17.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:10:0)
[17:28:17.529] Timer tick, asking Buildbucket for the build status
[17:28:17.907] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:17.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:11:0) after 1m0s
[17:29:18.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:11:0)
[17:29:18.097] Timer tick, asking Buildbucket for the build status
[17:29:18.353] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:18.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:12:0) after 1m0s
[17:30:18.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:12:0)
[17:30:18.441] Timer tick, asking Buildbucket for the build status
[17:30:18.602] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:18.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:13:0) after 1m0s
[17:31:18.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:13:0)
[17:31:18.655] Timer tick, asking Buildbucket for the build status
[17:31:18.920] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:18.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:14:0) after 1m0s
[17:32:19.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:14:0)
[17:32:19.021] Timer tick, asking Buildbucket for the build status
[17:32:19.433] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:19.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:15:0) after 1m0s
[17:33:19.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:15:0)
[17:33:19.422] Timer tick, asking Buildbucket for the build status
[17:33:23.082] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:23.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:16:0) after 1m0s
[17:34:23.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:16:0)
[17:34:23.298] Timer tick, asking Buildbucket for the build status
[17:34:28.734] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:28.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:17:0) after 1m0s
[17:35:28.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:17:0)
[17:35:28.850] Timer tick, asking Buildbucket for the build status
[17:35:29.111] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:29.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:18:0) after 1m0s
[17:36:29.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:18:0)
[17:36:29.438] Timer tick, asking Buildbucket for the build status
[17:36:29.738] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:29.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:19:0) after 1m0s
[17:37:29.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:19:0)
[17:37:29.817] Timer tick, asking Buildbucket for the build status
[17:37:30.628] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:30.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:20:0) after 1m0s
[17:38:30.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:20:0)
[17:38:30.674] Timer tick, asking Buildbucket for the build status
[17:38:31.032] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:31.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:21:0) after 1m0s
[17:39:31.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:21:0)
[17:39:31.136] Timer tick, asking Buildbucket for the build status
[17:39:31.809] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:31.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:22:0) after 1m0s
[17:40:32.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:22:0)
[17:40:32.056] Timer tick, asking Buildbucket for the build status
[17:40:32.912] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:32.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:23:0) after 1m0s
[17:41:32.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:23:0)
[17:41:32.932] Timer tick, asking Buildbucket for the build status
[17:41:34.227] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:34.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:24:0) after 1m0s
[17:42:34.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:24:0)
[17:42:34.689] Timer tick, asking Buildbucket for the build status
[17:42:35.240] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:35.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:25:0) after 1m0s
[17:43:35.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:25:0)
[17:43:35.422] Timer tick, asking Buildbucket for the build status
[17:43:35.705] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:35.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:26:0) after 1m0s
[17:44:36.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:26:0)
[17:44:36.012] Timer tick, asking Buildbucket for the build status
[17:44:36.302] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:36.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:27:0) after 1m0s
[17:45:36.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:27:0)
[17:45:36.520] Timer tick, asking Buildbucket for the build status
[17:45:36.730] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:36.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:28:0) after 1m0s
[17:46:37.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:28:0)
[17:46:37.385] Timer tick, asking Buildbucket for the build status
[17:46:37.504] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:37.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:29:0) after 1m0s
[17:47:37.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:29:0)
[17:47:37.664] Timer tick, asking Buildbucket for the build status
[17:47:38.381] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:38.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:30:0) after 1m0s
[17:48:38.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:30:0)
[17:48:38.498] Timer tick, asking Buildbucket for the build status
[17:48:38.813] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:38.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:31:0) after 1m0s
[17:49:39.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:31:0)
[17:49:39.735] Timer tick, asking Buildbucket for the build status
[17:49:39.903] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:39.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:32:0) after 1m0s
[17:50:40.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:32:0)
[17:50:40.528] Timer tick, asking Buildbucket for the build status
[17:50:40.804] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:40.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:33:0) after 1m0s
[17:51:41.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:33:0)
[17:51:41.345] Timer tick, asking Buildbucket for the build status
[17:51:41.632] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:41.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:34:0) after 1m0s
[17:52:41.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:34:0)
[17:52:41.713] Timer tick, asking Buildbucket for the build status
[17:52:41.915] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:41.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:35:0) after 1m0s
[17:53:42.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:35:0)
[17:53:42.198] Timer tick, asking Buildbucket for the build status
[17:53:43.552] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:43.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:36:0) after 1m0s
[17:54:43.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:36:0)
[17:54:43.585] Timer tick, asking Buildbucket for the build status
[17:54:44.022] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:44.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:37:0) after 1m0s
[17:55:44.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:37:0)
[17:55:44.043] Timer tick, asking Buildbucket for the build status
[17:55:44.656] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:44.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:38:0) after 1m0s
[17:56:44.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:38:0)
[17:56:44.672] Timer tick, asking Buildbucket for the build status
[17:56:45.122] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:45.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:39:0) after 1m0s
[17:57:45.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:39:0)
[17:57:45.185] Timer tick, asking Buildbucket for the build status
[17:57:45.299] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:45.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:40:0) after 1m0s
[17:58:45.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:40:0)
[17:58:45.418] Timer tick, asking Buildbucket for the build status
[17:58:46.116] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:46.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:41:0) after 1m0s
[17:59:46.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:41:0)
[17:59:46.167] Timer tick, asking Buildbucket for the build status
[17:59:46.418] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:46.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:42:0) after 1m0s
[18:00:46.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:42:0)
[18:00:46.445] Timer tick, asking Buildbucket for the build status
[18:00:46.717] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:46.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:43:0) after 1m0s
[18:01:46.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:43:0)
[18:01:46.775] Timer tick, asking Buildbucket for the build status
[18:01:47.030] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:47.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:44:0) after 1m0s
[18:02:47.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:44:0)
[18:02:47.093] Timer tick, asking Buildbucket for the build status
[18:02:47.304] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:47.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:45:0) after 1m0s
[18:03:47.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:45:0)
[18:03:47.315] Timer tick, asking Buildbucket for the build status
[18:03:48.240] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:48.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:46:0) after 1m0s
[18:04:48.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:46:0)
[18:04:48.270] Timer tick, asking Buildbucket for the build status
[18:04:48.449] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:48.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:47:0) after 1m0s
[18:05:48.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:47:0)
[18:05:48.506] Timer tick, asking Buildbucket for the build status
[18:05:48.906] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:48.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:48:0) after 1m0s
[18:06:49.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:48:0)
[18:06:49.012] Timer tick, asking Buildbucket for the build status
[18:06:50.514] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:50.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:49:0) after 1m0s
[18:07:50.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:49:0)
[18:07:50.574] Timer tick, asking Buildbucket for the build status
[18:07:50.837] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:50.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:50:0) after 1m0s
[18:08:50.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:50:0)
[18:08:50.858] Timer tick, asking Buildbucket for the build status
[18:08:50.997] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:50.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:51:0) after 1m0s
[18:09:51.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:51:0)
[18:09:51.019] Timer tick, asking Buildbucket for the build status
[18:09:51.201] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:51.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:52:0) after 1m0s
[18:10:51.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:52:0)
[18:10:51.228] Timer tick, asking Buildbucket for the build status
[18:10:53.965] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:53.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:53:0) after 1m0s
[18:11:54.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:53:0)
[18:11:54.026] Timer tick, asking Buildbucket for the build status
[18:11:56.109] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:56.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:54:0) after 1m0s
[18:12:56.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:54:0)
[18:12:56.452] Timer tick, asking Buildbucket for the build status
[18:12:56.510] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:56.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:55:0) after 1m0s
[18:13:56.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:55:0)
[18:13:56.626] Timer tick, asking Buildbucket for the build status
[18:13:56.729] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:56.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:56:0) after 1m0s
[18:14:56.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:56:0)
[18:14:56.933] Timer tick, asking Buildbucket for the build status
[18:14:56.997] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:56.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:57:0) after 1m0s
[18:15:57.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:57:0)
[18:15:57.052] Timer tick, asking Buildbucket for the build status
[18:15:57.144] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:57.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:58:0) after 1m0s
[18:16:57.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:58:0)
[18:16:57.289] Timer tick, asking Buildbucket for the build status
[18:16:57.397] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:57.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:59:0) after 1m0s
[18:17:57.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:59:0)
[18:17:57.539] Timer tick, asking Buildbucket for the build status
[18:17:57.858] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:57.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:60:0) after 1m0s
[18:18:57.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:60:0)
[18:18:57.939] Timer tick, asking Buildbucket for the build status
[18:18:58.298] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:58.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:61:0) after 1m0s
[18:19:58.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:61:0)
[18:19:58.408] Timer tick, asking Buildbucket for the build status
[18:19:58.724] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:58.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:62:0) after 1m0s
[18:20:58.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:62:0)
[18:20:58.851] Timer tick, asking Buildbucket for the build status
[18:20:58.946] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:58.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:63:0) after 1m0s
[18:21:58.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:63:0)
[18:21:58.953] Timer tick, asking Buildbucket for the build status
[18:21:59.041] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:59.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:64:0) after 1m0s
[18:22:59.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:64:0)
[18:22:59.154] Timer tick, asking Buildbucket for the build status
[18:22:59.325] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:59.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:65:0) after 1m0s
[18:23:59.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:65:0)
[18:23:59.432] Timer tick, asking Buildbucket for the build status
[18:23:59.600] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:59.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:66:0) after 1m0s
[18:24:59.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:66:0)
[18:24:59.638] Timer tick, asking Buildbucket for the build status
[18:24:59.823] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:59.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:67:0) after 1m0s
[18:25:59.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:67:0)
[18:25:59.913] Timer tick, asking Buildbucket for the build status
[18:26:00.103] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:00.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:68:0) after 1m0s
[18:27:00.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:68:0)
[18:27:00.166] Timer tick, asking Buildbucket for the build status
[18:27:00.399] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:00.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:69:0) after 1m0s
[18:28:01.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:69:0)
[18:28:01.033] Timer tick, asking Buildbucket for the build status
[18:28:01.553] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:01.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:70:0) after 1m0s
[18:29:01.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:70:0)
[18:29:01.604] Timer tick, asking Buildbucket for the build status
[18:29:01.827] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:01.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:71:0) after 1m0s
[18:30:01.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:71:0)
[18:30:01.870] Timer tick, asking Buildbucket for the build status
[18:30:02.240] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:02.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:72:0) after 1m0s
[18:31:02.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:72:0)
[18:31:02.388] Timer tick, asking Buildbucket for the build status
[18:31:02.854] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:02.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:73:0) after 1m0s
[18:32:02.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:73:0)
[18:32:02.891] Timer tick, asking Buildbucket for the build status
[18:32:07.905] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:07.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:74:0) after 1m0s
[18:33:07.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:74:0)
[18:33:07.928] Timer tick, asking Buildbucket for the build status
[18:33:08.127] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:08.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:75:0) after 1m0s
[18:34:08.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:75:0)
[18:34:08.167] Timer tick, asking Buildbucket for the build status
[18:34:08.299] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:08.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:76:0) after 1m0s
[18:35:08.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:76:0)
[18:35:08.345] Timer tick, asking Buildbucket for the build status
[18:35:08.540] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:08.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:77:0) after 1m0s
[18:36:08.590] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:77:0)
[18:36:08.590] Timer tick, asking Buildbucket for the build status
[18:36:08.675] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:08.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:78:0) after 1m0s
[18:37:08.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:78:0)
[18:37:08.754] Timer tick, asking Buildbucket for the build status
[18:37:08.938] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:08.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:79:0) after 1m0s
[18:38:08.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:79:0)
[18:38:08.933] Timer tick, asking Buildbucket for the build status
[18:38:09.259] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:09.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:80:0) after 1m0s
[18:39:09.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:80:0)
[18:39:09.449] Timer tick, asking Buildbucket for the build status
[18:39:09.723] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:09.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:81:0) after 1m0s
[18:40:09.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:81:0)
[18:40:09.896] Timer tick, asking Buildbucket for the build status
[18:40:10.143] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:10.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:82:0) after 1m0s
[18:41:10.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:82:0)
[18:41:10.301] Timer tick, asking Buildbucket for the build status
[18:41:10.658] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:10.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:83:0) after 1m0s
[18:42:10.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:83:0)
[18:42:10.802] Timer tick, asking Buildbucket for the build status
[18:42:10.924] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:10.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:84:0) after 1m0s
[18:43:10.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:84:0)
[18:43:10.952] Timer tick, asking Buildbucket for the build status
[18:43:11.345] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:11.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:85:0) after 1m0s
[18:44:11.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:85:0)
[18:44:11.429] Timer tick, asking Buildbucket for the build status
[18:44:11.601] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:11.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:86:0) after 1m0s
[18:45:11.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:86:0)
[18:45:11.626] Timer tick, asking Buildbucket for the build status
[18:45:11.859] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:11.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:87:0) after 1m0s
[18:46:11.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:87:0)
[18:46:11.881] Timer tick, asking Buildbucket for the build status
[18:46:12.209] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:12.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:88:0) after 1m0s
[18:47:12.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:88:0)
[18:47:12.329] Timer tick, asking Buildbucket for the build status
[18:47:12.536] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:12.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:89:0) after 1m0s
[18:48:12.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:89:0)
[18:48:12.565] Timer tick, asking Buildbucket for the build status
[18:48:12.925] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:12.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:90:0) after 1m0s
[18:49:12.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:90:0)
[18:49:12.975] Timer tick, asking Buildbucket for the build status
[18:49:13.458] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:13.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:91:0) after 1m0s
[18:50:13.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:91:0)
[18:50:13.484] Timer tick, asking Buildbucket for the build status
[18:50:13.773] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:13.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:92:0) after 1m0s
[18:51:13.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:92:0)
[18:51:13.882] Timer tick, asking Buildbucket for the build status
[18:51:14.144] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:14.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:93:0) after 1m0s
[18:52:14.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:93:0)
[18:52:14.168] Timer tick, asking Buildbucket for the build status
[18:52:14.401] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:14.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:94:0) after 1m0s
[18:53:14.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:94:0)
[18:53:14.453] Timer tick, asking Buildbucket for the build status
[18:53:14.937] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:14.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:95:0) after 1m0s
[18:54:14.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:95:0)
[18:54:14.958] Timer tick, asking Buildbucket for the build status
[18:54:16.102] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:16.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:96:0) after 1m0s
[18:55:16.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:96:0)
[18:55:16.167] Timer tick, asking Buildbucket for the build status
[18:55:16.398] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:16.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:97:0) after 1m0s
[18:56:16.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:97:0)
[18:56:16.411] Timer tick, asking Buildbucket for the build status
[18:56:16.638] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:16.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:98:0) after 1m0s
[18:57:16.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:98:0)
[18:57:16.657] Timer tick, asking Buildbucket for the build status
[18:57:17.739] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:17.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:99:0) after 1m0s
[18:58:17.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:99:0)
[18:58:17.763] Timer tick, asking Buildbucket for the build status
[18:58:18.014] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:18.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:100:0) after 1m0s
[18:59:18.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:100:0)
[18:59:18.075] Timer tick, asking Buildbucket for the build status
[18:59:18.316] Build 8855664103336511568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:18.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021094165468130128:101:0) after 1m0s
[18:59:36.756] Received PubSub notification, asking Buildbucket for the build status
[18:59:37.156] Build 8855664103336511568: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:59:37.156] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612983575966207",
"created_by": "project:chromium",
"created_ts": "1612977612135289",
"failure_reason": "BUILD_FAILURE",
"id": "8855664103336511568",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"55d634a021287f142579d032e63012577daa9f5c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PDFExtensionJSTest.AnnotationsFeatureEnabled\"}, \"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\": \"55d634a021287f142579d032e63012577daa9f5c\", \"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-165-pzoq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45305, \"got_angle_revision\": \"0f74ae58ed185108ec38a4cb038ef3c31acd9ff4\", \"got_dawn_revision\": \"02fd17c75403a399672c65aa98e577c7c2ae9010\", \"got_nacl_revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"got_revision\": \"55d634a021287f142579d032e63012577daa9f5c\", \"got_revision_cp\": \"refs/heads/master@{#852688}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"ddadcacd13fae633df256fb967e61d4dbf1c8083\", \"got_v8_revision_cp\": \"refs/heads/9.0.157@{#1}\", \"got_webrtc_revision\": \"ad8a00d25c91ba28ae987d994c362cb183b6e7ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33212}\", \"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\": \"55d634a021287f142579d032e63012577daa9f5c\", \"swarm_hashes_refs/heads/master(at){#852688}_without_patch\": {\"absl_hardening_tests\": \"93055d6fef6d2cd4e09c412a1d36c1ecc01a1a83\", \"accessibility_unittests\": \"2fce2df2a367861d802bf60528bb71330bc4d761\", \"angle_unittests\": \"4cf658b5d6b40777618a9679a1467569d7286b50\", \"app_list_unittests\": \"344e96a78639622c61e601adc3aed9cdb3b1fc61\", \"app_shell_unittests\": \"90ec9b1cd25993293309db65d71dc359419de694\", \"ash_unittests\": \"735fba04269fc12ef18c1297e76a916c4546c1b2\", \"aura_unittests\": \"e81d9413573d89eaf183c2bce106d4e396fb90e1\", \"base_unittests\": \"b364edd8cd5a7ccfcaeeada1913840b32b936ef8\", \"base_util_unittests\": \"60b695c1e20f127cc830051b3aba00ef7b1bd75f\", \"blink_common_unittests\": \"e518438715467b2283bfefcc042a2c284b1ada8c\", \"blink_fuzzer_unittests\": \"ec32c4f1313b2c8e12a20768179c7ca7986d9f36\", \"blink_heap_unittests\": \"b57c606ece5adb6c0aa1987a785af45564c1532f\", \"blink_platform_unittests\": \"41507ccb71ac1ba235352840dc37bb2d5611c8b1\", \"blink_unittests\": \"2427ace9db3db8448bbb2e4cf8ce1d6a52a10925\", \"boringssl_crypto_tests\": \"72b57ad4477e9913eaf6a72525c26f287e66eff2\", \"boringssl_ssl_tests\": \"cf4c77e8ebba1bbbbe5c4710e2e5671ceff12792\", \"browser_tests\": \"ca88d8789991286815c9bde7622b995058658761\", \"capture_unittests\": \"94218be6fad25eb419062dd2a8a6093dd548a3ba\", \"cast_unittests\": \"49aec61d2885ea3049eefcda87ffa14e548a0f3a\", \"cc_unittests\": \"86b9a76dfb84c577d9a467766b4bf81385b7cbb7\", \"chrome_app_unittests\": \"ebb1d9370ac69cb6538620ab36de857d9ff615a8\", \"chromedriver_unittests\": \"a0aa2bd673489914e0686f6649aff4410a2a4e2f\", \"chromeos_components_unittests\": \"17bd95285b7ac89f41373f5368f55b489e81999f\", \"chromeos_unittests\": \"5ef8b1a221ae8f9571bfe0672219e6522b31d40d\", \"components_browsertests\": \"74a95c4e1d48319cc3363e9026f960b5a1f14d72\", \"components_unittests\": \"9e394d2c28397177921f8d17e054500c8ee4366d\", \"compositor_unittests\": \"7e21b8653630f74672d5dc51e8c8cdd5aba774d1\", \"content_browsertests\": \"6a6545562e27a359c6acdde2daf0d474a365adc7\", \"content_unittests\": \"818c3f39850c713adf1fbe321253888ae46e0c61\", \"crashpad_tests\": \"706f5165d64c8596da518e8d79aab25069eae71d\", \"crypto_unittests\": \"626bc967f8be31ccf00a2203943105f8d42c785f\", \"dbus_unittests\": \"55e8c275910c554d8fa2ad7f14fe2e178f68bdcb\", \"device_unittests\": \"a6f4be5337ed7c70106e8957f828f995c31299dd\", \"display_unittests\": \"fac846d67b3489f97d3083b4dbdbb1e033edab6d\", \"events_unittests\": \"3efe412eb53d280fd2cf3015005eb3ab081d930f\", \"exo_unittests\": \"a1460e705205edeaf0cb64d8b9168035a82c5b62\", \"extensions_browsertests\": \"4ec8d09a8d6c73f47f6f22553be442c4c9fc74ee\", \"extensions_unittests\": \"21a062b0a513ebd9b7669dd5876b7c6f37b3ea54\", \"filesystem_service_unittests\": \"151ae4bf43d71c5be881821fd76b8f8cd98ba1aa\", \"gcm_unit_tests\": \"1d88ae75b0e80ceaa6c03658c8440be1019f8ee4\", \"gfx_unittests\": \"8ae0b5b0bcd48d7cb54f72c7369d3e91bc11f71a\", \"gin_unittests\": \"0b603775d209b2b1edf78aa6344a72e0142594a2\", \"gl_unittests_ozone\": \"ff2cf9cbdad03821e31db8a3962e109e254ff8ac\", \"google_apis_unittests\": \"905661e86770eaa88503f55312dc85d56e5c8b96\", \"gpu_unittests\": \"3a140b3ab7ce8c7a69157027bfa9d78eddba6418\", \"gwp_asan_unittests\": \"5d01b9ba10ce5dcd0efe026c84b98d8debfb300e\", \"interactive_ui_tests\": \"e73ced913bd1247eb429ce151fa8e211a3770220\", \"ipc_tests\": \"7a98bb84d39230104846645a3194ae5b4b57bc26\", \"jingle_unittests\": \"ebb36559572ace67cf55bcbac7390d6d4a9182c8\", \"keyboard_unittests\": \"5e86d6ec104c713fcfd07f23faf718daea1a214f\", \"latency_unittests\": \"53b17b61e1a8b1441bf2fcd93c72c69f9af63e3d\", \"libjingle_xmpp_unittests\": \"55b13351044b672c19096fc42b35ffaa392b2cd6\", \"liburlpattern_unittests\": \"32664aca730aeae07a4a41a06485c066a860bd88\", \"media_blink_unittests\": \"e04f0937f6c68163d58f667c3e54be8a154a2083\", \"media_unittests\": \"8335adf01bb3bca86171a6f0d7132cf0a0aa29d4\", \"message_center_unittests\": \"5e86daec344308f8781bf9c73a15deafb7d6a6eb\", \"midi_unittests\": \"fe855bcfb79cce1b9c7ffe8ec86c2f091d7dd65c\", \"mojo_core_unittests\": \"c9741432eede47eaf0cc08f785a23cb540a7cfa8\", \"mojo_unittests\": \"e627734ebda1ba0e6a2095eb073d8e50e5e5f14c\", \"nacl_helper_nonsfi_unittests\": \"01630d7a00584f1827e337484ac2ac6edd788805\", \"nacl_loader_unittests\": \"0ea25143be906f7536fc2ef156dd9063df62217f\", \"native_theme_unittests\": \"e45f080d924ea18dcd1559b13d3a4743ecb74529\", \"net_unittests\": \"f5584e34ebd76045a709fa63e71a17301d6888a0\", \"ozone_gl_unittests\": \"a7472afba56c417869376698b3afd48604317bcf\", \"ozone_unittests\": \"9cb2f048be68cd12b884c77631c408adb3bcbdc1\", \"ozone_x11_unittests\": \"dc0be1b49f9c4b4f0b6f3e88955929b59b6e0312\", \"pdf_unittests\": \"cee096ae669ec04ea655ec5027dc16a82b3ec772\", \"perfetto_unittests\": \"eecc04238824aa9aff08b27e05a89cd543c184c9\", \"ppapi_unittests\": \"3c15b39e891816187b5d17f6082b06824f1708f1\", \"printing_unittests\": \"beb2afae8cd03316237a81804a1a77e714e459ee\", \"remoting_unittests\": \"dcae49179f3ebf394f8e11cdb8cdb960d6b48377\", \"sandbox_linux_unittests\": \"76bc19ca9b8613f6c1d097de1686ee7f6d56579e\", \"service_manager_unittests\": \"36be213193c451277a04c6bd9c5782d8ef67e00b\", \"services_unittests\": \"edca17c67312adf09823b9598cbb4a2b77298333\", \"shell_dialogs_unittests\": \"624e374bffdad1b49af540b9b75129d6b758d925\", \"shell_encryption_unittests\": \"1d190d48eb5f058ffff52efc42ac598d2256907f\", \"skia_unittests\": \"8dc2692a2882a11e03bfe65ad71bfcf4f91c005e\", \"snapshot_unittests\": \"a4fdb7c2666b4795ce2aa50a59dba05c92e0c0dc\", \"sql_unittests\": \"3f6e32585f28d4aa83f3b7fd10822c17594a8a12\", \"storage_unittests\": \"95e7d891954741ab8d0a51f0c5ea6b2aaf6a0236\", \"sync_integration_tests\": \"3edfdff31049c006fd0c390ab791e26e58939579\", \"traffic_annotation_auditor_unittests\": \"62a4a2fe0d39b2f9884b2abb52bf10e62fa05715\", \"ui_base_unittests\": \"41ac297c201cdcfad51638fa91296f50d071f567\", \"ui_chromeos_unittests\": \"687a1e3865cc24e40f86fc7e2a68dce44e705577\", \"ui_touch_selection_unittests\": \"f9e29d90e1dbc976ac0f47e866d90e8ff42eef69\", \"unit_tests\": \"3c382e0b947f076067618101abbd0834d448d6f1\", \"url_unittests\": \"3d4c9e7b1be46e8c8fb64aa7239ec143bfa76cbb\", \"usage_time_limit_unittests\": \"520d862a659b0669a7007575dc18b980f1b40ad6\", \"views_unittests\": \"0c100e88d7d36e980d9946ab8313e6af92dc53ab\", \"viz_unittests\": \"164f245647a22dd00c8fdfc64439e65224fa3a56\", \"wayland_client_perftests\": \"7c9678498ce8cbc1fe0859cb71e55ed0162a9894\", \"wm_unittests\": \"1e13635159ca4421da54046fed12c5724e693379\", \"wtf_unittests\": \"0a4c9fde14d3c750a4d5981503a83d2e48715221\", \"zlib_unittests\": \"1145f91ee339bf8e66478bc22f32aa1ca15d762a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_578c225c2c50d5653cce8ddfaab266952737b31d66b6113fbb64cc6f5229aebd_v2\", \"builder_81975214af25fe3c157931ccf4182e1c4ae2635ce20b925a941b30b87e2fcb1d_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_b624744545b53f30069a9e0f9d655dd80cb0688eb6f8753c5d4d1f02e2478691_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_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-165-pzoq\"], \"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\": [\"5685-1877293\"], \"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- PDFExtensionJSTest.AnnotationsFeatureEnabled\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612977905847550",
"status": "COMPLETED",
"status_changed_ts": "1612977907194877",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45305",
"builder:linux-chromeos-rel",
"buildset:commit/git/55d634a021287f142579d032e63012577daa9f5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/55d634a021287f142579d032e63012577daa9f5c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021094165468130128",
"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/8855664103336511568/+/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:51a5c6b54694c610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612983576349471",
"url": "https://ci.chromium.org/b/8855664103336511568",
"utcnow_ts": "1612983577116408"
}
}
[18:59:37.156] Invocation finished in 1h39m26.437996872s with status FAILED