[15:12:33.225] New invocation is queued and will start shortly
[15:12:34.467] Starting the invocation (attempt 1)
[15:12:34.528] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:12:34.529] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:12:34.529] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017840704157211184",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"891cf25c329b59832e0ddf42455831a3e981a4cc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9017840704157211184",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/891cf25c329b59832e0ddf42455831a3e981a4cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/891cf25c329b59832e0ddf42455831a3e981a4cc",
"gitiles_ref:refs/heads/master"
]
}
[15:12:35.786] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616080354673240",
"id": "8852410641977103136",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"891cf25c329b59832e0ddf42455831a3e981a4cc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616080355505227",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46251",
"builder:linux-chromeos-rel",
"buildset:commit/git/891cf25c329b59832e0ddf42455831a3e981a4cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/891cf25c329b59832e0ddf42455831a3e981a4cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017840704157211184",
"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/8852410641977103136/+/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": "1616080355505479",
"url": "https://ci.chromium.org/b/8852410641977103136",
"utcnow_ts": "1616080355778299"
}
}
[15:12:35.786] Task URL: https://ci.chromium.org/b/8852410641977103136
[15:12:35.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:2:0) after 1m0s
[15:13:35.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:2:0)
[15:13:35.886] Timer tick, asking Buildbucket for the build status
[15:13:36.047] Build 8852410641977103136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:13:36.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:3:0) after 1m0s
[15:14:36.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:3:0)
[15:14:36.075] Timer tick, asking Buildbucket for the build status
[15:14:36.141] Build 8852410641977103136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:14:36.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:4:0) after 1m0s
[15:15:36.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:4:0)
[15:15:36.231] Timer tick, asking Buildbucket for the build status
[15:15:36.298] Build 8852410641977103136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:15:36.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:5:0) after 1m0s
[15:16:36.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:5:0)
[15:16:36.531] Timer tick, asking Buildbucket for the build status
[15:16:36.582] Build 8852410641977103136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:16:36.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:6:0) after 1m0s
[15:17:36.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:6:0)
[15:17:36.765] Timer tick, asking Buildbucket for the build status
[15:17:36.832] Build 8852410641977103136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:17:36.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:7:0) after 1m0s
[15:18:03.921] Received PubSub notification, asking Buildbucket for the build status
[15:18:04.162] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:36.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:7:0)
[15:18:36.986] Timer tick, asking Buildbucket for the build status
[15:18:37.082] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:37.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:9:0) after 1m0s
[15:19:37.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:9:0)
[15:19:37.213] Timer tick, asking Buildbucket for the build status
[15:19:37.330] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:37.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:10:0) after 1m0s
[15:20:37.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:10:0)
[15:20:37.452] Timer tick, asking Buildbucket for the build status
[15:20:37.606] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:37.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:11:0) after 1m0s
[15:21:37.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:11:0)
[15:21:37.658] Timer tick, asking Buildbucket for the build status
[15:21:37.746] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:37.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:12:0) after 1m0s
[15:22:37.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:12:0)
[15:22:37.865] Timer tick, asking Buildbucket for the build status
[15:22:38.337] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:38.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:13:0) after 1m0s
[15:23:38.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:13:0)
[15:23:38.392] Timer tick, asking Buildbucket for the build status
[15:23:38.460] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:38.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:14:0) after 1m0s
[15:24:38.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:14:0)
[15:24:38.616] Timer tick, asking Buildbucket for the build status
[15:24:38.679] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:38.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:15:0) after 1m0s
[15:25:38.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:15:0)
[15:25:38.804] Timer tick, asking Buildbucket for the build status
[15:25:40.264] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:40.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:16:0) after 1m0s
[15:26:40.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:16:0)
[15:26:40.427] Timer tick, asking Buildbucket for the build status
[15:26:40.541] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:40.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:17:0) after 1m0s
[15:27:40.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:17:0)
[15:27:40.550] Timer tick, asking Buildbucket for the build status
[15:27:40.685] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:40.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:18:0) after 1m0s
[15:28:40.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:18:0)
[15:28:40.824] Timer tick, asking Buildbucket for the build status
[15:28:40.904] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:40.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:19:0) after 1m0s
[15:29:40.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:19:0)
[15:29:40.941] Timer tick, asking Buildbucket for the build status
[15:29:41.170] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:41.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:20:0) after 1m0s
[15:30:41.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:20:0)
[15:30:41.221] Timer tick, asking Buildbucket for the build status
[15:30:41.295] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:41.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:21:0) after 1m0s
[15:31:41.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:21:0)
[15:31:41.332] Timer tick, asking Buildbucket for the build status
[15:31:41.517] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:41.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:22:0) after 1m0s
[15:32:41.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:22:0)
[15:32:41.576] Timer tick, asking Buildbucket for the build status
[15:32:41.666] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:41.666] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:23:0) after 1m0s
[15:33:41.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:23:0)
[15:33:41.683] Timer tick, asking Buildbucket for the build status
[15:33:41.765] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:41.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:24:0) after 1m0s
[15:34:41.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:24:0)
[15:34:41.801] Timer tick, asking Buildbucket for the build status
[15:34:42.071] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:42.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:25:0) after 1m0s
[15:35:42.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:25:0)
[15:35:42.156] Timer tick, asking Buildbucket for the build status
[15:35:42.224] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:42.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:26:0) after 1m0s
[15:36:42.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:26:0)
[15:36:42.297] Timer tick, asking Buildbucket for the build status
[15:36:42.366] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:42.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:27:0) after 1m0s
[15:37:42.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:27:0)
[15:37:42.365] Timer tick, asking Buildbucket for the build status
[15:37:42.456] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:42.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:28:0) after 1m0s
[15:38:42.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:28:0)
[15:38:42.497] Timer tick, asking Buildbucket for the build status
[15:38:42.793] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:42.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:29:0) after 1m0s
[15:39:42.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:29:0)
[15:39:42.841] Timer tick, asking Buildbucket for the build status
[15:39:43.130] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:43.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:30:0) after 1m0s
[15:40:43.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:30:0)
[15:40:43.142] Timer tick, asking Buildbucket for the build status
[15:40:43.210] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:43.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:31:0) after 1m0s
[15:41:43.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:31:0)
[15:41:43.275] Timer tick, asking Buildbucket for the build status
[15:41:43.446] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:43.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:32:0) after 1m0s
[15:42:43.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:32:0)
[15:42:43.499] Timer tick, asking Buildbucket for the build status
[15:42:44.009] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:44.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:33:0) after 1m0s
[15:43:44.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:33:0)
[15:43:44.014] Timer tick, asking Buildbucket for the build status
[15:43:44.210] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:44.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:34:0) after 1m0s
[15:44:44.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:34:0)
[15:44:44.175] Timer tick, asking Buildbucket for the build status
[15:44:44.386] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:44.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:35:0) after 1m0s
[15:45:44.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:35:0)
[15:45:44.410] Timer tick, asking Buildbucket for the build status
[15:45:44.626] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:44.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:36:0) after 1m0s
[15:46:44.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:36:0)
[15:46:44.652] Timer tick, asking Buildbucket for the build status
[15:46:44.735] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:44.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:37:0) after 1m0s
[15:47:44.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:37:0)
[15:47:44.752] Timer tick, asking Buildbucket for the build status
[15:47:44.845] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:44.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:38:0) after 1m0s
[15:48:44.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:38:0)
[15:48:44.839] Timer tick, asking Buildbucket for the build status
[15:48:44.906] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:44.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:39:0) after 1m0s
[15:49:44.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:39:0)
[15:49:44.968] Timer tick, asking Buildbucket for the build status
[15:49:45.059] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:45.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:40:0) after 1m0s
[15:50:45.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:40:0)
[15:50:45.108] Timer tick, asking Buildbucket for the build status
[15:50:45.231] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:45.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:41:0) after 1m0s
[15:51:45.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:41:0)
[15:51:45.402] Timer tick, asking Buildbucket for the build status
[15:51:45.488] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:45.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:42:0) after 1m0s
[15:52:45.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:42:0)
[15:52:45.556] Timer tick, asking Buildbucket for the build status
[15:52:45.633] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:45.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:43:0) after 1m0s
[15:53:45.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:43:0)
[15:53:45.699] Timer tick, asking Buildbucket for the build status
[15:53:45.775] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:45.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:44:0) after 1m0s
[15:54:45.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:44:0)
[15:54:45.813] Timer tick, asking Buildbucket for the build status
[15:54:45.876] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:45.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:45:0) after 1m0s
[15:55:45.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:45:0)
[15:55:45.956] Timer tick, asking Buildbucket for the build status
[15:55:46.028] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:46.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:46:0) after 1m0s
[15:56:46.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:46:0)
[15:56:46.314] Timer tick, asking Buildbucket for the build status
[15:56:46.389] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:46.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:47:0) after 1m0s
[15:57:46.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:47:0)
[15:57:46.440] Timer tick, asking Buildbucket for the build status
[15:57:46.859] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:46.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:48:0) after 1m0s
[15:58:46.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:48:0)
[15:58:46.894] Timer tick, asking Buildbucket for the build status
[15:58:46.973] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:46.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:49:0) after 1m0s
[15:59:46.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:49:0)
[15:59:46.988] Timer tick, asking Buildbucket for the build status
[15:59:47.076] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:47.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:50:0) after 1m0s
[16:00:47.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:50:0)
[16:00:47.137] Timer tick, asking Buildbucket for the build status
[16:00:47.211] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:47.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:51:0) after 1m0s
[16:01:47.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:51:0)
[16:01:47.473] Timer tick, asking Buildbucket for the build status
[16:01:47.554] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:47.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:52:0) after 1m0s
[16:02:47.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:52:0)
[16:02:47.665] Timer tick, asking Buildbucket for the build status
[16:02:47.764] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:47.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:53:0) after 1m0s
[16:03:47.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:53:0)
[16:03:47.925] Timer tick, asking Buildbucket for the build status
[16:03:48.159] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:48.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:54:0) after 1m0s
[16:04:48.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:54:0)
[16:04:48.258] Timer tick, asking Buildbucket for the build status
[16:04:48.639] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:48.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:55:0) after 1m0s
[16:05:48.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:55:0)
[16:05:48.661] Timer tick, asking Buildbucket for the build status
[16:05:48.731] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:48.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:56:0) after 1m0s
[16:06:48.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:56:0)
[16:06:48.777] Timer tick, asking Buildbucket for the build status
[16:06:48.851] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:48.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:57:0) after 1m0s
[16:07:48.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:57:0)
[16:07:48.867] Timer tick, asking Buildbucket for the build status
[16:07:48.933] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:48.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:58:0) after 1m0s
[16:08:49.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:58:0)
[16:08:49.007] Timer tick, asking Buildbucket for the build status
[16:08:49.091] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:49.091] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:59:0) after 1m0s
[16:09:49.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:59:0)
[16:09:49.177] Timer tick, asking Buildbucket for the build status
[16:09:49.291] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:49.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:60:0) after 1m0s
[16:10:49.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:60:0)
[16:10:49.322] Timer tick, asking Buildbucket for the build status
[16:10:49.397] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:49.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:61:0) after 1m0s
[16:11:49.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:61:0)
[16:11:49.414] Timer tick, asking Buildbucket for the build status
[16:11:49.555] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:49.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:62:0) after 1m0s
[16:12:49.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:62:0)
[16:12:49.626] Timer tick, asking Buildbucket for the build status
[16:12:49.702] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:49.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:63:0) after 1m0s
[16:13:49.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:63:0)
[16:13:49.692] Timer tick, asking Buildbucket for the build status
[16:13:49.911] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:49.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:64:0) after 1m0s
[16:14:49.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:64:0)
[16:14:49.914] Timer tick, asking Buildbucket for the build status
[16:14:49.986] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:49.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:65:0) after 1m0s
[16:15:50.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:65:0)
[16:15:50.277] Timer tick, asking Buildbucket for the build status
[16:15:50.499] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:50.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:66:0) after 1m0s
[16:16:50.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:66:0)
[16:16:50.643] Timer tick, asking Buildbucket for the build status
[16:16:51.001] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:51.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:67:0) after 1m0s
[16:17:51.063] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:67:0)
[16:17:51.063] Timer tick, asking Buildbucket for the build status
[16:17:51.136] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:51.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:68:0) after 1m0s
[16:18:51.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:68:0)
[16:18:51.206] Timer tick, asking Buildbucket for the build status
[16:18:51.299] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:51.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:69:0) after 1m0s
[16:19:52.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:69:0)
[16:19:52.229] Timer tick, asking Buildbucket for the build status
[16:19:52.539] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:52.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:70:0) after 1m0s
[16:20:52.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:70:0)
[16:20:52.572] Timer tick, asking Buildbucket for the build status
[16:20:52.647] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:52.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:71:0) after 1m0s
[16:21:52.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:71:0)
[16:21:52.686] Timer tick, asking Buildbucket for the build status
[16:21:52.757] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:52.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:72:0) after 1m0s
[16:22:52.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:72:0)
[16:22:52.774] Timer tick, asking Buildbucket for the build status
[16:22:52.885] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:52.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:73:0) after 1m0s
[16:23:52.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:73:0)
[16:23:52.942] Timer tick, asking Buildbucket for the build status
[16:23:53.038] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:53.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:74:0) after 1m0s
[16:24:53.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:74:0)
[16:24:53.058] Timer tick, asking Buildbucket for the build status
[16:24:53.126] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:53.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:75:0) after 1m0s
[16:25:53.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:75:0)
[16:25:53.178] Timer tick, asking Buildbucket for the build status
[16:25:53.258] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:53.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:76:0) after 1m0s
[16:26:53.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:76:0)
[16:26:53.297] Timer tick, asking Buildbucket for the build status
[16:26:53.377] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:53.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:77:0) after 1m0s
[16:27:53.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:77:0)
[16:27:53.397] Timer tick, asking Buildbucket for the build status
[16:27:53.490] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:53.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:78:0) after 1m0s
[16:28:53.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:78:0)
[16:28:53.521] Timer tick, asking Buildbucket for the build status
[16:28:53.601] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:53.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:79:0) after 1m0s
[16:29:53.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:79:0)
[16:29:53.697] Timer tick, asking Buildbucket for the build status
[16:29:53.785] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:53.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:80:0) after 1m0s
[16:30:54.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:80:0)
[16:30:54.006] Timer tick, asking Buildbucket for the build status
[16:30:54.107] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:54.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:81:0) after 1m0s
[16:31:54.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:81:0)
[16:31:54.242] Timer tick, asking Buildbucket for the build status
[16:31:54.337] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:54.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:82:0) after 1m0s
[16:32:54.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:82:0)
[16:32:54.386] Timer tick, asking Buildbucket for the build status
[16:32:54.483] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:54.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:83:0) after 1m0s
[16:33:54.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:83:0)
[16:33:54.460] Timer tick, asking Buildbucket for the build status
[16:33:54.593] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:54.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:84:0) after 1m0s
[16:34:54.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:84:0)
[16:34:54.670] Timer tick, asking Buildbucket for the build status
[16:34:54.732] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:54.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:85:0) after 1m0s
[16:35:54.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:85:0)
[16:35:54.799] Timer tick, asking Buildbucket for the build status
[16:35:54.938] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:54.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:86:0) after 1m0s
[16:36:55.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:86:0)
[16:36:55.003] Timer tick, asking Buildbucket for the build status
[16:36:55.288] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:55.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:87:0) after 1m0s
[16:37:55.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:87:0)
[16:37:55.299] Timer tick, asking Buildbucket for the build status
[16:37:55.441] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:55.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:88:0) after 1m0s
[16:38:55.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:88:0)
[16:38:55.513] Timer tick, asking Buildbucket for the build status
[16:38:55.808] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:55.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:89:0) after 1m0s
[16:39:55.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:89:0)
[16:39:55.861] Timer tick, asking Buildbucket for the build status
[16:39:56.027] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:56.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:90:0) after 1m0s
[16:40:56.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:90:0)
[16:40:56.055] Timer tick, asking Buildbucket for the build status
[16:40:56.153] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:56.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:91:0) after 1m0s
[16:41:56.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:91:0)
[16:41:56.145] Timer tick, asking Buildbucket for the build status
[16:41:56.382] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:56.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:92:0) after 1m0s
[16:42:56.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:92:0)
[16:42:56.412] Timer tick, asking Buildbucket for the build status
[16:42:56.558] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:56.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:93:0) after 1m0s
[16:43:56.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:93:0)
[16:43:56.591] Timer tick, asking Buildbucket for the build status
[16:43:56.756] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:56.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:94:0) after 1m0s
[16:44:56.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:94:0)
[16:44:56.842] Timer tick, asking Buildbucket for the build status
[16:44:56.939] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:56.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:95:0) after 1m0s
[16:45:57.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:95:0)
[16:45:57.179] Timer tick, asking Buildbucket for the build status
[16:45:57.273] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:57.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:96:0) after 1m0s
[16:46:57.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:96:0)
[16:46:57.425] Timer tick, asking Buildbucket for the build status
[16:46:57.528] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:57.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:97:0) after 1m0s
[16:47:57.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:97:0)
[16:47:57.713] Timer tick, asking Buildbucket for the build status
[16:47:58.256] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:58.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:98:0) after 1m0s
[16:48:58.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:98:0)
[16:48:58.444] Timer tick, asking Buildbucket for the build status
[16:48:58.565] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:58.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:99:0) after 1m0s
[16:49:58.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:99:0)
[16:49:58.671] Timer tick, asking Buildbucket for the build status
[16:49:59.054] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:59.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:100:0) after 1m0s
[16:50:59.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:100:0)
[16:50:59.038] Timer tick, asking Buildbucket for the build status
[16:50:59.115] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:59.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:101:0) after 1m0s
[16:51:59.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:101:0)
[16:51:59.200] Timer tick, asking Buildbucket for the build status
[16:51:59.571] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:59.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:102:0) after 1m0s
[16:52:59.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:102:0)
[16:52:59.806] Timer tick, asking Buildbucket for the build status
[16:53:00.376] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:00.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:103:0) after 1m0s
[16:54:00.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:103:0)
[16:54:00.407] Timer tick, asking Buildbucket for the build status
[16:54:00.509] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:00.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:104:0) after 1m0s
[16:55:00.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:104:0)
[16:55:00.647] Timer tick, asking Buildbucket for the build status
[16:55:00.733] Build 8852410641977103136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:00.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017840704157211184:105:0) after 1m0s
[16:55:12.259] Received PubSub notification, asking Buildbucket for the build status
[16:55:12.384] Build 8852410641977103136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:55:12.384] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616086511594748",
"created_by": "project:chromium",
"created_ts": "1616080354673240",
"id": "8852410641977103136",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"891cf25c329b59832e0ddf42455831a3e981a4cc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"891cf25c329b59832e0ddf42455831a3e981a4cc\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-69-8ghj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46251, \"got_angle_revision\": \"f201c9d2bf829d9d70e3cda0a149dc3b243520eb\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"891cf25c329b59832e0ddf42455831a3e981a4cc\", \"got_revision_cp\": \"refs/heads/master@{#864241}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"4f0f4e2a3c5ad9dc7312774da5197e0179c30863\", \"got_v8_revision_cp\": \"refs/heads/9.1.146@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": false, \"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\": \"891cf25c329b59832e0ddf42455831a3e981a4cc\", \"swarm_hashes_refs/heads/master(at){#864241}_without_patch\": {\"absl_hardening_tests\": \"44ea98563fd5b6948a892c7c4ef9c6ea21e7c72d\", \"accessibility_unittests\": \"479231621c62aa5e871c91cdcf2f29c83e12d092\", \"angle_unittests\": \"19fbf031f985e6a70b9fa02f7f65f851d22fb114\", \"app_list_unittests\": \"6d768fb2985b264f314629ff68b024e76cce4b76\", \"app_shell_unittests\": \"6be365a10b4f6f076e521f11541b402df8d4ec1b\", \"ash_unittests\": \"5d1c2dda7189cabd1b5a082badfe539db93a4499\", \"aura_unittests\": \"85f0854e671c06388cc81fc61839afc94bf83be7\", \"base_unittests\": \"0fd843c6ffa903947ff5222a7ecd914aa8f6ebcf\", \"base_util_unittests\": \"2122b367d386b032964da1a95f8ce8762d4b6c2f\", \"blink_common_unittests\": \"870f3d4ef67bc8298f7c777844a09f425686c04f\", \"blink_fuzzer_unittests\": \"8cad958c2c6248be1d6635c243e7d419951c801e\", \"blink_heap_unittests\": \"a3daadcda4ca39943a5984174ab9b69648973ec5\", \"blink_platform_unittests\": \"7f896e397dad495438bbd1821a03b5093b96ae4f\", \"blink_unittests\": \"944927133b5771e7a430a32ef1a2bb2d62e4cf2c\", \"boringssl_crypto_tests\": \"11a45fcfcf7938a66789ca84f440e16e100a6ac2\", \"boringssl_ssl_tests\": \"24857397c6ed8e57ca021ed31bd57f89ef78c511\", \"browser_tests\": \"ab5aede68632e1d6b51af07328440c29ef9739ce\", \"capture_unittests\": \"24c57e61c196451785e5f3d081b88f89c42e58d3\", \"cast_unittests\": \"133b371f2f3cf533861651d4bd993e3cac545eaf\", \"cc_unittests\": \"88978d0b522465c286cd38f652f5117e1ad23f75\", \"chrome_app_unittests\": \"222f86db06e4ba078f6d636cf0437904ff33d2cb\", \"chromedriver_unittests\": \"571e52f7fd971f2dba36b8532fc3bbd606516254\", \"chromeos_components_unittests\": \"cbc08477d3dad6d83e649def9e67ae772f96fdec\", \"chromeos_unittests\": \"0c87465d0d0621aea085a997129e3a16af5f03ec\", \"color_unittests\": \"cb9cf9f422c6811c5133e000531b47b6ee01168f\", \"components_browsertests\": \"f996031763043cee09880551b73a5f2ceef22689\", \"components_unittests\": \"0a26a0dd57f52f1bc1472c84c274cae49ddc4cce\", \"compositor_unittests\": \"486f43af1d7232ff99d409a8c68194660721fc53\", \"content_browsertests\": \"e5c4d378374bf1a822ae769bc86a6f4b83be7ece\", \"content_unittests\": \"bca74d5bbe5396d7fb7d6f58ae7198901c55c181\", \"crashpad_tests\": \"261b0b9bfffddb0f100a0d14f4bfd64466e73f08\", \"crypto_unittests\": \"7bbaffcc0f030bf3bba86bfe782514a92f945417\", \"dbus_unittests\": \"3afa231500e03ae37d2a2febc9d0c01927641b80\", \"device_unittests\": \"eed729f2c119ad7c8e82f9500a6dd0f0d72a3dcf\", \"display_unittests\": \"ca9fa3d4c8ff74687de4da20276ecaf8cfd7c305\", \"events_unittests\": \"f578cdf05acd44056b98b5276f10e8b45f47d3b5\", \"exo_unittests\": \"1438c809639098d4901114566ae4eb2e6b78cc7c\", \"extensions_browsertests\": \"9ae34e03faa3546f62df6fb4de64ca68a40b783d\", \"extensions_unittests\": \"a0c4fa5fa28d2549294269a486d21823768ed3ca\", \"filesystem_service_unittests\": \"4b27ede728a06b09f04745d3a4dbcb483e952ca4\", \"gcm_unit_tests\": \"2c861d539784409cebe6949070e49fa8ffc263d4\", \"gfx_unittests\": \"8e9b9e3788ce075d5d1f5c22673f8b1778486743\", \"gin_unittests\": \"2797472002342232649075ad02743cd191be4d30\", \"gl_unittests_ozone\": \"db2d1c979be72461340970042f54b684ca7cf0ef\", \"google_apis_unittests\": \"b5d71994f98c869b528e01fcae84175fbe2e18c3\", \"gpu_unittests\": \"ec5bd3311310ff21b5be0e1e8dd492ba50727cc1\", \"gwp_asan_unittests\": \"3fc2ff1cfb863653810745ebbe97186573cc6b81\", \"interactive_ui_tests\": \"b1ae42306a3a2490a4facdee4134621a63046119\", \"ipc_tests\": \"137ba23b91dd2941c4e16a8b24fdbc7cc7359356\", \"jingle_unittests\": \"2283ce32a432e210db5ca9f325975c433c81c1ab\", \"keyboard_unittests\": \"823022be2ba059e0c7eaef95332d13da396dc81f\", \"latency_unittests\": \"c6e8ef691a8d8b439eff8f06a9b58f14e71dd4cd\", \"libjingle_xmpp_unittests\": \"466755f78f22d66bf4be13d94d8b85f8ce38823f\", \"liburlpattern_unittests\": \"fd4e911fce6b6713ea354baacf0b77768f2d9c4e\", \"media_blink_unittests\": \"a0e64814c19708d36044c1742f9dccec58d8362a\", \"media_unittests\": \"7a16bbe3de79911793526cfdf02b118e7056a911\", \"message_center_unittests\": \"d12758235651c1e0b41db73cf40c2c5bf476b781\", \"midi_unittests\": \"55aa2c926d30cd4992ec2760f72bc5418765e024\", \"mojo_core_unittests\": \"4b4295891b72974b22b667b107ff88524a21205c\", \"mojo_unittests\": \"72602da035d1ae1d7444c3f2b511f8f4f725e318\", \"nacl_helper_nonsfi_unittests\": \"011a5645214401d537777ae7a039a9bd7074590a\", \"nacl_loader_unittests\": \"85e16166c70c3c521c6db205744402dae1f8b5a1\", \"native_theme_unittests\": \"415c80df6827f638e132a23df4711682e34ddcfa\", \"net_unittests\": \"9e965155c2bb94d1f972099e8482ab430fd988c8\", \"ozone_gl_unittests\": \"ca925759aa3712cb66ce54f97c71ce81ef96e096\", \"ozone_unittests\": \"96530794a85fde2825d89286f99a1995b352836c\", \"ozone_x11_unittests\": \"eec5e26b3003715d7bfc31a530955742b6fc3504\", \"pdf_unittests\": \"03345a5724c321e5507ccbd579d313dfe3718871\", \"perfetto_unittests\": \"972f9f95c8c552d5ee5925cb29dc433ab732e510\", \"ppapi_unittests\": \"4cf5279dc922cafbeb43d44e35a9e423170afad0\", \"printing_unittests\": \"0b1ffcecb9334462a3976c3d0caa8cfe5da5789e\", \"remoting_unittests\": \"bbb78a5c3c51b27cbf9726965fe19c995d570994\", \"sandbox_linux_unittests\": \"43e63bc852d63564d2df05a385f9b1827bd19f26\", \"service_manager_unittests\": \"be1e30e3b4d7a0c924f137c08794a9b7a87dfc3c\", \"services_unittests\": \"93d4746b363c097025982a2f742517cc4bbe5347\", \"shell_dialogs_unittests\": \"7860e1876d6bcaec110cfdb5b7ee8704394127eb\", \"shell_encryption_unittests\": \"e5f74f5fe847a51b9f2d18647bd2d7f81acc628d\", \"skia_unittests\": \"6c0043f1f1fc8d81dc5e6e0335454d653c272ef3\", \"snapshot_unittests\": \"4768da4bc8b26d6d05ccfc316eac7c74baa0291f\", \"sql_unittests\": \"b0a7dee26d0cda45bfd7313fc9b363fb1b7b832a\", \"storage_unittests\": \"ef3ca06608098cc2165a596d8e8338e418991b9e\", \"sync_integration_tests\": \"683e6587f097e132d1191244ef474a44a375812e\", \"traffic_annotation_auditor_unittests\": \"4a4149117c54605fb15fc6e963ca28f3024766e3\", \"ui_base_unittests\": \"914ae3230cc432caf5066ae9041f971a36536071\", \"ui_chromeos_unittests\": \"69e8ef4f683323deb05e525ef8e878495114ee10\", \"ui_touch_selection_unittests\": \"d540fb17d5245da40ee2a36035a0a4d5fff674f5\", \"unit_tests\": \"4088b7b5281b36902f4da8f91c51ac92ca6572f6\", \"url_unittests\": \"3ad9190ccc7a4d26027f40a78206f58df2b74f0e\", \"usage_time_limit_unittests\": \"50335261908ceb9c2b3ccafa90c23266d7d628c2\", \"views_unittests\": \"f97fcc28c622453a6254110446cdb2aab9db735c\", \"viz_unittests\": \"11d878ddd00f6a7514664856188aeeae3a408d83\", \"wayland_client_perftests\": \"ad1b67d50788226d84f7fbc7cb611f2912242f6d\", \"wayland_client_tests\": \"53a6cb4d3074e605f5ad51a9a74e2dd0b7034629\", \"wm_unittests\": \"da2f62f0c7dcc94a7b28f3a4ec135a431f049549\", \"wtf_unittests\": \"d2b822c2bd2f502455b7f8a344a8678fcecd3d1d\", \"zlib_unittests\": \"ee1cc520567e66538027b7f3605f0bb00ed271eb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_12fe258969bd458c0c491cc8ec8a110de21b19ba6d4540c003334ed929b828ba_v2\", \"builder_1cac3a12be5110575bfda3df59662d0c101b80b5d04053a56e3716a56e114d29_v2\", \"builder_288b090d6274f2a2f913a0241f3e1a83c28ff98db6eccb4d2a91107c37eb954f_v2\", \"builder_41a56887a5f94da437bca02a8352f9969580ae835cf23972ebecbddbd7380819_v2\", \"builder_814f6f94cc36c906709f77e5f5d2e081c15a658384526be65a434a8542efb6b6_v2\", \"builder_983dbffb8cc86de1533c3acacd740027aafc9d2942de9f299ba8447770e514ac_v2\", \"builder_e7fd4b7a9e211f8d968af60263c508e13e49814167aff980c2bf239c05ea48ef_v2\", \"builder_fe96da9c6a19fa2fc3ad0c2b7020b7cb68e4a92161f4cd6cb7dc1d1e2d5af5dc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-69-8ghj\"], \"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\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616080682656892",
"status": "COMPLETED",
"status_changed_ts": "1616086511860716",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46251",
"builder:linux-chromeos-rel",
"buildset:commit/git/891cf25c329b59832e0ddf42455831a3e981a4cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/891cf25c329b59832e0ddf42455831a3e981a4cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017840704157211184",
"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/8852410641977103136/+/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:525eb6c9cfb35010",
"user_agent:luci-scheduler"
],
"updated_ts": "1616086511865006",
"url": "https://ci.chromium.org/b/8852410641977103136",
"utcnow_ts": "1616086512375598"
}
}
[16:55:12.386] Invocation finished in 1h42m39.175954535s with status SUCCEEDED