[03:38:42.721] New invocation is queued and will start shortly
[03:38:43.972] Starting the invocation (attempt 1)
[03:38:44.007] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:38:44.007] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:38:44.007] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020874056797488320",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4e78393f3f93b23eb7d5d9eb14b02df6ec3db428\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9020874056797488320",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4e78393f3f93b23eb7d5d9eb14b02df6ec3db428",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e78393f3f93b23eb7d5d9eb14b02df6ec3db428",
"gitiles_ref:refs/heads/master"
]
}
[03:38:44.601] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613187524084659",
"id": "8855443994704471120",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e78393f3f93b23eb7d5d9eb14b02df6ec3db428\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613187524405200",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45382",
"builder:linux-chromeos-rel",
"buildset:commit/git/4e78393f3f93b23eb7d5d9eb14b02df6ec3db428",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e78393f3f93b23eb7d5d9eb14b02df6ec3db428",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020874056797488320",
"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/8855443994704471120/+/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": "1613187524405310",
"url": "https://ci.chromium.org/b/8855443994704471120",
"utcnow_ts": "1613187524523434"
}
}
[03:38:44.601] Task URL: https://ci.chromium.org/b/8855443994704471120
[03:38:44.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:2:0) after 1m0s
[03:39:44.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:2:0)
[03:39:44.672] Timer tick, asking Buildbucket for the build status
[03:39:44.764] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:44.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:3:0) after 1m0s
[03:40:44.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:3:0)
[03:40:44.813] Timer tick, asking Buildbucket for the build status
[03:40:44.900] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:44.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:4:0) after 1m0s
[03:41:44.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:4:0)
[03:41:44.918] Timer tick, asking Buildbucket for the build status
[03:41:44.984] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:44.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:5:0) after 1m0s
[03:42:45.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:5:0)
[03:42:45.101] Timer tick, asking Buildbucket for the build status
[03:42:45.167] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:45.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:6:0) after 1m0s
[03:43:45.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:6:0)
[03:43:45.237] Timer tick, asking Buildbucket for the build status
[03:43:45.327] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:7:0) after 1m0s
[03:44:45.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:7:0)
[03:44:45.400] Timer tick, asking Buildbucket for the build status
[03:44:45.586] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:45.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:8:0) after 1m0s
[03:45:45.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:8:0)
[03:45:45.651] Timer tick, asking Buildbucket for the build status
[03:45:45.721] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:45.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:9:0) after 1m0s
[03:46:45.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:9:0)
[03:46:45.777] Timer tick, asking Buildbucket for the build status
[03:46:45.845] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:45.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:10:0) after 1m0s
[03:47:45.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:10:0)
[03:47:45.916] Timer tick, asking Buildbucket for the build status
[03:47:46.008] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:46.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:11:0) after 1m0s
[03:48:46.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:11:0)
[03:48:46.071] Timer tick, asking Buildbucket for the build status
[03:48:46.173] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:46.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:12:0) after 1m0s
[03:49:46.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:12:0)
[03:49:46.332] Timer tick, asking Buildbucket for the build status
[03:49:49.878] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:49.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:13:0) after 1m0s
[03:50:49.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:13:0)
[03:50:49.952] Timer tick, asking Buildbucket for the build status
[03:50:50.032] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:50.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:14:0) after 1m0s
[03:51:50.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:14:0)
[03:51:50.121] Timer tick, asking Buildbucket for the build status
[03:51:50.300] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:50.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:15:0) after 1m0s
[03:52:50.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:15:0)
[03:52:50.352] Timer tick, asking Buildbucket for the build status
[03:52:50.441] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:50.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:16:0) after 1m0s
[03:53:50.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:16:0)
[03:53:50.460] Timer tick, asking Buildbucket for the build status
[03:53:50.561] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:50.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:17:0) after 1m0s
[03:54:50.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:17:0)
[03:54:50.579] Timer tick, asking Buildbucket for the build status
[03:54:50.797] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:50.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:18:0) after 1m0s
[03:55:50.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:18:0)
[03:55:50.794] Timer tick, asking Buildbucket for the build status
[03:55:50.861] Build 8855443994704471120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:50.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:19:0) after 1m0s
[03:56:45.865] Received PubSub notification, asking Buildbucket for the build status
[03:56:45.944] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:50.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:19:0)
[03:56:50.990] Timer tick, asking Buildbucket for the build status
[03:56:51.056] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:51.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:21:0) after 1m0s
[03:57:51.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:21:0)
[03:57:51.102] Timer tick, asking Buildbucket for the build status
[03:57:51.307] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:51.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:22:0) after 1m0s
[03:58:51.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:22:0)
[03:58:51.423] Timer tick, asking Buildbucket for the build status
[03:58:51.721] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:51.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:23:0) after 1m0s
[03:59:51.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:23:0)
[03:59:51.741] Timer tick, asking Buildbucket for the build status
[03:59:51.929] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:51.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:24:0) after 1m0s
[04:00:51.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:24:0)
[04:00:51.902] Timer tick, asking Buildbucket for the build status
[04:00:51.995] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:51.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:25:0) after 1m0s
[04:01:52.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:25:0)
[04:01:52.011] Timer tick, asking Buildbucket for the build status
[04:01:52.087] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:52.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:26:0) after 1m0s
[04:02:52.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:26:0)
[04:02:52.103] Timer tick, asking Buildbucket for the build status
[04:02:52.415] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:52.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:27:0) after 1m0s
[04:03:52.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:27:0)
[04:03:52.421] Timer tick, asking Buildbucket for the build status
[04:03:52.525] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:52.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:28:0) after 1m0s
[04:04:52.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:28:0)
[04:04:52.544] Timer tick, asking Buildbucket for the build status
[04:04:52.684] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:52.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:29:0) after 1m0s
[04:05:52.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:29:0)
[04:05:52.705] Timer tick, asking Buildbucket for the build status
[04:05:52.793] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:52.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:30:0) after 1m0s
[04:06:52.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:30:0)
[04:06:52.797] Timer tick, asking Buildbucket for the build status
[04:06:53.005] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:53.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:31:0) after 1m0s
[04:07:53.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:31:0)
[04:07:53.051] Timer tick, asking Buildbucket for the build status
[04:07:53.345] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:53.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:32:0) after 1m0s
[04:08:53.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:32:0)
[04:08:53.357] Timer tick, asking Buildbucket for the build status
[04:08:53.437] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:53.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:33:0) after 1m0s
[04:09:53.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:33:0)
[04:09:53.466] Timer tick, asking Buildbucket for the build status
[04:09:53.555] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:53.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:34:0) after 1m0s
[04:10:53.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:34:0)
[04:10:53.645] Timer tick, asking Buildbucket for the build status
[04:10:53.715] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:53.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:35:0) after 1m0s
[04:11:53.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:35:0)
[04:11:53.837] Timer tick, asking Buildbucket for the build status
[04:11:54.303] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:54.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:36:0) after 1m0s
[04:12:54.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:36:0)
[04:12:54.330] Timer tick, asking Buildbucket for the build status
[04:12:54.409] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:54.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:37:0) after 1m0s
[04:13:54.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:37:0)
[04:13:54.429] Timer tick, asking Buildbucket for the build status
[04:13:54.784] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:54.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:38:0) after 1m0s
[04:14:54.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:38:0)
[04:14:54.816] Timer tick, asking Buildbucket for the build status
[04:14:55.370] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:55.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:39:0) after 1m0s
[04:15:55.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:39:0)
[04:15:55.370] Timer tick, asking Buildbucket for the build status
[04:15:55.449] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:55.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:40:0) after 1m0s
[04:16:55.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:40:0)
[04:16:55.526] Timer tick, asking Buildbucket for the build status
[04:16:55.596] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:55.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:41:0) after 1m0s
[04:17:55.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:41:0)
[04:17:55.714] Timer tick, asking Buildbucket for the build status
[04:17:56.036] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:56.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:42:0) after 1m0s
[04:18:56.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:42:0)
[04:18:56.096] Timer tick, asking Buildbucket for the build status
[04:18:56.191] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:56.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:43:0) after 1m0s
[04:19:56.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:43:0)
[04:19:56.211] Timer tick, asking Buildbucket for the build status
[04:19:56.324] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:56.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:44:0) after 1m0s
[04:20:56.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:44:0)
[04:20:56.347] Timer tick, asking Buildbucket for the build status
[04:20:56.556] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:56.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:45:0) after 1m0s
[04:21:56.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:45:0)
[04:21:56.553] Timer tick, asking Buildbucket for the build status
[04:21:56.681] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:56.681] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:46:0) after 1m0s
[04:22:56.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:46:0)
[04:22:56.729] Timer tick, asking Buildbucket for the build status
[04:22:56.826] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:56.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:47:0) after 1m0s
[04:23:56.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:47:0)
[04:23:56.849] Timer tick, asking Buildbucket for the build status
[04:23:57.093] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:57.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:48:0) after 1m0s
[04:24:57.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:48:0)
[04:24:57.134] Timer tick, asking Buildbucket for the build status
[04:24:57.265] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:57.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:49:0) after 1m0s
[04:25:57.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:49:0)
[04:25:57.277] Timer tick, asking Buildbucket for the build status
[04:25:57.351] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:57.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:50:0) after 1m0s
[04:26:57.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:50:0)
[04:26:57.344] Timer tick, asking Buildbucket for the build status
[04:26:57.435] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:51:0) after 1m0s
[04:27:57.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:51:0)
[04:27:57.479] Timer tick, asking Buildbucket for the build status
[04:27:57.585] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:57.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:52:0) after 1m0s
[04:28:57.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:52:0)
[04:28:57.605] Timer tick, asking Buildbucket for the build status
[04:28:57.805] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:57.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:53:0) after 1m0s
[04:29:57.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:53:0)
[04:29:57.912] Timer tick, asking Buildbucket for the build status
[04:29:58.060] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:58.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:54:0) after 1m0s
[04:30:58.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:54:0)
[04:30:58.069] Timer tick, asking Buildbucket for the build status
[04:30:58.232] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:58.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:55:0) after 1m0s
[04:31:58.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:55:0)
[04:31:58.371] Timer tick, asking Buildbucket for the build status
[04:31:58.797] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:58.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:56:0) after 1m0s
[04:32:58.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:56:0)
[04:32:58.824] Timer tick, asking Buildbucket for the build status
[04:32:58.917] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:58.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:57:0) after 1m0s
[04:33:58.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:57:0)
[04:33:58.915] Timer tick, asking Buildbucket for the build status
[04:33:59.012] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:59.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:58:0) after 1m0s
[04:34:59.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:58:0)
[04:34:59.077] Timer tick, asking Buildbucket for the build status
[04:34:59.314] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:59.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:59:0) after 1m0s
[04:35:59.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:59:0)
[04:35:59.390] Timer tick, asking Buildbucket for the build status
[04:35:59.598] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:59.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:60:0) after 1m0s
[04:36:59.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:60:0)
[04:36:59.687] Timer tick, asking Buildbucket for the build status
[04:36:59.769] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:61:0) after 1m0s
[04:37:59.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:61:0)
[04:37:59.762] Timer tick, asking Buildbucket for the build status
[04:37:59.844] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:62:0) after 1m0s
[04:38:59.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:62:0)
[04:38:59.951] Timer tick, asking Buildbucket for the build status
[04:39:00.190] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:00.190] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:63:0) after 1m0s
[04:40:00.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:63:0)
[04:40:00.189] Timer tick, asking Buildbucket for the build status
[04:40:00.418] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:00.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:64:0) after 1m0s
[04:41:00.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:64:0)
[04:41:00.490] Timer tick, asking Buildbucket for the build status
[04:41:00.561] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:00.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:65:0) after 1m0s
[04:42:00.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:65:0)
[04:42:00.594] Timer tick, asking Buildbucket for the build status
[04:42:00.698] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:00.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:66:0) after 1m0s
[04:43:00.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:66:0)
[04:43:00.761] Timer tick, asking Buildbucket for the build status
[04:43:00.997] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:00.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:67:0) after 1m0s
[04:44:01.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:67:0)
[04:44:01.065] Timer tick, asking Buildbucket for the build status
[04:44:01.196] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:01.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:68:0) after 1m0s
[04:45:01.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:68:0)
[04:45:01.231] Timer tick, asking Buildbucket for the build status
[04:45:01.319] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:01.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:69:0) after 1m0s
[04:46:01.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:69:0)
[04:46:01.338] Timer tick, asking Buildbucket for the build status
[04:46:01.426] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:01.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:70:0) after 1m0s
[04:47:01.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:70:0)
[04:47:01.467] Timer tick, asking Buildbucket for the build status
[04:47:01.550] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:01.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:71:0) after 1m0s
[04:48:01.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:71:0)
[04:48:01.655] Timer tick, asking Buildbucket for the build status
[04:48:01.751] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:01.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:72:0) after 1m0s
[04:49:01.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:72:0)
[04:49:01.734] Timer tick, asking Buildbucket for the build status
[04:49:01.958] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:01.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:73:0) after 1m0s
[04:50:02.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:73:0)
[04:50:02.045] Timer tick, asking Buildbucket for the build status
[04:50:02.281] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:02.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:74:0) after 1m0s
[04:51:02.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:74:0)
[04:51:02.311] Timer tick, asking Buildbucket for the build status
[04:51:02.397] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:02.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:75:0) after 1m0s
[04:52:02.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:75:0)
[04:52:02.417] Timer tick, asking Buildbucket for the build status
[04:52:02.590] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:02.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:76:0) after 1m0s
[04:53:02.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:76:0)
[04:53:02.592] Timer tick, asking Buildbucket for the build status
[04:53:02.678] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:02.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:77:0) after 1m0s
[04:54:02.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:77:0)
[04:54:02.698] Timer tick, asking Buildbucket for the build status
[04:54:02.799] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:02.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:78:0) after 1m0s
[04:55:02.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:78:0)
[04:55:02.852] Timer tick, asking Buildbucket for the build status
[04:55:02.992] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:02.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:79:0) after 1m0s
[04:56:03.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:79:0)
[04:56:03.013] Timer tick, asking Buildbucket for the build status
[04:56:03.095] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:03.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:80:0) after 1m0s
[04:57:03.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:80:0)
[04:57:03.169] Timer tick, asking Buildbucket for the build status
[04:57:03.263] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:03.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:81:0) after 1m0s
[04:58:03.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:81:0)
[04:58:03.282] Timer tick, asking Buildbucket for the build status
[04:58:03.358] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:03.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:82:0) after 1m0s
[04:59:03.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:82:0)
[04:59:03.384] Timer tick, asking Buildbucket for the build status
[04:59:03.754] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:03.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:83:0) after 1m0s
[05:00:03.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:83:0)
[05:00:03.787] Timer tick, asking Buildbucket for the build status
[05:00:03.912] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:03.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:84:0) after 1m0s
[05:01:03.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:84:0)
[05:01:03.985] Timer tick, asking Buildbucket for the build status
[05:01:04.092] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:04.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:85:0) after 1m0s
[05:02:04.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:85:0)
[05:02:04.196] Timer tick, asking Buildbucket for the build status
[05:02:04.383] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:04.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:86:0) after 1m0s
[05:03:04.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:86:0)
[05:03:04.422] Timer tick, asking Buildbucket for the build status
[05:03:04.502] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:04.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:87:0) after 1m0s
[05:04:04.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:87:0)
[05:04:04.616] Timer tick, asking Buildbucket for the build status
[05:04:04.785] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:04.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:88:0) after 1m0s
[05:05:04.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:88:0)
[05:05:04.898] Timer tick, asking Buildbucket for the build status
[05:05:05.142] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:05.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:89:0) after 1m0s
[05:06:05.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:89:0)
[05:06:05.336] Timer tick, asking Buildbucket for the build status
[05:06:05.429] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:05.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:90:0) after 1m0s
[05:07:05.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:90:0)
[05:07:05.492] Timer tick, asking Buildbucket for the build status
[05:07:05.661] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:05.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:91:0) after 1m0s
[05:08:05.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:91:0)
[05:08:05.709] Timer tick, asking Buildbucket for the build status
[05:08:05.802] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:05.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:92:0) after 1m0s
[05:09:05.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:92:0)
[05:09:05.833] Timer tick, asking Buildbucket for the build status
[05:09:06.058] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:06.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:93:0) after 1m0s
[05:10:06.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:93:0)
[05:10:06.108] Timer tick, asking Buildbucket for the build status
[05:10:06.211] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:06.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:94:0) after 1m0s
[05:11:06.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:94:0)
[05:11:06.301] Timer tick, asking Buildbucket for the build status
[05:11:06.724] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:06.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:95:0) after 1m0s
[05:12:06.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:95:0)
[05:12:06.781] Timer tick, asking Buildbucket for the build status
[05:12:06.875] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:06.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:96:0) after 1m0s
[05:13:06.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:96:0)
[05:13:06.966] Timer tick, asking Buildbucket for the build status
[05:13:07.149] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:07.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:97:0) after 1m0s
[05:14:07.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:97:0)
[05:14:07.174] Timer tick, asking Buildbucket for the build status
[05:14:07.395] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:07.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:98:0) after 1m0s
[05:15:07.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:98:0)
[05:15:07.866] Timer tick, asking Buildbucket for the build status
[05:15:07.954] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:07.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:99:0) after 1m0s
[05:16:08.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:99:0)
[05:16:08.021] Timer tick, asking Buildbucket for the build status
[05:16:08.101] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:08.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:100:0) after 1m0s
[05:17:08.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:100:0)
[05:17:08.198] Timer tick, asking Buildbucket for the build status
[05:17:08.528] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:08.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:101:0) after 1m0s
[05:18:08.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:101:0)
[05:18:08.582] Timer tick, asking Buildbucket for the build status
[05:18:08.950] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:08.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:102:0) after 1m0s
[05:19:10.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:102:0)
[05:19:10.178] Timer tick, asking Buildbucket for the build status
[05:19:10.349] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:10.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:103:0) after 1m0s
[05:20:10.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:103:0)
[05:20:10.351] Timer tick, asking Buildbucket for the build status
[05:20:10.496] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:10.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:104:0) after 1m0s
[05:21:10.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:104:0)
[05:21:10.551] Timer tick, asking Buildbucket for the build status
[05:21:10.838] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:10.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:105:0) after 1m0s
[05:22:10.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:105:0)
[05:22:10.911] Timer tick, asking Buildbucket for the build status
[05:22:10.997] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:10.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:106:0) after 1m0s
[05:23:11.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:106:0)
[05:23:11.075] Timer tick, asking Buildbucket for the build status
[05:23:11.230] Build 8855443994704471120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:11.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020874056797488320:107:0) after 1m0s
[05:23:50.454] Received PubSub notification, asking Buildbucket for the build status
[05:23:50.535] Build 8855443994704471120: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:23:50.535] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613193829714418",
"created_by": "project:chromium",
"created_ts": "1613187524084659",
"failure_reason": "BUILD_FAILURE",
"id": "8855443994704471120",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e78393f3f93b23eb7d5d9eb14b02df6ec3db428\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- CellularSetupEsimFlowUiTest.All\\n\\n- CellularSetupEsimFlowUiTestV3.All\"}, \"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\": \"4e78393f3f93b23eb7d5d9eb14b02df6ec3db428\", \"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-65-35tm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45382, \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"4e78393f3f93b23eb7d5d9eb14b02df6ec3db428\", \"got_revision_cp\": \"refs/heads/master@{#853793}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"554b7ee535b3eca3f041dc45d00de5cbd490729c\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"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\": \"4e78393f3f93b23eb7d5d9eb14b02df6ec3db428\", \"swarm_hashes_refs/heads/master(at){#853793}_without_patch\": {\"absl_hardening_tests\": \"c35160ddbe641c79abbc2dfcaac3baac5b578c4d\", \"accessibility_unittests\": \"777e88558c6634ec948707ae96f6fd3865abfa80\", \"angle_unittests\": \"066a97031d1426d8a355ccb7fb1fe4168a39f669\", \"app_list_unittests\": \"4b5b018761603052d07601f81c0895df38de2094\", \"app_shell_unittests\": \"86ec74b69a9177b4c9714fd3b57cc4c060693101\", \"ash_unittests\": \"9901903f5a8e1bb143c04831961ef8061a4b3bb3\", \"aura_unittests\": \"65829aa2fde8fe77e8e3139d11abbeabd917f2f8\", \"base_unittests\": \"4c75422efd7e56e33db73c5ebebc8128c1a66c62\", \"base_util_unittests\": \"55a2b0b6f86ed0e8a1a94fb0b099a73aad8bfadb\", \"blink_common_unittests\": \"5f1896f3bfb30d8c9e2434ba29db2444172b7c51\", \"blink_fuzzer_unittests\": \"6da835c80242591e6ca86d3ed96ed65bdd788bfa\", \"blink_heap_unittests\": \"f5843c26f53f8200cc7b778f270f516a2c5c4df3\", \"blink_platform_unittests\": \"fd19a8afc1e926eccf0b7fa5819dce9fd602e023\", \"blink_unittests\": \"48d02fd01160352d3e870ae5ce44756c53e1e2ae\", \"boringssl_crypto_tests\": \"6de98394daea2aafa54ce14836acbb4f07355a72\", \"boringssl_ssl_tests\": \"6ca5f88c0a4fa4f6d9db4017e00b5fc134ba0d93\", \"browser_tests\": \"40a754dde0bd588eec9bd11c55e0b4d7d6e04d54\", \"capture_unittests\": \"af4121048bc80c9433644c5191febb12d63067e3\", \"cast_unittests\": \"1e370a2550a772f104f5060cf1e643e18167e0c8\", \"cc_unittests\": \"e7ccd2704b58ed2cb61b9e52ee1d6acfe81ecfe3\", \"chrome_app_unittests\": \"84091ad37527cbd47286a2093cb22f8455d70029\", \"chromedriver_unittests\": \"69e714cc36c42cfb3606adfd54ef9e3745103c78\", \"chromeos_components_unittests\": \"011b4043db86d720ec4d0ae84c1ec1fc71059f0e\", \"chromeos_unittests\": \"8f7049c61872c542e2aa17fe12c60a3ad83b88cc\", \"components_browsertests\": \"307e30f8aa217b6525672ce3bc058c1ad3dcbe92\", \"components_unittests\": \"37bc012adc7196c208b574a07c6e3e96783ffb9b\", \"compositor_unittests\": \"dadfa4e6936ae714a6ea99a53a487706140e74d6\", \"content_browsertests\": \"ac903bf24a5014f17efb19833e462c78527ed3e6\", \"content_unittests\": \"930e666b55b4df6e754d52accb9be1ade3ca65aa\", \"crashpad_tests\": \"65aa32ce2f48d90d673af09f4caccbd1d95359f9\", \"crypto_unittests\": \"b962a0df261314ec353e11f212750fcd5b16d3e0\", \"dbus_unittests\": \"bbefe4876586a8cf54bd6de0aa6f76b548719cc4\", \"device_unittests\": \"b005ec2ff58cdcbe4851366ff68c3e5e0224d671\", \"display_unittests\": \"48767dd18b652faa7f4fe3eb5a0da8b858da5b4d\", \"events_unittests\": \"69b6b613e861e725a85cd155699be97ea04ecb9f\", \"exo_unittests\": \"5a7652992f243fe465f24ae2c66aa9cfb0da7103\", \"extensions_browsertests\": \"3bb8404f96fc33408155c3fdd70d10dfe9496414\", \"extensions_unittests\": \"b8c015b4d956df7bbbe19a8ffa526593ab842203\", \"filesystem_service_unittests\": \"878aa2d0cc0df31e77d150f143cbe8225e7b24f8\", \"gcm_unit_tests\": \"c6bc7b80dccebfeb520af1e87b84b22d8f1a5e4a\", \"gfx_unittests\": \"bf4dbcc5b1dcfb634b4717f8dda0edb0011e4486\", \"gin_unittests\": \"9f938124220890d7f0db956a266930727a2684d6\", \"gl_unittests_ozone\": \"e3508b0d5deb34b7e4c04f61f8d54ec8b2029cf8\", \"google_apis_unittests\": \"488dbfa645061e8ddc81926096be7a54e63efd5b\", \"gpu_unittests\": \"36d300dac1f8d5b4856b6d6d0242d4d19c329c3d\", \"gwp_asan_unittests\": \"9af5ce2ccdb51a7711fd70eab41be0bc20be69c3\", \"interactive_ui_tests\": \"4f9f07eaa32d6330c09036d6ae4f3b4863e9fb36\", \"ipc_tests\": \"07dbd207831f3422561835c526ff74857a5f6fbf\", \"jingle_unittests\": \"8691fede003426bf68f68953f2a981f4fce2e9fe\", \"keyboard_unittests\": \"5ffbf015fc27ead53697c02a09f901d53d30c013\", \"latency_unittests\": \"b8331799864b81539f37c4981fcda94615beea70\", \"libjingle_xmpp_unittests\": \"8c1708b540e8f5669a7ec34b500880a338139c57\", \"liburlpattern_unittests\": \"45312b2caef78731127182e3d301dd86fbc2b3c2\", \"media_blink_unittests\": \"3e7b838d645a483442bd13e0e5923be52ed01193\", \"media_unittests\": \"f8431e05a20b19640a5e486e58ad03c92e93f8b7\", \"message_center_unittests\": \"875b793265276e02a981121e78ba0e705a91c358\", \"midi_unittests\": \"d5d5587db1f405e58b189d6abc89eacfd0030517\", \"mojo_core_unittests\": \"ccd4fbf210370a01f2af55a7a8ae243bee2a7f32\", \"mojo_unittests\": \"7a325ccd743981d9637182815f236b05971925e3\", \"nacl_helper_nonsfi_unittests\": \"52412e069b676b8447c5dfad2c30bcbdabc60bce\", \"nacl_loader_unittests\": \"c6b8867d4a6fdd88c456cb5e9d902b0b630ea67c\", \"native_theme_unittests\": \"78c7d18f4f159c2dea4ef9c5210e392fe7d03eb9\", \"net_unittests\": \"bdf22eee2830826997935ac35bc18bff3dd618b4\", \"ozone_gl_unittests\": \"8eaeac6f2df09096315a1a42268ac13955c55ce7\", \"ozone_unittests\": \"5887bffa06717153c45bbe24a8c98b056604f70f\", \"ozone_x11_unittests\": \"7211e0d1562385ad6a12fb377adb8f36c12008af\", \"pdf_unittests\": \"24131bb8c97cd8605aeade21237ef7e7528fa04b\", \"perfetto_unittests\": \"77772977f7ee7abb13c64e4d52e5bc389080f272\", \"ppapi_unittests\": \"9d493717dc76fd124181981b119e6750e8754cb9\", \"printing_unittests\": \"6f160115dc7b34ca66e3ab24e03abb39b0e3ea76\", \"remoting_unittests\": \"f7446189c4bc5a2fa50a7b993d2bb38421bc0178\", \"sandbox_linux_unittests\": \"9b72737cfbb3fac1647b528cbe2d78359a559417\", \"service_manager_unittests\": \"0c9b7936593fab0411759eef169bd391d5189301\", \"services_unittests\": \"156bb8b7fef48e82d99248501ebc34511a2231ee\", \"shell_dialogs_unittests\": \"780b8e78b44340faff3c3520246e51cf6d5ef9ba\", \"shell_encryption_unittests\": \"bdca214b80d747343fe0baeb719acfaa76c260a1\", \"skia_unittests\": \"adcfd6fc67358216ef2c9ed4792f9eab5c30df65\", \"snapshot_unittests\": \"318740ec77f3c1983552abf762c2fa077a842e18\", \"sql_unittests\": \"7a52276bd95df6f17a17e852288c86fc4b7e2e3f\", \"storage_unittests\": \"a84a4feffddee4724dd2450cb3c75b38fa157d87\", \"sync_integration_tests\": \"191fde1f7eef9b1754debb5133d053e9141f066d\", \"traffic_annotation_auditor_unittests\": \"1c0cb48828786d2188f9413bd4c9c4e4b28aae72\", \"ui_base_unittests\": \"dc04534a0c5977c73688aad584c6cc45c480e733\", \"ui_chromeos_unittests\": \"0056cda9c55c1888b22901ddfaa493d24a3aa9e9\", \"ui_touch_selection_unittests\": \"aa4e1cc134d98d50427d4d670047016996b40d69\", \"unit_tests\": \"4e13ae97a3f5300e20f05fa329ffbf5e4d4fd0c4\", \"url_unittests\": \"1b1c2479a44c12135f3f0bc163c14cc3036e5d65\", \"usage_time_limit_unittests\": \"be09bc2d7b0083487fc719e48018266cc6175353\", \"views_unittests\": \"5137450d2cd823154664b4ddef7ceaedc03cd50c\", \"viz_unittests\": \"dc3c431e24f217206e97bd4296a81caabbf6dcc5\", \"wayland_client_perftests\": \"ef26912570e18fabbb32da4758a0b08efb7c780b\", \"wm_unittests\": \"a91bdbbcac9f360831d352c7843d51d7c08c6e17\", \"wtf_unittests\": \"948d53ee7945fda7862e77c814535101ebf63a99\", \"zlib_unittests\": \"7a77038b4fa3e49991b27142823093746ec12281\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0e22c04085d4f23fdc95f18e428a3d660f7cf1c501a8c991d94e3298ba2ece61_v2\", \"builder_158d0f10fa5d87b7b90de0551ce233e88e46559602c021beec49ce89728c3318_v2\", \"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_5f9879dd307b3e3de87d4273aaf77dfc3adf50a63c1d75c3b245bfdff463a933_v2\", \"builder_76783dd5c2a60df34bfc78d9ceb12670a24fdda597cce6f387a1a4911c7cdf2d_v2\", \"builder_a7b9f8880f1f76f2cce14c79e9b35da9a713af6654c6d1393cfbcc356d1ede5d_v2\", \"builder_ffe623326ac74578ce917ff55ef6cefa75094e5ad3ad5fe3409a729d79be647a_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-65-35tm\"], \"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\": [\"5690-40331dd\"], \"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- CellularSetupEsimFlowUiTest.All\\n\\n- CellularSetupEsimFlowUiTestV3.All\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613188604989315",
"status": "COMPLETED",
"status_changed_ts": "1613188605441055",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45382",
"builder:linux-chromeos-rel",
"buildset:commit/git/4e78393f3f93b23eb7d5d9eb14b02df6ec3db428",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e78393f3f93b23eb7d5d9eb14b02df6ec3db428",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020874056797488320",
"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/8855443994704471120/+/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:51b249b018240610",
"user_agent:luci-scheduler"
],
"updated_ts": "1613193830162197",
"url": "https://ci.chromium.org/b/8855443994704471120",
"utcnow_ts": "1613193830523626"
}
}
[05:23:50.535] Invocation finished in 1h45m7.830366474s with status FAILED