[14:00:06.628] New invocation is queued and will start shortly
[14:00:08.664] Starting the invocation (attempt 1)
[14:00:08.704] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:00:08.704] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:00:08.704] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021378543587639840",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021378543587639840",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff",
"gitiles_ref:refs/heads/master"
]
}
[14:00:10.335] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612706408879485",
"id": "8855948480561693344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612706409977623",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22519",
"builder:linux-chromeos-dbg",
"buildset:commit/git/cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021378543587639840",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8855948480561693344/+/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": "1612706409977733",
"url": "https://ci.chromium.org/b/8855948480561693344",
"utcnow_ts": "1612706410328733"
}
}
[14:00:10.335] Task URL: https://ci.chromium.org/b/8855948480561693344
[14:00:10.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:2:0) after 1m0s
[14:01:10.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:2:0)
[14:01:10.372] Timer tick, asking Buildbucket for the build status
[14:01:10.469] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:01:10.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:3:0) after 1m0s
[14:02:10.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:3:0)
[14:02:10.535] Timer tick, asking Buildbucket for the build status
[14:02:10.618] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:02:10.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:4:0) after 1m0s
[14:03:10.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:4:0)
[14:03:10.706] Timer tick, asking Buildbucket for the build status
[14:03:10.791] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:03:10.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:5:0) after 1m0s
[14:04:11.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:5:0)
[14:04:11.171] Timer tick, asking Buildbucket for the build status
[14:04:11.238] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:04:11.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:6:0) after 1m0s
[14:05:11.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:6:0)
[14:05:11.396] Timer tick, asking Buildbucket for the build status
[14:05:11.624] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:05:11.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:7:0) after 1m0s
[14:06:11.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:7:0)
[14:06:11.679] Timer tick, asking Buildbucket for the build status
[14:06:11.753] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:06:11.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:8:0) after 1m0s
[14:07:11.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:8:0)
[14:07:11.839] Timer tick, asking Buildbucket for the build status
[14:07:11.902] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:07:11.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:9:0) after 1m0s
[14:08:11.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:9:0)
[14:08:11.952] Timer tick, asking Buildbucket for the build status
[14:08:12.022] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:08:12.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:10:0) after 1m0s
[14:09:12.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:10:0)
[14:09:12.036] Timer tick, asking Buildbucket for the build status
[14:09:12.196] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:12.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:11:0) after 1m0s
[14:10:12.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:11:0)
[14:10:12.214] Timer tick, asking Buildbucket for the build status
[14:10:12.438] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:10:12.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:12:0) after 1m0s
[14:11:12.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:12:0)
[14:11:12.606] Timer tick, asking Buildbucket for the build status
[14:11:12.671] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:12.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:13:0) after 1m0s
[14:12:12.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:13:0)
[14:12:12.743] Timer tick, asking Buildbucket for the build status
[14:12:12.825] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:12.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:14:0) after 1m0s
[14:13:12.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:14:0)
[14:13:12.871] Timer tick, asking Buildbucket for the build status
[14:13:13.016] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:13:13.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:15:0) after 1m0s
[14:14:13.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:15:0)
[14:14:13.088] Timer tick, asking Buildbucket for the build status
[14:14:13.154] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:14:13.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:16:0) after 1m0s
[14:15:13.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:16:0)
[14:15:13.291] Timer tick, asking Buildbucket for the build status
[14:15:13.749] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:15:13.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:17:0) after 1m0s
[14:16:13.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:17:0)
[14:16:13.968] Timer tick, asking Buildbucket for the build status
[14:16:14.163] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:14.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:18:0) after 1m0s
[14:17:14.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:18:0)
[14:17:14.185] Timer tick, asking Buildbucket for the build status
[14:17:14.278] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:14.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:19:0) after 1m0s
[14:18:14.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:19:0)
[14:18:14.302] Timer tick, asking Buildbucket for the build status
[14:18:14.383] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:14.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:20:0) after 1m0s
[14:19:14.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:20:0)
[14:19:14.563] Timer tick, asking Buildbucket for the build status
[14:19:14.633] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:14.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:21:0) after 1m0s
[14:20:14.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:21:0)
[14:20:14.762] Timer tick, asking Buildbucket for the build status
[14:20:14.854] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:20:14.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:22:0) after 1m0s
[14:21:14.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:22:0)
[14:21:14.922] Timer tick, asking Buildbucket for the build status
[14:21:15.062] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:21:15.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:23:0) after 1m0s
[14:22:15.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:23:0)
[14:22:15.085] Timer tick, asking Buildbucket for the build status
[14:22:15.164] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:22:15.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:24:0) after 1m0s
[14:23:15.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:24:0)
[14:23:15.224] Timer tick, asking Buildbucket for the build status
[14:23:15.419] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:23:15.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:25:0) after 1m0s
[14:24:15.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:25:0)
[14:24:15.436] Timer tick, asking Buildbucket for the build status
[14:24:15.510] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:24:15.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:26:0) after 1m0s
[14:25:15.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:26:0)
[14:25:15.571] Timer tick, asking Buildbucket for the build status
[14:25:16.116] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:16.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:27:0) after 1m0s
[14:26:16.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:27:0)
[14:26:16.216] Timer tick, asking Buildbucket for the build status
[14:26:16.306] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:26:16.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:28:0) after 1m0s
[14:27:16.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:28:0)
[14:27:16.374] Timer tick, asking Buildbucket for the build status
[14:27:16.437] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:16.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:29:0) after 1m0s
[14:28:16.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:29:0)
[14:28:16.575] Timer tick, asking Buildbucket for the build status
[14:28:16.680] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:16.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:30:0) after 1m0s
[14:29:16.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:30:0)
[14:29:16.747] Timer tick, asking Buildbucket for the build status
[14:29:16.825] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:16.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:31:0) after 1m0s
[14:30:16.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:31:0)
[14:30:16.839] Timer tick, asking Buildbucket for the build status
[14:30:17.249] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:17.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:32:0) after 1m0s
[14:31:17.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:32:0)
[14:31:17.897] Timer tick, asking Buildbucket for the build status
[14:31:18.028] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:31:18.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:33:0) after 1m0s
[14:32:18.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:33:0)
[14:32:18.191] Timer tick, asking Buildbucket for the build status
[14:32:18.320] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:32:18.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:34:0) after 1m0s
[14:33:18.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:34:0)
[14:33:18.375] Timer tick, asking Buildbucket for the build status
[14:33:18.440] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:33:18.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:35:0) after 1m0s
[14:34:18.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:35:0)
[14:34:18.586] Timer tick, asking Buildbucket for the build status
[14:34:18.658] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:34:18.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:36:0) after 1m0s
[14:35:18.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:36:0)
[14:35:18.679] Timer tick, asking Buildbucket for the build status
[14:35:18.803] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:35:18.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:37:0) after 1m0s
[14:36:18.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:37:0)
[14:36:18.939] Timer tick, asking Buildbucket for the build status
[14:36:19.556] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:36:19.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:38:0) after 1m0s
[14:37:19.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:38:0)
[14:37:19.928] Timer tick, asking Buildbucket for the build status
[14:37:20.019] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:20.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:39:0) after 1m0s
[14:38:20.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:39:0)
[14:38:20.296] Timer tick, asking Buildbucket for the build status
[14:38:20.524] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:20.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:40:0) after 1m0s
[14:39:20.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:40:0)
[14:39:20.625] Timer tick, asking Buildbucket for the build status
[14:39:20.700] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:20.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:41:0) after 1m0s
[14:40:20.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:41:0)
[14:40:20.776] Timer tick, asking Buildbucket for the build status
[14:40:20.897] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:20.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:42:0) after 1m0s
[14:41:21.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:42:0)
[14:41:21.095] Timer tick, asking Buildbucket for the build status
[14:41:21.242] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:21.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:43:0) after 1m0s
[14:42:21.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:43:0)
[14:42:21.440] Timer tick, asking Buildbucket for the build status
[14:42:21.664] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:42:21.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:44:0) after 1m0s
[14:43:21.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:44:0)
[14:43:21.683] Timer tick, asking Buildbucket for the build status
[14:43:21.902] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:43:21.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:45:0) after 1m0s
[14:44:22.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:45:0)
[14:44:22.015] Timer tick, asking Buildbucket for the build status
[14:44:22.145] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:44:22.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:46:0) after 1m0s
[14:45:22.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:46:0)
[14:45:22.298] Timer tick, asking Buildbucket for the build status
[14:45:22.620] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:45:22.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:47:0) after 1m0s
[14:46:22.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:47:0)
[14:46:22.671] Timer tick, asking Buildbucket for the build status
[14:46:22.742] Build 8855948480561693344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:46:22.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:48:0) after 1m0s
[14:47:14.138] Received PubSub notification, asking Buildbucket for the build status
[14:47:14.205] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:22.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:48:0)
[14:47:22.847] Timer tick, asking Buildbucket for the build status
[14:47:22.925] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:22.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:50:0) after 1m0s
[14:48:22.992] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:50:0)
[14:48:22.992] Timer tick, asking Buildbucket for the build status
[14:48:23.200] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:23.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:51:0) after 1m0s
[14:49:23.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:51:0)
[14:49:23.247] Timer tick, asking Buildbucket for the build status
[14:49:23.333] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:23.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:52:0) after 1m0s
[14:50:23.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:52:0)
[14:50:23.446] Timer tick, asking Buildbucket for the build status
[14:50:23.533] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:23.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:53:0) after 1m0s
[14:51:23.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:53:0)
[14:51:23.596] Timer tick, asking Buildbucket for the build status
[14:51:24.013] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:24.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:54:0) after 1m0s
[14:52:23.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:54:0)
[14:52:23.990] Timer tick, asking Buildbucket for the build status
[14:52:24.416] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:24.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:55:0) after 1m0s
[14:53:24.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:55:0)
[14:53:24.510] Timer tick, asking Buildbucket for the build status
[14:53:24.637] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:24.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:56:0) after 1m0s
[14:54:24.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:56:0)
[14:54:24.673] Timer tick, asking Buildbucket for the build status
[14:54:24.772] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:24.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:57:0) after 1m0s
[14:55:24.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:57:0)
[14:55:24.890] Timer tick, asking Buildbucket for the build status
[14:55:25.178] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:25.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:58:0) after 1m0s
[14:56:25.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:58:0)
[14:56:25.299] Timer tick, asking Buildbucket for the build status
[14:56:25.375] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:25.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:59:0) after 1m0s
[14:57:25.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:59:0)
[14:57:25.403] Timer tick, asking Buildbucket for the build status
[14:57:25.492] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:25.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:60:0) after 1m0s
[14:58:25.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:60:0)
[14:58:25.576] Timer tick, asking Buildbucket for the build status
[14:58:25.638] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:25.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:61:0) after 1m0s
[14:59:25.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:61:0)
[14:59:25.692] Timer tick, asking Buildbucket for the build status
[14:59:25.759] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:25.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:62:0) after 1m0s
[15:00:25.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:62:0)
[15:00:25.734] Timer tick, asking Buildbucket for the build status
[15:00:25.842] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:25.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:63:0) after 1m0s
[15:01:25.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:63:0)
[15:01:25.956] Timer tick, asking Buildbucket for the build status
[15:01:26.932] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:26.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:64:0) after 1m0s
[15:02:27.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:64:0)
[15:02:27.135] Timer tick, asking Buildbucket for the build status
[15:02:27.647] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:27.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:65:0) after 1m0s
[15:03:27.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:65:0)
[15:03:27.794] Timer tick, asking Buildbucket for the build status
[15:03:27.929] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:27.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:66:0) after 1m0s
[15:04:28.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:66:0)
[15:04:28.154] Timer tick, asking Buildbucket for the build status
[15:04:28.212] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:28.212] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:67:0) after 1m0s
[15:05:28.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:67:0)
[15:05:28.408] Timer tick, asking Buildbucket for the build status
[15:05:28.770] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:28.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:68:0) after 1m0s
[15:06:28.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:68:0)
[15:06:28.904] Timer tick, asking Buildbucket for the build status
[15:06:28.962] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:28.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:69:0) after 1m0s
[15:07:28.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:69:0)
[15:07:28.986] Timer tick, asking Buildbucket for the build status
[15:07:29.245] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:29.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:70:0) after 1m0s
[15:08:29.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:70:0)
[15:08:29.276] Timer tick, asking Buildbucket for the build status
[15:08:29.380] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:29.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:71:0) after 1m0s
[15:09:30.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:71:0)
[15:09:30.646] Timer tick, asking Buildbucket for the build status
[15:09:30.753] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:30.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:72:0) after 1m0s
[15:10:30.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:72:0)
[15:10:30.775] Timer tick, asking Buildbucket for the build status
[15:10:30.998] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:30.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:73:0) after 1m0s
[15:11:31.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:73:0)
[15:11:31.028] Timer tick, asking Buildbucket for the build status
[15:11:31.098] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:31.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:74:0) after 1m0s
[15:12:31.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:74:0)
[15:12:31.149] Timer tick, asking Buildbucket for the build status
[15:12:31.275] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:31.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:75:0) after 1m0s
[15:13:31.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:75:0)
[15:13:31.342] Timer tick, asking Buildbucket for the build status
[15:13:31.406] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:31.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:76:0) after 1m0s
[15:14:31.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:76:0)
[15:14:31.424] Timer tick, asking Buildbucket for the build status
[15:14:31.515] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:31.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:77:0) after 1m0s
[15:15:31.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:77:0)
[15:15:31.498] Timer tick, asking Buildbucket for the build status
[15:15:31.786] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:31.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:78:0) after 1m0s
[15:16:31.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:78:0)
[15:16:31.824] Timer tick, asking Buildbucket for the build status
[15:16:31.970] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:31.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:79:0) after 1m0s
[15:17:32.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:79:0)
[15:17:32.005] Timer tick, asking Buildbucket for the build status
[15:17:32.313] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:32.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:80:0) after 1m0s
[15:18:32.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:80:0)
[15:18:32.336] Timer tick, asking Buildbucket for the build status
[15:18:32.407] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:32.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:81:0) after 1m0s
[15:19:32.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:81:0)
[15:19:32.467] Timer tick, asking Buildbucket for the build status
[15:19:33.158] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:33.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:82:0) after 1m0s
[15:20:33.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:82:0)
[15:20:33.177] Timer tick, asking Buildbucket for the build status
[15:20:33.292] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:33.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:83:0) after 1m0s
[15:21:33.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:83:0)
[15:21:33.340] Timer tick, asking Buildbucket for the build status
[15:21:33.815] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:33.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:84:0) after 1m0s
[15:22:33.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:84:0)
[15:22:33.820] Timer tick, asking Buildbucket for the build status
[15:22:33.914] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:85:0) after 1m0s
[15:23:33.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:85:0)
[15:23:33.961] Timer tick, asking Buildbucket for the build status
[15:23:34.136] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:34.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:86:0) after 1m0s
[15:24:34.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:86:0)
[15:24:34.230] Timer tick, asking Buildbucket for the build status
[15:24:34.360] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:34.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:87:0) after 1m0s
[15:25:34.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:87:0)
[15:25:34.390] Timer tick, asking Buildbucket for the build status
[15:25:34.512] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:34.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:88:0) after 1m0s
[15:26:34.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:88:0)
[15:26:34.559] Timer tick, asking Buildbucket for the build status
[15:26:34.611] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:34.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:89:0) after 1m0s
[15:27:34.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:89:0)
[15:27:34.630] Timer tick, asking Buildbucket for the build status
[15:27:34.830] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:34.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:90:0) after 1m0s
[15:28:34.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:90:0)
[15:28:34.878] Timer tick, asking Buildbucket for the build status
[15:28:34.928] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:34.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:91:0) after 1m0s
[15:29:34.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:91:0)
[15:29:34.971] Timer tick, asking Buildbucket for the build status
[15:29:35.035] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:35.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:92:0) after 1m0s
[15:30:35.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:92:0)
[15:30:35.109] Timer tick, asking Buildbucket for the build status
[15:30:35.272] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:35.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:93:0) after 1m0s
[15:31:35.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:93:0)
[15:31:35.404] Timer tick, asking Buildbucket for the build status
[15:31:35.478] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:35.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:94:0) after 1m0s
[15:32:35.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:94:0)
[15:32:35.637] Timer tick, asking Buildbucket for the build status
[15:32:35.765] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:35.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:95:0) after 1m0s
[15:33:35.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:95:0)
[15:33:35.857] Timer tick, asking Buildbucket for the build status
[15:33:36.038] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:36.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:96:0) after 1m0s
[15:34:36.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:96:0)
[15:34:36.199] Timer tick, asking Buildbucket for the build status
[15:34:36.294] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:36.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:97:0) after 1m0s
[15:35:37.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:97:0)
[15:35:37.627] Timer tick, asking Buildbucket for the build status
[15:35:37.722] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:37.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:98:0) after 1m0s
[15:36:38.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:98:0)
[15:36:38.034] Timer tick, asking Buildbucket for the build status
[15:36:38.326] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:38.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:99:0) after 1m0s
[15:37:38.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:99:0)
[15:37:38.502] Timer tick, asking Buildbucket for the build status
[15:37:38.583] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:38.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:100:0) after 1m0s
[15:38:38.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:100:0)
[15:38:38.800] Timer tick, asking Buildbucket for the build status
[15:38:39.184] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:39.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:101:0) after 1m0s
[15:39:39.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:101:0)
[15:39:39.414] Timer tick, asking Buildbucket for the build status
[15:39:39.711] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:39.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:102:0) after 1m0s
[15:40:39.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:102:0)
[15:40:39.894] Timer tick, asking Buildbucket for the build status
[15:40:40.017] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:40.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:103:0) after 1m0s
[15:41:40.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:103:0)
[15:41:40.048] Timer tick, asking Buildbucket for the build status
[15:41:40.223] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:40.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:104:0) after 1m0s
[15:42:40.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:104:0)
[15:42:40.246] Timer tick, asking Buildbucket for the build status
[15:42:40.415] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:40.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:105:0) after 1m0s
[15:43:40.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:105:0)
[15:43:40.434] Timer tick, asking Buildbucket for the build status
[15:43:40.791] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:40.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:106:0) after 1m0s
[15:44:40.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:106:0)
[15:44:40.817] Timer tick, asking Buildbucket for the build status
[15:44:41.409] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:41.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:107:0) after 1m0s
[15:45:41.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:107:0)
[15:45:41.429] Timer tick, asking Buildbucket for the build status
[15:45:41.543] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:41.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:108:0) after 1m0s
[15:46:41.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:108:0)
[15:46:41.582] Timer tick, asking Buildbucket for the build status
[15:46:41.647] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:41.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:109:0) after 1m0s
[15:47:41.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:109:0)
[15:47:41.749] Timer tick, asking Buildbucket for the build status
[15:47:41.809] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:41.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:110:0) after 1m0s
[15:48:41.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:110:0)
[15:48:41.890] Timer tick, asking Buildbucket for the build status
[15:48:42.130] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:42.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:111:0) after 1m0s
[15:49:42.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:111:0)
[15:49:42.252] Timer tick, asking Buildbucket for the build status
[15:49:42.356] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:42.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:112:0) after 1m0s
[15:50:42.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:112:0)
[15:50:42.419] Timer tick, asking Buildbucket for the build status
[15:50:42.613] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:42.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:113:0) after 1m0s
[15:51:42.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:113:0)
[15:51:42.633] Timer tick, asking Buildbucket for the build status
[15:51:42.848] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:42.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:114:0) after 1m0s
[15:52:42.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:114:0)
[15:52:42.890] Timer tick, asking Buildbucket for the build status
[15:52:43.104] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:43.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:115:0) after 1m0s
[15:53:43.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:115:0)
[15:53:43.234] Timer tick, asking Buildbucket for the build status
[15:53:43.369] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:43.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:116:0) after 1m0s
[15:54:43.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:116:0)
[15:54:43.417] Timer tick, asking Buildbucket for the build status
[15:54:44.116] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:44.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:117:0) after 1m0s
[15:55:44.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:117:0)
[15:55:44.194] Timer tick, asking Buildbucket for the build status
[15:55:44.540] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:44.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:118:0) after 1m0s
[15:56:44.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:118:0)
[15:56:44.563] Timer tick, asking Buildbucket for the build status
[15:56:44.631] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:44.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:119:0) after 1m0s
[15:57:44.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:119:0)
[15:57:44.731] Timer tick, asking Buildbucket for the build status
[15:57:45.109] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:120:0) after 1m0s
[15:58:45.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:120:0)
[15:58:45.313] Timer tick, asking Buildbucket for the build status
[15:58:45.399] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:45.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:121:0) after 1m0s
[15:59:45.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:121:0)
[15:59:45.496] Timer tick, asking Buildbucket for the build status
[15:59:45.610] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:45.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:122:0) after 1m0s
[16:00:45.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:122:0)
[16:00:45.721] Timer tick, asking Buildbucket for the build status
[16:00:46.252] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:46.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:123:0) after 1m0s
[16:01:46.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:123:0)
[16:01:46.283] Timer tick, asking Buildbucket for the build status
[16:01:46.407] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:46.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:124:0) after 1m0s
[16:02:46.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:124:0)
[16:02:46.533] Timer tick, asking Buildbucket for the build status
[16:02:46.617] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:46.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:125:0) after 1m0s
[16:03:46.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:125:0)
[16:03:46.694] Timer tick, asking Buildbucket for the build status
[16:03:47.525] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:47.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:126:0) after 1m0s
[16:04:47.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:126:0)
[16:04:47.572] Timer tick, asking Buildbucket for the build status
[16:04:47.881] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:47.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:127:0) after 1m0s
[16:05:48.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:127:0)
[16:05:48.114] Timer tick, asking Buildbucket for the build status
[16:05:48.231] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:48.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:128:0) after 1m0s
[16:06:48.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:128:0)
[16:06:48.644] Timer tick, asking Buildbucket for the build status
[16:06:48.805] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:48.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:129:0) after 1m0s
[16:07:49.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:129:0)
[16:07:49.332] Timer tick, asking Buildbucket for the build status
[16:07:49.447] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:49.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:130:0) after 1m0s
[16:08:49.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:130:0)
[16:08:49.730] Timer tick, asking Buildbucket for the build status
[16:08:51.706] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:51.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:131:0) after 1m0s
[16:09:51.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:131:0)
[16:09:51.927] Timer tick, asking Buildbucket for the build status
[16:09:52.424] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:52.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:132:0) after 1m0s
[16:10:52.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:132:0)
[16:10:52.744] Timer tick, asking Buildbucket for the build status
[16:10:53.065] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:53.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:133:0) after 1m0s
[16:11:53.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:133:0)
[16:11:53.225] Timer tick, asking Buildbucket for the build status
[16:11:53.532] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:53.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:134:0) after 1m0s
[16:12:53.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:134:0)
[16:12:53.680] Timer tick, asking Buildbucket for the build status
[16:12:53.935] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:53.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:135:0) after 1m0s
[16:13:54.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:135:0)
[16:13:54.163] Timer tick, asking Buildbucket for the build status
[16:13:54.652] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:54.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:136:0) after 1m0s
[16:14:54.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:136:0)
[16:14:54.810] Timer tick, asking Buildbucket for the build status
[16:14:54.908] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:54.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:137:0) after 1m0s
[16:15:55.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:137:0)
[16:15:55.040] Timer tick, asking Buildbucket for the build status
[16:15:55.153] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:55.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:138:0) after 1m0s
[16:16:55.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:138:0)
[16:16:55.162] Timer tick, asking Buildbucket for the build status
[16:16:55.269] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:55.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:139:0) after 1m0s
[16:17:55.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:139:0)
[16:17:55.442] Timer tick, asking Buildbucket for the build status
[16:17:55.542] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:55.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:140:0) after 1m0s
[16:18:55.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:140:0)
[16:18:55.647] Timer tick, asking Buildbucket for the build status
[16:18:55.717] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:55.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:141:0) after 1m0s
[16:19:55.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:141:0)
[16:19:55.886] Timer tick, asking Buildbucket for the build status
[16:19:56.114] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:56.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:142:0) after 1m0s
[16:20:56.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:142:0)
[16:20:56.286] Timer tick, asking Buildbucket for the build status
[16:20:56.894] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:56.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:143:0) after 1m0s
[16:21:57.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:143:0)
[16:21:57.095] Timer tick, asking Buildbucket for the build status
[16:21:57.163] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:57.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:144:0) after 1m0s
[16:22:57.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:144:0)
[16:22:57.374] Timer tick, asking Buildbucket for the build status
[16:22:57.726] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:57.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:145:0) after 1m0s
[16:23:57.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:145:0)
[16:23:57.841] Timer tick, asking Buildbucket for the build status
[16:23:57.908] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:57.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:146:0) after 1m0s
[16:24:57.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:146:0)
[16:24:57.983] Timer tick, asking Buildbucket for the build status
[16:24:58.042] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:58.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:147:0) after 1m0s
[16:25:58.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:147:0)
[16:25:58.254] Timer tick, asking Buildbucket for the build status
[16:25:58.381] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:58.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:148:0) after 1m0s
[16:26:58.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:148:0)
[16:26:58.436] Timer tick, asking Buildbucket for the build status
[16:26:58.820] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:58.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:149:0) after 1m0s
[16:27:58.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:149:0)
[16:27:58.910] Timer tick, asking Buildbucket for the build status
[16:27:58.986] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:58.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:150:0) after 1m0s
[16:28:59.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:150:0)
[16:28:59.010] Timer tick, asking Buildbucket for the build status
[16:28:59.095] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:59.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:151:0) after 1m0s
[16:29:59.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:151:0)
[16:29:59.127] Timer tick, asking Buildbucket for the build status
[16:29:59.193] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:59.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:152:0) after 1m0s
[16:30:59.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:152:0)
[16:30:59.214] Timer tick, asking Buildbucket for the build status
[16:30:59.707] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:59.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:153:0) after 1m0s
[16:31:59.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:153:0)
[16:31:59.795] Timer tick, asking Buildbucket for the build status
[16:31:59.872] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:59.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:154:0) after 1m0s
[16:32:59.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:154:0)
[16:32:59.957] Timer tick, asking Buildbucket for the build status
[16:33:00.276] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:00.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:155:0) after 1m0s
[16:34:00.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:155:0)
[16:34:00.305] Timer tick, asking Buildbucket for the build status
[16:34:00.657] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:00.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:156:0) after 1m0s
[16:35:00.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:156:0)
[16:35:00.851] Timer tick, asking Buildbucket for the build status
[16:35:01.063] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:01.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:157:0) after 1m0s
[16:36:01.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:157:0)
[16:36:01.199] Timer tick, asking Buildbucket for the build status
[16:36:01.671] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:01.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:158:0) after 1m0s
[16:37:01.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:158:0)
[16:37:01.699] Timer tick, asking Buildbucket for the build status
[16:37:01.920] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:01.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:159:0) after 1m0s
[16:38:02.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:159:0)
[16:38:02.024] Timer tick, asking Buildbucket for the build status
[16:38:02.150] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:02.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:160:0) after 1m0s
[16:39:02.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:160:0)
[16:39:02.270] Timer tick, asking Buildbucket for the build status
[16:39:02.367] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:02.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:161:0) after 1m0s
[16:40:02.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:161:0)
[16:40:02.343] Timer tick, asking Buildbucket for the build status
[16:40:02.558] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:02.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:162:0) after 1m0s
[16:41:02.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:162:0)
[16:41:02.632] Timer tick, asking Buildbucket for the build status
[16:41:02.759] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:02.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:163:0) after 1m0s
[16:42:02.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:163:0)
[16:42:02.790] Timer tick, asking Buildbucket for the build status
[16:42:03.269] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:03.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:164:0) after 1m0s
[16:43:03.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:164:0)
[16:43:03.427] Timer tick, asking Buildbucket for the build status
[16:43:03.556] Build 8855948480561693344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:03.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021378543587639840:165:0) after 1m0s
[16:43:41.358] Received PubSub notification, asking Buildbucket for the build status
[16:43:41.470] Build 8855948480561693344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:43:41.470] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612716220546144",
"created_by": "project:chromium",
"created_ts": "1612706408879485",
"id": "8855948480561693344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff\"}}",
"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\": \"cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff\", \"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-47-r25s\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22519, \"got_angle_revision\": \"580961fbb01984d2d9b2f382609f9fc0c44e6b82\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff\", \"got_revision_cp\": \"refs/heads/master@{#851535}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"462378addab907ab7452d38baccb27fe2ef7289a\", \"got_v8_revision_cp\": \"refs/heads/9.0.138@{#1}\", \"got_webrtc_revision\": \"c3c63c8ceaf4ac3646b1e17bfe8a97e7102f0913\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33185}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff\", \"swarm_hashes_refs/heads/master(at){#851535}_without_patch\": {\"absl_hardening_tests\": \"72ace6e1068c9d25ae51939c5966a28855a532f2\", \"accessibility_unittests\": \"d4d97ef6c5011f06bf9b05d50fb9fb4751698820\", \"angle_unittests\": \"fdf719f021b0416b7b4ad20ee42af569261d226e\", \"app_list_unittests\": \"a703a0325b224d9d6499e4f59aa9f283372fe3c7\", \"app_shell_unittests\": \"d13871cdd7d9765dd1b228e62246c7b5fd1452d0\", \"ash_unittests\": \"f08a72a6db514d3abab561fff199133b0b75fc78\", \"aura_unittests\": \"0d1a47b20ebdfc6b14aa596333cbce9b13441d0a\", \"base_unittests\": \"fcbcd681a256463183590da794a815cc996478ba\", \"base_util_unittests\": \"284a8c94320941e1b12e5d036a3c585c34bc311d\", \"blink_common_unittests\": \"c79e7758b88348cd3d48a956661d12d20b10e2d9\", \"blink_fuzzer_unittests\": \"92d213440e02761dfa5bdb9a7855ccfa97f65682\", \"blink_heap_unittests\": \"1fd2ffb52c667c232223869ba1abb1be19734b4e\", \"blink_platform_unittests\": \"c51c4635d1ab578e43348edd45b4a856b6f763e4\", \"blink_unittests\": \"17fd44d4befedd06f62d706fd04e7aa9fbb8109c\", \"boringssl_crypto_tests\": \"06fc510332ac5610058a27ea2dca29fa30fc4c0d\", \"boringssl_ssl_tests\": \"ad031920ad178864795e6a8c86e1463e6dc1cf64\", \"browser_tests\": \"0667fd963a82f0db6d99beb5675b2bc2bc3fcf5c\", \"capture_unittests\": \"bdf0fe97ca723efbf4ec4a39a603d86c4765134f\", \"cast_unittests\": \"cb88506bd1a6b9b511976d98b5e4a46749cee9c2\", \"cc_unittests\": \"7d3919cd4af536eaaf266a7195acbfcb198c8cd7\", \"chrome_app_unittests\": \"d01dd791bcd4eea5688872c39d116c770839af52\", \"chromedriver_unittests\": \"964f0f864a913848d63af910172e898044a56946\", \"chromeos_components_unittests\": \"2d2ea7a6c20f45f1953605ab6750d801ba018227\", \"chromeos_unittests\": \"fac4842ea68753638a2b50b53f246a75c4fb5dc0\", \"components_browsertests\": \"b8f7246c789f8986ef51f11d355c4c0c5f60bb58\", \"components_unittests\": \"41b50e10ad955e8360d98eb8f9f5c1d4df16d9da\", \"compositor_unittests\": \"97ce650f218a4ed1d07fb3a6ab91be7ec749441f\", \"content_browsertests\": \"885bab069b2e43d09a2a55f92efe51c3add0108f\", \"content_unittests\": \"560e9e1ccd7e01dd1d67ce9f70181cdf0fe6c532\", \"crashpad_tests\": \"c001168a045cae4710edc4c8db7dc4a7ce295eda\", \"crypto_unittests\": \"5f1c8102a0e4a5be8e6626599964c452f23d8ade\", \"dbus_unittests\": \"85354723bd6e5ec5e403fc60f5c84e469e7d07b1\", \"device_unittests\": \"7a7ff50f7dd74aed25c7d1f8ea9ec4936ffb95f3\", \"display_unittests\": \"43c6969a492dbe475be937af04e074f13849b822\", \"events_unittests\": \"b6084990d51d980deee6803cf5281fc96228ba1a\", \"exo_unittests\": \"2fb6236f90eb78b118bd45d02311247adcad8e84\", \"extensions_browsertests\": \"034a209a449abaf44edbd3ea8c8506131fbb2e44\", \"extensions_unittests\": \"4f202d3a8d195825a5a6caceba2d4d21c1c3e6c4\", \"filesystem_service_unittests\": \"47bcb791bc54b860047d6890cbb7104dd70ed979\", \"gcm_unit_tests\": \"f16f6088ce4d36fa2bd63da0e197373868e8892e\", \"gfx_unittests\": \"a60398810db5bfc900ee08a247056de74997acc7\", \"gin_unittests\": \"1253a521790a896c0ac9c8ad34527edfb3462a27\", \"gl_unittests_ozone\": \"26f9f0dbf500e5531f56ed3e899bb06a2a3f584e\", \"google_apis_unittests\": \"7b2b11e0c514a1e6629656a23ff733bec7a73d99\", \"gpu_unittests\": \"247b6c3c7bd66026830a9c6d7f641c3234753731\", \"gwp_asan_unittests\": \"22479c7ffb6e53da01bda32d4183246e68062540\", \"interactive_ui_tests\": \"fc80a376cee8a1a2212ba5167b2e1318a5ae3fc2\", \"ipc_tests\": \"b7159edf41d62b73db25b34ebe57fd4e7163b42a\", \"jingle_unittests\": \"f4c1a8b9c69d4c2325f7134a8206b0817b2c3467\", \"keyboard_unittests\": \"a5fe7f3e3f127301aae4e63d9ad188120e9c7ea0\", \"latency_unittests\": \"3129eea8dcfa4620b56c55151fd4703e44212595\", \"libjingle_xmpp_unittests\": \"ce9d9684d93640140bb1162201e1a8eee102eae2\", \"liburlpattern_unittests\": \"5e7905518782d61b45075e4a3801cfea30a2c4a6\", \"media_blink_unittests\": \"d7294f253be04bce073fdb096956d368660656b3\", \"media_unittests\": \"15ccfd9f78e68a081b57577b2ff4e7c57cbb1acb\", \"message_center_unittests\": \"2beeecaa16df4c605f04daf525c20063dae115ca\", \"midi_unittests\": \"38af95de22d72e16c2e614075dcda7a6a4f4966b\", \"mojo_core_unittests\": \"ca9ae1e93a7e99ec291c8e2dd7bb84c3f5b6e48d\", \"mojo_unittests\": \"46c9f5acc41459334bea1baa72db4308af44d3a3\", \"nacl_helper_nonsfi_unittests\": \"ad5e7236874a4a37bfb82d85fe376b7d15224324\", \"nacl_loader_unittests\": \"08b9168ed19d218135ebc12c7686700cdc3dacf7\", \"native_theme_unittests\": \"508916b1bcf693745233dea89d61c2d8be7a0ec6\", \"net_unittests\": \"927d32fd955b567d4cdf9106044697828854b94d\", \"ozone_gl_unittests\": \"379691734f63210dec4bf907612208fb6cb65b2d\", \"ozone_unittests\": \"37b0943ccf221aa8ed44a6de7d9edc7403720b79\", \"ozone_x11_unittests\": \"b8a4098b78e961d8a12c1254270ca94e85528550\", \"pdf_unittests\": \"48198c0226b4ad1e1a2b3354477d97e4d054b761\", \"perfetto_unittests\": \"163c3aacc9dda0c9d6d4c779d250acccc83862a1\", \"ppapi_unittests\": \"1211f7ccf1b3ecb6cafd67d8de6ea348511290f8\", \"printing_unittests\": \"ec2824ce5a12b5b5b9f30955d08d6c49be1d7acc\", \"remoting_unittests\": \"a3e1e6d15ce5806e8b2be4fdd66db56bb051490a\", \"sandbox_linux_unittests\": \"6630b6b835a0383052dd149777ac66d18db3d06a\", \"service_manager_unittests\": \"f33be2e22abd481cf8054d2e43a856329ee29921\", \"services_unittests\": \"c1938530abd19003f4604392e86045755aee4935\", \"shell_dialogs_unittests\": \"29768d12bedb8f7af7da8e48314b17a332d5b007\", \"shell_encryption_unittests\": \"a1b2a87e75953e8eaaae199dfe5279e763a68833\", \"skia_unittests\": \"ee09a6d8e21374a82d44cd26091f9e21396620a5\", \"snapshot_unittests\": \"902cb476a67b0a5e1c74dff75e170b071c6a8ebc\", \"sql_unittests\": \"814e1ac84e4fd69bd13083c9afc242708c35673a\", \"storage_unittests\": \"8499cc6a256a180d2581f20a6facc9bab066bf90\", \"sync_integration_tests\": \"132e5a70fc42d10bae1570d44c707679430160b2\", \"traffic_annotation_auditor_unittests\": \"411e3f6b2fe25dd65b3d53fdeeca8ca7e4373f18\", \"ui_base_unittests\": \"427f49cf3f38a3a4d6ca448523054a86b3eb84e2\", \"ui_chromeos_unittests\": \"a9ae9cac7c961f141bdb53fa29b5d2a8b29409b6\", \"ui_touch_selection_unittests\": \"c20cb9e298333d127f56a14d62bc5b64380b735c\", \"unit_tests\": \"30e85969d038f23751a6963898c8109c9a12afe5\", \"url_unittests\": \"100e1bbac0c9bdc74684040b4c244b95924d486d\", \"usage_time_limit_unittests\": \"e2aa61845eb540a02088a343aa6693566186b339\", \"views_unittests\": \"c97d0d60b11b089dccb6cd50c39920321add98ab\", \"viz_unittests\": \"73b2d82f3931fb27bb1b53f3ccace16f0cefee4b\", \"wm_unittests\": \"547b78ee5f064d4ffefedea0a1b2db1de1babe5f\", \"wtf_unittests\": \"05fcf4bcf4e2a0ab9db2fff9bcdf576454d487f4\", \"zlib_unittests\": \"316cb844626483ed1d5cc96e382fafb4bb99118b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2bff2df219af63c0787c4be2031d3fbd218ec1b0853f1b8ce80b2331f0e04ffd_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_ce22d0fc5c297d03141230de59f52ebaf9005395a667e8b14df59e499796620f_v2\", \"builder_f568157a4ec00fb9568da05a054c670841713183d1f68e80f18d1b79a7a19e1f_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-47-r25s\"], \"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\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612709232977978",
"status": "COMPLETED",
"status_changed_ts": "1612716220975144",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22519",
"builder:linux-chromeos-dbg",
"buildset:commit/git/cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cda0c1c11cea4df5bd5cb51f1ec5b843c9bb6bff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021378543587639840",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8855948480561693344/+/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:51959c77d2329910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612716220981903",
"url": "https://ci.chromium.org/b/8855948480561693344",
"utcnow_ts": "1612716221458609"
}
}
[16:43:41.470] Invocation finished in 2h43m34.859415132s with status SUCCEEDED