[15:05:05.910] New invocation is queued and will start shortly
[15:05:07.072] Starting the invocation (attempt 1)
[15:05:07.109] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:05:07.109] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:05:07.109] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019924903429530864",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a6e3d524e6a0e44354baee7882d5cfb38317de06\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019924903429530864",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/a6e3d524e6a0e44354baee7882d5cfb38317de06",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6e3d524e6a0e44354baee7882d5cfb38317de06",
"gitiles_ref:refs/heads/master"
]
}
[15:05:08.313] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614092707204559",
"id": "8854494841409040976",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a6e3d524e6a0e44354baee7882d5cfb38317de06\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614092707762075",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45667",
"builder:linux-chromeos-rel",
"buildset:commit/git/a6e3d524e6a0e44354baee7882d5cfb38317de06",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6e3d524e6a0e44354baee7882d5cfb38317de06",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019924903429530864",
"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/8854494841409040976/+/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": "1614092707762199",
"url": "https://ci.chromium.org/b/8854494841409040976",
"utcnow_ts": "1614092708295382"
}
}
[15:05:08.313] Task URL: https://ci.chromium.org/b/8854494841409040976
[15:05:08.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:2:0) after 1m0s
[15:06:08.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:2:0)
[15:06:08.370] Timer tick, asking Buildbucket for the build status
[15:06:08.691] Build 8854494841409040976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:06:08.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:3:0) after 1m0s
[15:07:08.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:3:0)
[15:07:08.767] Timer tick, asking Buildbucket for the build status
[15:07:08.877] Build 8854494841409040976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:07:08.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:4:0) after 1m0s
[15:08:08.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:4:0)
[15:08:08.920] Timer tick, asking Buildbucket for the build status
[15:08:08.993] Build 8854494841409040976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:08:08.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:5:0) after 1m0s
[15:09:09.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:5:0)
[15:09:09.093] Timer tick, asking Buildbucket for the build status
[15:09:09.155] Build 8854494841409040976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:09:09.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:6:0) after 1m0s
[15:10:02.414] Received PubSub notification, asking Buildbucket for the build status
[15:10:02.617] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:09.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:6:0)
[15:10:09.302] Timer tick, asking Buildbucket for the build status
[15:10:09.439] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:09.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:8:0) after 1m0s
[15:11:09.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:8:0)
[15:11:09.619] Timer tick, asking Buildbucket for the build status
[15:11:09.716] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:09.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:9:0) after 1m0s
[15:12:09.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:9:0)
[15:12:09.779] Timer tick, asking Buildbucket for the build status
[15:12:09.864] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:09.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:10:0) after 1m0s
[15:13:09.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:10:0)
[15:13:09.922] Timer tick, asking Buildbucket for the build status
[15:13:10.025] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:10.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:11:0) after 1m0s
[15:14:10.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:11:0)
[15:14:10.084] Timer tick, asking Buildbucket for the build status
[15:14:10.171] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:10.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:12:0) after 1m0s
[15:15:10.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:12:0)
[15:15:10.254] Timer tick, asking Buildbucket for the build status
[15:15:10.362] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:10.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:13:0) after 1m0s
[15:16:10.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:13:0)
[15:16:10.364] Timer tick, asking Buildbucket for the build status
[15:16:10.445] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:10.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:14:0) after 1m0s
[15:17:10.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:14:0)
[15:17:10.486] Timer tick, asking Buildbucket for the build status
[15:17:10.555] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:10.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:15:0) after 1m0s
[15:18:10.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:15:0)
[15:18:10.576] Timer tick, asking Buildbucket for the build status
[15:18:10.893] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:10.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:16:0) after 1m0s
[15:19:10.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:16:0)
[15:19:10.950] Timer tick, asking Buildbucket for the build status
[15:19:11.031] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:11.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:17:0) after 1m0s
[15:20:11.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:17:0)
[15:20:11.082] Timer tick, asking Buildbucket for the build status
[15:20:11.452] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:11.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:18:0) after 1m0s
[15:21:11.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:18:0)
[15:21:11.471] Timer tick, asking Buildbucket for the build status
[15:21:11.729] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:11.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:19:0) after 1m0s
[15:22:11.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:19:0)
[15:22:11.795] Timer tick, asking Buildbucket for the build status
[15:22:11.882] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:11.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:20:0) after 1m0s
[15:23:11.903] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:20:0)
[15:23:11.903] Timer tick, asking Buildbucket for the build status
[15:23:11.985] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:11.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:21:0) after 1m0s
[15:24:12.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:21:0)
[15:24:12.085] Timer tick, asking Buildbucket for the build status
[15:24:12.509] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:12.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:22:0) after 1m0s
[15:25:12.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:22:0)
[15:25:12.579] Timer tick, asking Buildbucket for the build status
[15:25:12.664] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:12.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:23:0) after 1m0s
[15:26:12.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:23:0)
[15:26:12.681] Timer tick, asking Buildbucket for the build status
[15:26:12.807] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:12.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:24:0) after 1m0s
[15:27:12.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:24:0)
[15:27:12.825] Timer tick, asking Buildbucket for the build status
[15:27:12.921] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:12.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:25:0) after 1m0s
[15:28:12.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:25:0)
[15:28:12.965] Timer tick, asking Buildbucket for the build status
[15:28:13.050] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:13.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:26:0) after 1m0s
[15:29:13.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:26:0)
[15:29:13.150] Timer tick, asking Buildbucket for the build status
[15:29:13.249] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:13.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:27:0) after 1m0s
[15:30:13.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:27:0)
[15:30:13.365] Timer tick, asking Buildbucket for the build status
[15:30:13.674] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:13.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:28:0) after 1m0s
[15:31:13.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:28:0)
[15:31:13.770] Timer tick, asking Buildbucket for the build status
[15:31:14.027] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:14.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:29:0) after 1m0s
[15:32:14.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:29:0)
[15:32:14.109] Timer tick, asking Buildbucket for the build status
[15:32:14.240] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:14.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:30:0) after 1m0s
[15:33:14.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:30:0)
[15:33:14.280] Timer tick, asking Buildbucket for the build status
[15:33:14.473] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:14.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:31:0) after 1m0s
[15:34:14.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:31:0)
[15:34:14.608] Timer tick, asking Buildbucket for the build status
[15:34:14.778] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:14.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:32:0) after 1m0s
[15:35:14.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:32:0)
[15:35:14.811] Timer tick, asking Buildbucket for the build status
[15:35:14.943] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:14.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:33:0) after 1m0s
[15:36:15.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:33:0)
[15:36:15.044] Timer tick, asking Buildbucket for the build status
[15:36:15.204] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:15.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:34:0) after 1m0s
[15:37:15.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:34:0)
[15:37:15.235] Timer tick, asking Buildbucket for the build status
[15:37:15.435] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:15.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:35:0) after 1m0s
[15:38:15.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:35:0)
[15:38:15.563] Timer tick, asking Buildbucket for the build status
[15:38:15.648] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:15.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:36:0) after 1m0s
[15:39:15.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:36:0)
[15:39:15.666] Timer tick, asking Buildbucket for the build status
[15:39:15.878] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:15.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:37:0) after 1m0s
[15:40:15.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:37:0)
[15:40:15.909] Timer tick, asking Buildbucket for the build status
[15:40:16.014] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:16.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:38:0) after 1m0s
[15:41:16.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:38:0)
[15:41:16.037] Timer tick, asking Buildbucket for the build status
[15:41:16.314] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:16.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:39:0) after 1m0s
[15:42:16.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:39:0)
[15:42:16.385] Timer tick, asking Buildbucket for the build status
[15:42:16.734] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:16.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:40:0) after 1m0s
[15:43:16.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:40:0)
[15:43:16.814] Timer tick, asking Buildbucket for the build status
[15:43:17.095] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:17.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:41:0) after 1m0s
[15:44:17.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:41:0)
[15:44:17.138] Timer tick, asking Buildbucket for the build status
[15:44:17.322] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:17.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:42:0) after 1m0s
[15:45:17.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:42:0)
[15:45:17.382] Timer tick, asking Buildbucket for the build status
[15:45:17.627] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:17.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:43:0) after 1m0s
[15:46:17.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:43:0)
[15:46:17.657] Timer tick, asking Buildbucket for the build status
[15:46:17.725] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:17.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:44:0) after 1m0s
[15:47:17.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:44:0)
[15:47:17.746] Timer tick, asking Buildbucket for the build status
[15:47:17.822] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:17.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:45:0) after 1m0s
[15:48:17.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:45:0)
[15:48:17.845] Timer tick, asking Buildbucket for the build status
[15:48:17.938] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:17.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:46:0) after 1m0s
[15:49:17.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:46:0)
[15:49:17.959] Timer tick, asking Buildbucket for the build status
[15:49:18.055] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:18.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:47:0) after 1m0s
[15:50:18.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:47:0)
[15:50:18.129] Timer tick, asking Buildbucket for the build status
[15:50:18.221] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:18.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:48:0) after 1m0s
[15:51:18.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:48:0)
[15:51:18.252] Timer tick, asking Buildbucket for the build status
[15:51:18.330] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:18.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:49:0) after 1m0s
[15:52:18.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:49:0)
[15:52:18.373] Timer tick, asking Buildbucket for the build status
[15:52:18.612] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:18.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:50:0) after 1m0s
[15:53:18.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:50:0)
[15:53:18.638] Timer tick, asking Buildbucket for the build status
[15:53:18.748] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:18.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:51:0) after 1m0s
[15:54:18.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:51:0)
[15:54:18.868] Timer tick, asking Buildbucket for the build status
[15:54:18.947] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:18.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:52:0) after 1m0s
[15:55:18.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:52:0)
[15:55:18.950] Timer tick, asking Buildbucket for the build status
[15:55:19.061] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:19.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:53:0) after 1m0s
[15:56:19.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:53:0)
[15:56:19.161] Timer tick, asking Buildbucket for the build status
[15:56:19.237] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:19.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:54:0) after 1m0s
[15:57:19.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:54:0)
[15:57:19.227] Timer tick, asking Buildbucket for the build status
[15:57:19.622] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:19.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:55:0) after 1m0s
[15:58:19.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:55:0)
[15:58:19.715] Timer tick, asking Buildbucket for the build status
[15:58:19.814] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:19.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:56:0) after 1m0s
[15:59:19.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:56:0)
[15:59:19.814] Timer tick, asking Buildbucket for the build status
[15:59:19.875] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:19.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:57:0) after 1m0s
[16:00:19.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:57:0)
[16:00:19.886] Timer tick, asking Buildbucket for the build status
[16:00:20.356] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:20.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:58:0) after 1m0s
[16:01:20.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:58:0)
[16:01:20.401] Timer tick, asking Buildbucket for the build status
[16:01:20.622] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:20.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:59:0) after 1m0s
[16:02:21.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:59:0)
[16:02:21.051] Timer tick, asking Buildbucket for the build status
[16:02:21.187] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:21.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:60:0) after 1m0s
[16:03:21.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:60:0)
[16:03:21.228] Timer tick, asking Buildbucket for the build status
[16:03:21.309] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:21.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:61:0) after 1m0s
[16:04:21.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:61:0)
[16:04:21.354] Timer tick, asking Buildbucket for the build status
[16:04:21.807] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:21.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:62:0) after 1m0s
[16:05:21.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:62:0)
[16:05:21.805] Timer tick, asking Buildbucket for the build status
[16:05:22.248] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:22.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:63:0) after 1m0s
[16:06:22.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:63:0)
[16:06:22.288] Timer tick, asking Buildbucket for the build status
[16:06:22.366] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:22.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:64:0) after 1m0s
[16:07:22.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:64:0)
[16:07:22.508] Timer tick, asking Buildbucket for the build status
[16:07:22.618] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:22.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:65:0) after 1m0s
[16:08:22.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:65:0)
[16:08:22.626] Timer tick, asking Buildbucket for the build status
[16:08:22.721] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:22.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:66:0) after 1m0s
[16:09:22.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:66:0)
[16:09:22.712] Timer tick, asking Buildbucket for the build status
[16:09:22.833] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:22.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:67:0) after 1m0s
[16:10:22.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:67:0)
[16:10:22.876] Timer tick, asking Buildbucket for the build status
[16:10:22.999] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:22.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:68:0) after 1m0s
[16:11:23.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:68:0)
[16:11:23.011] Timer tick, asking Buildbucket for the build status
[16:11:23.092] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:23.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:69:0) after 1m0s
[16:12:23.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:69:0)
[16:12:23.102] Timer tick, asking Buildbucket for the build status
[16:12:23.174] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:23.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:70:0) after 1m0s
[16:13:23.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:70:0)
[16:13:23.212] Timer tick, asking Buildbucket for the build status
[16:13:23.396] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:23.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:71:0) after 1m0s
[16:14:23.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:71:0)
[16:14:23.419] Timer tick, asking Buildbucket for the build status
[16:14:23.491] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:23.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:72:0) after 1m0s
[16:15:23.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:72:0)
[16:15:23.648] Timer tick, asking Buildbucket for the build status
[16:15:23.745] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:23.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:73:0) after 1m0s
[16:16:23.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:73:0)
[16:16:23.971] Timer tick, asking Buildbucket for the build status
[16:16:24.053] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:24.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:74:0) after 1m0s
[16:17:24.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:74:0)
[16:17:24.712] Timer tick, asking Buildbucket for the build status
[16:17:24.785] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:24.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:75:0) after 1m0s
[16:18:24.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:75:0)
[16:18:24.899] Timer tick, asking Buildbucket for the build status
[16:18:25.036] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:25.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:76:0) after 1m0s
[16:19:25.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:76:0)
[16:19:25.094] Timer tick, asking Buildbucket for the build status
[16:19:25.173] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:25.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:77:0) after 1m0s
[16:20:25.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:77:0)
[16:20:25.292] Timer tick, asking Buildbucket for the build status
[16:20:25.416] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:25.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:78:0) after 1m0s
[16:21:25.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:78:0)
[16:21:25.500] Timer tick, asking Buildbucket for the build status
[16:21:25.575] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:25.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:79:0) after 1m0s
[16:22:25.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:79:0)
[16:22:25.645] Timer tick, asking Buildbucket for the build status
[16:22:25.726] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:25.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:80:0) after 1m0s
[16:23:25.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:80:0)
[16:23:25.748] Timer tick, asking Buildbucket for the build status
[16:23:25.816] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:25.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:81:0) after 1m0s
[16:24:25.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:81:0)
[16:24:25.888] Timer tick, asking Buildbucket for the build status
[16:24:26.015] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:26.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:82:0) after 1m0s
[16:25:26.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:82:0)
[16:25:26.121] Timer tick, asking Buildbucket for the build status
[16:25:26.326] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:26.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:83:0) after 1m0s
[16:26:26.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:83:0)
[16:26:26.402] Timer tick, asking Buildbucket for the build status
[16:26:26.657] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:26.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:84:0) after 1m0s
[16:27:26.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:84:0)
[16:27:26.704] Timer tick, asking Buildbucket for the build status
[16:27:26.840] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:26.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:85:0) after 1m0s
[16:28:26.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:85:0)
[16:28:26.970] Timer tick, asking Buildbucket for the build status
[16:28:27.066] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:27.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:86:0) after 1m0s
[16:29:27.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:86:0)
[16:29:27.082] Timer tick, asking Buildbucket for the build status
[16:29:27.164] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:27.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:87:0) after 1m0s
[16:30:27.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:87:0)
[16:30:27.309] Timer tick, asking Buildbucket for the build status
[16:30:27.391] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:27.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:88:0) after 1m0s
[16:31:27.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:88:0)
[16:31:27.418] Timer tick, asking Buildbucket for the build status
[16:31:27.608] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:27.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:89:0) after 1m0s
[16:32:27.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:89:0)
[16:32:27.728] Timer tick, asking Buildbucket for the build status
[16:32:27.803] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:27.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:90:0) after 1m0s
[16:33:27.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:90:0)
[16:33:27.918] Timer tick, asking Buildbucket for the build status
[16:33:28.011] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:28.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:91:0) after 1m0s
[16:34:28.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:91:0)
[16:34:28.033] Timer tick, asking Buildbucket for the build status
[16:34:28.135] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:28.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:92:0) after 1m0s
[16:35:28.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:92:0)
[16:35:28.156] Timer tick, asking Buildbucket for the build status
[16:35:28.251] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:28.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:93:0) after 1m0s
[16:36:28.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:93:0)
[16:36:28.295] Timer tick, asking Buildbucket for the build status
[16:36:28.393] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:28.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:94:0) after 1m0s
[16:37:28.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:94:0)
[16:37:28.413] Timer tick, asking Buildbucket for the build status
[16:37:28.498] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:28.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:95:0) after 1m0s
[16:38:28.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:95:0)
[16:38:28.528] Timer tick, asking Buildbucket for the build status
[16:38:28.835] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:28.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:96:0) after 1m0s
[16:39:28.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:96:0)
[16:39:28.887] Timer tick, asking Buildbucket for the build status
[16:39:29.133] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:97:0) after 1m0s
[16:40:29.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:97:0)
[16:40:29.245] Timer tick, asking Buildbucket for the build status
[16:40:29.486] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:29.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:98:0) after 1m0s
[16:41:29.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:98:0)
[16:41:29.544] Timer tick, asking Buildbucket for the build status
[16:41:29.629] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:29.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:99:0) after 1m0s
[16:42:29.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:99:0)
[16:42:29.653] Timer tick, asking Buildbucket for the build status
[16:42:29.864] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:29.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:100:0) after 1m0s
[16:43:29.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:100:0)
[16:43:29.925] Timer tick, asking Buildbucket for the build status
[16:43:30.232] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:30.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:101:0) after 1m0s
[16:44:30.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:101:0)
[16:44:30.239] Timer tick, asking Buildbucket for the build status
[16:44:30.382] Build 8854494841409040976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:30.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019924903429530864:102:0) after 1m0s
[16:44:58.137] Received PubSub notification, asking Buildbucket for the build status
[16:44:58.217] Build 8854494841409040976: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:44:58.217] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614098696548346",
"created_by": "project:chromium",
"created_ts": "1614092707204559",
"failure_reason": "BUILD_FAILURE",
"id": "8854494841409040976",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a6e3d524e6a0e44354baee7882d5cfb38317de06\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- EmojiPickerMainTest.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\": \"a6e3d524e6a0e44354baee7882d5cfb38317de06\", \"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-172-x07d\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45667, \"got_angle_revision\": \"1a70e5a04584ce3586c9ce7243b419f0df7b052f\", \"got_dawn_revision\": \"76a94b17be3c6cfc7e88d30b90e99d7b89c8f30b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a6e3d524e6a0e44354baee7882d5cfb38317de06\", \"got_revision_cp\": \"refs/heads/master@{#856671}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"4120b5f2509821e8dd460b1d033708397520750a\", \"got_v8_revision_cp\": \"refs/heads/9.0.238@{#1}\", \"got_webrtc_revision\": \"e5caa9e2d3c7e402dd62892b1de6bd4be6f6aaf5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33321}\", \"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\": \"a6e3d524e6a0e44354baee7882d5cfb38317de06\", \"swarm_hashes_refs/heads/master(at){#856671}_without_patch\": {\"absl_hardening_tests\": \"3c9dd4826c8c4ccb318415964985f914e5ce86cb\", \"accessibility_unittests\": \"052db280a38062d297ae2538c202d338fe802bc1\", \"angle_unittests\": \"d31c3dbfd80834f52eb742acfd33b69cf1e45e98\", \"app_list_unittests\": \"122b9cba5dc833153baa0bc164f73bbf0caeada0\", \"app_shell_unittests\": \"81740595924367ce13f56fc847481e1515329f59\", \"ash_unittests\": \"f889507bbc7cd7b802dc990d83039a1280075ff7\", \"aura_unittests\": \"06aedaaab58ab74fbd59a861abb01d861bee494d\", \"base_unittests\": \"59edda6111b83d3b3b235c1903d24041afe97c31\", \"base_util_unittests\": \"593fe48f705ac661ab4a13573c98851530f796b1\", \"blink_common_unittests\": \"58b9dd5abc429f5bf4253526dc4f8f705ae1c119\", \"blink_fuzzer_unittests\": \"69c9b94190d624f95aa17cecae6f614299cb928f\", \"blink_heap_unittests\": \"d7a0860695c6300ea13cc6b79970872cc4f2106b\", \"blink_platform_unittests\": \"a86da710f8f338e6374f90c0c0505cb8c7d024ab\", \"blink_unittests\": \"2126a7c7f1d7ee41d38b326805f4401c27e8ce7e\", \"boringssl_crypto_tests\": \"daa0263a682720a16b6b10fe8a47c9d059576e02\", \"boringssl_ssl_tests\": \"ab4d22d30a37855f9635bd40ced5d0b3495acbb1\", \"browser_tests\": \"efcf88b864288de311eddda9346eeea0a9533a73\", \"capture_unittests\": \"22b06051808db009940ac078826da35bb259022d\", \"cast_unittests\": \"f450f0d027bc7487d0ba1a17ed47ea1c195a6588\", \"cc_unittests\": \"487eba435181590876943a02a683a0690b9b1619\", \"chrome_app_unittests\": \"86b7d75bb09d62660094e934b8fdd2e0084124a7\", \"chromedriver_unittests\": \"2e30c83ec3235cbde9e064c65e4b861e0f802bfc\", \"chromeos_components_unittests\": \"9d054382d0a9f551610847a76befe9578bf4df27\", \"chromeos_unittests\": \"4bfee29dcd215b589117c4bb0a9ef52a4609aace\", \"components_browsertests\": \"076646b52b9900bb391e58cf7a87b6d19ede6421\", \"components_unittests\": \"86dbb387490702a97b57a7d41036f333fb0a910c\", \"compositor_unittests\": \"21a79b1231caef82af6ca8f0f320eaef54408de9\", \"content_browsertests\": \"95e83f6f5bfad24732f770552ec5c14c1ff8cfb7\", \"content_unittests\": \"a28d37db5e9cb031f3fb1a69358c57718d6b0518\", \"crashpad_tests\": \"bea33522882e4ec4252b663002a1dfd539dae484\", \"crypto_unittests\": \"c0314ba79acac3e4652c583b8d3dab341c18a1ba\", \"dbus_unittests\": \"c27090b5f95da29d2ec8d6962aba6e5c249d9b87\", \"device_unittests\": \"2bf8710b7c40d81b4ae03dd5658f5b9556c5ece7\", \"display_unittests\": \"040ed8bed91fe6d2e2e9f87f3e3866e399956fea\", \"events_unittests\": \"3516cebd01acd443ece0a7866e978f573c941cb2\", \"exo_unittests\": \"627603331b1006c1b83a9dc73b1f4afc0f2653ed\", \"extensions_browsertests\": \"90cc66aa69566aabd774796080990cb118d52c96\", \"extensions_unittests\": \"a9200e4cc84329e74130684d9957009d1df5802f\", \"filesystem_service_unittests\": \"830e213a3820d9f0a6153ce897687646c7f81ae6\", \"gcm_unit_tests\": \"2f622af2c271cf44302923d1abd044f1ee6100b2\", \"gfx_unittests\": \"dfdc2fee22741c662d15baa88c7bb3cf7334d02c\", \"gin_unittests\": \"6513a35925a005af0f91aa23dcc93cad9c44c3f6\", \"gl_unittests_ozone\": \"61dcace0756d19f3c6416430d45ee10b65b11cb1\", \"google_apis_unittests\": \"a2dfce99cb396879dde7063c8384e74ad50f5f5d\", \"gpu_unittests\": \"8f3b7e7a95a0fef47f0daea2f839c3cbf861d0f8\", \"gwp_asan_unittests\": \"e8a94ef5a40dd3c759ac6545388811454e7cef06\", \"interactive_ui_tests\": \"4aa91e081f27a4f4a9435ed354228b25ae9bce60\", \"ipc_tests\": \"b20e2e061e97b1cdce8f09cd4cb5d4bebef89070\", \"jingle_unittests\": \"cd24c011f020629519ad3de8ee8d0cebaa2523ee\", \"keyboard_unittests\": \"a6fcebd234e985c42d5a28524238cbc9d2c17165\", \"latency_unittests\": \"d76501bf1cd0fa9c1fa076fd726d4460fa218276\", \"libjingle_xmpp_unittests\": \"1fc05fce2e990c5180f0679ba6ee3eebccf8c792\", \"liburlpattern_unittests\": \"358bb1e5b18275939432edb50ce11cad0ad58b40\", \"media_blink_unittests\": \"bf3bd41e3d9baf93dabe4f97e25c6ce3a2474f9c\", \"media_unittests\": \"54f68980b969c5b419dc1fbcee5b2451a447966f\", \"message_center_unittests\": \"63425233534c7ef27b03cb044d9a155bcb209e83\", \"midi_unittests\": \"a3c82d016dbf6c3cd295ab9ca9170f10dc288ad6\", \"mojo_core_unittests\": \"4a81f675a7f3a1a63659d59092b795ac2e63fe55\", \"mojo_unittests\": \"6c95e60c2b9b534d99cb80f64e91b8807fae3598\", \"nacl_helper_nonsfi_unittests\": \"1e7eba67fcbfe09ec0a881e17811fc58b0b19747\", \"nacl_loader_unittests\": \"c7039db7b5c25e670baf2aa13b133fb27fc733e0\", \"native_theme_unittests\": \"520588d3b104b172961f08efb8f1a66bc623105c\", \"net_unittests\": \"73bc6c71dc632b7fcc1da8f5ebc3f42044f8511e\", \"ozone_gl_unittests\": \"50c75ee4feee8f673f7cd1d594f03facbea32846\", \"ozone_unittests\": \"9f9fbb0060bfbd22d96a3ceb6ad22e77824954d4\", \"ozone_x11_unittests\": \"9c60c2c79df44a472375c754ee78075ee16b6089\", \"pdf_unittests\": \"fd525c47da9cb31649973afcc164223534a83987\", \"perfetto_unittests\": \"d081f88a4d76c64649bd31fde6d75b35ff9b15b0\", \"ppapi_unittests\": \"0c53763040ffee13fd0a156810858ecb06299a9d\", \"printing_unittests\": \"8b27436ad6acd14664964dc662ee6b24093d43c2\", \"remoting_unittests\": \"434ea67432c2a7ca4ec9718428708c064b44dee3\", \"sandbox_linux_unittests\": \"c95c343c6a88804b0704598c5e4e545c3968a25a\", \"service_manager_unittests\": \"81223c720f52eddaa572142d39f2d589b80c6f83\", \"services_unittests\": \"92872a111609119bfb19851e660724c8b3842dad\", \"shell_dialogs_unittests\": \"25e0bbdd72285f5058a26f2dbb1482985c5cee50\", \"shell_encryption_unittests\": \"38b00278a809a9cb555d94d7ab8c1fc3b77296db\", \"skia_unittests\": \"8ba4ac287c113e5b02bfa15f8dc73b756ca3d0c7\", \"snapshot_unittests\": \"b86abd3a70c58f486b76502578f89f45070a5a25\", \"sql_unittests\": \"443fd91f92e078c1ff3632b7d27c97f0560c6309\", \"storage_unittests\": \"ae701443593fcef1bbcd9ec2f9a7b31a5d39e19a\", \"sync_integration_tests\": \"284beb1cc83fa5bbb04b69c2476df0b4c36e3a83\", \"traffic_annotation_auditor_unittests\": \"f56ef224677027197532fe14a18767051970ecc0\", \"ui_base_unittests\": \"1aee157d33fac01ada4db007afd530d1180b38fa\", \"ui_chromeos_unittests\": \"f8eb8a77a0d758bd17f94ce5e200ba18ebad3ab8\", \"ui_touch_selection_unittests\": \"fe6794e3c432b4f42f8ad390533f18123d326c25\", \"unit_tests\": \"33a8b312d015b34fd565e97c15b7a74adbc3afbb\", \"url_unittests\": \"e409c10b884e7f63dc85da7544444632cf538a93\", \"usage_time_limit_unittests\": \"c872d4fa4a5ec619054f3417424a5a3d22573e20\", \"views_unittests\": \"4e8a91ab805f2a46ae58dfbad84b846ccc0626ed\", \"viz_unittests\": \"e6cc7e6f11f4f1fef858b3792bfe9840ac2dcc90\", \"wayland_client_perftests\": \"b793615b5f72b44c5977632b680a09d1b3f2ebf0\", \"wm_unittests\": \"9238dc51b79646760f249caca55d7237693913c4\", \"wtf_unittests\": \"fe80f1e48049abb420b511026b1dab0cf1cda966\", \"zlib_unittests\": \"b24526bc66621bcd565d889fb7c11a0bf703f430\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_541cf43b1bca5745fae9eb808cc6f212921580c436c428e330e275f831e0e73a_v2\", \"builder_800f6f487d95d787b5e050c93b44c7c7276a85aefb5f9344834f1f03ffd0e322_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_be71ec2e45ff41f29ecdc3611cfb7c276cc0ff3138424d653aa2b7cfb51218d3_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-172-x07d\"], \"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\": [\"5706-397711c\"], \"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- EmojiPickerMainTest.All\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614093001329813",
"status": "COMPLETED",
"status_changed_ts": "1614093001920685",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45667",
"builder:linux-chromeos-rel",
"buildset:commit/git/a6e3d524e6a0e44354baee7882d5cfb38317de06",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6e3d524e6a0e44354baee7882d5cfb38317de06",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019924903429530864",
"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/8854494841409040976/+/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:51e83db18a74c010",
"user_agent:luci-scheduler"
],
"updated_ts": "1614098697887768",
"url": "https://ci.chromium.org/b/8854494841409040976",
"utcnow_ts": "1614098698208981"
}
}
[16:44:58.217] Invocation finished in 1h39m52.323781691s with status FAILED