[02:09:15.433] New invocation is queued and will start shortly
[02:09:16.511] Starting the invocation (attempt 1)
[02:09:16.554] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:09:16.554] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:09:16.555] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019973715139789568",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"87a7da1cf82aa086a7f4535b5dad9c02da18842e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019973715139789568",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/87a7da1cf82aa086a7f4535b5dad9c02da18842e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87a7da1cf82aa086a7f4535b5dad9c02da18842e",
"gitiles_ref:refs/heads/master"
]
}
[02:09:17.165] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614046156630455",
"id": "8854543653224247152",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"87a7da1cf82aa086a7f4535b5dad9c02da18842e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614046157017287",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45646",
"builder:linux-chromeos-rel",
"buildset:commit/git/87a7da1cf82aa086a7f4535b5dad9c02da18842e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87a7da1cf82aa086a7f4535b5dad9c02da18842e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019973715139789568",
"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/8854543653224247152/+/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": "1614046157017416",
"url": "https://ci.chromium.org/b/8854543653224247152",
"utcnow_ts": "1614046157153156"
}
}
[02:09:17.165] Task URL: https://ci.chromium.org/b/8854543653224247152
[02:09:17.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:2:0) after 1m0s
[02:10:17.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:2:0)
[02:10:17.220] Timer tick, asking Buildbucket for the build status
[02:10:17.513] Build 8854543653224247152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:17.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:3:0) after 1m0s
[02:11:17.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:3:0)
[02:11:17.524] Timer tick, asking Buildbucket for the build status
[02:11:17.632] Build 8854543653224247152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:17.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:4:0) after 1m0s
[02:12:17.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:4:0)
[02:12:17.675] Timer tick, asking Buildbucket for the build status
[02:12:17.793] Build 8854543653224247152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:17.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:5:0) after 1m0s
[02:13:17.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:5:0)
[02:13:17.899] Timer tick, asking Buildbucket for the build status
[02:13:17.967] Build 8854543653224247152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:17.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:6:0) after 1m0s
[02:14:04.145] Received PubSub notification, asking Buildbucket for the build status
[02:14:04.231] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:18.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:6:0)
[02:14:18.068] Timer tick, asking Buildbucket for the build status
[02:14:18.172] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:18.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:8:0) after 1m0s
[02:15:18.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:8:0)
[02:15:18.264] Timer tick, asking Buildbucket for the build status
[02:15:18.337] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:18.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:9:0) after 1m0s
[02:16:18.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:9:0)
[02:16:18.308] Timer tick, asking Buildbucket for the build status
[02:16:18.464] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:18.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:10:0) after 1m0s
[02:17:18.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:10:0)
[02:17:18.471] Timer tick, asking Buildbucket for the build status
[02:17:18.558] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:18.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:11:0) after 1m0s
[02:18:18.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:11:0)
[02:18:18.637] Timer tick, asking Buildbucket for the build status
[02:18:18.723] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:18.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:12:0) after 1m0s
[02:19:18.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:12:0)
[02:19:18.754] Timer tick, asking Buildbucket for the build status
[02:19:18.858] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:18.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:13:0) after 1m0s
[02:20:18.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:13:0)
[02:20:18.941] Timer tick, asking Buildbucket for the build status
[02:20:19.083] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:19.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:14:0) after 1m0s
[02:21:19.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:14:0)
[02:21:19.101] Timer tick, asking Buildbucket for the build status
[02:21:19.174] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:19.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:15:0) after 1m0s
[02:22:19.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:15:0)
[02:22:19.239] Timer tick, asking Buildbucket for the build status
[02:22:19.354] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:19.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:16:0) after 1m0s
[02:23:19.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:16:0)
[02:23:19.366] Timer tick, asking Buildbucket for the build status
[02:23:19.912] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:19.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:17:0) after 1m0s
[02:24:20.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:17:0)
[02:24:20.007] Timer tick, asking Buildbucket for the build status
[02:24:20.098] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:20.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:18:0) after 1m0s
[02:25:20.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:18:0)
[02:25:20.167] Timer tick, asking Buildbucket for the build status
[02:25:20.267] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:20.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:19:0) after 1m0s
[02:26:20.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:19:0)
[02:26:20.287] Timer tick, asking Buildbucket for the build status
[02:26:20.385] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:20.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:20:0) after 1m0s
[02:27:20.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:20:0)
[02:27:20.414] Timer tick, asking Buildbucket for the build status
[02:27:20.869] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:20.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:21:0) after 1m0s
[02:28:20.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:21:0)
[02:28:20.876] Timer tick, asking Buildbucket for the build status
[02:28:20.970] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:20.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:22:0) after 1m0s
[02:29:20.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:22:0)
[02:29:20.984] Timer tick, asking Buildbucket for the build status
[02:29:21.071] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:21.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:23:0) after 1m0s
[02:30:21.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:23:0)
[02:30:21.137] Timer tick, asking Buildbucket for the build status
[02:30:21.340] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:21.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:24:0) after 1m0s
[02:31:21.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:24:0)
[02:31:21.368] Timer tick, asking Buildbucket for the build status
[02:31:21.559] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:21.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:25:0) after 1m0s
[02:32:21.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:25:0)
[02:32:21.574] Timer tick, asking Buildbucket for the build status
[02:32:21.673] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:21.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:26:0) after 1m0s
[02:33:21.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:26:0)
[02:33:21.733] Timer tick, asking Buildbucket for the build status
[02:33:21.904] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:21.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:27:0) after 1m0s
[02:34:21.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:27:0)
[02:34:21.920] Timer tick, asking Buildbucket for the build status
[02:34:22.026] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:22.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:28:0) after 1m0s
[02:35:22.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:28:0)
[02:35:22.042] Timer tick, asking Buildbucket for the build status
[02:35:22.213] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:22.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:29:0) after 1m0s
[02:36:22.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:29:0)
[02:36:22.229] Timer tick, asking Buildbucket for the build status
[02:36:22.303] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:22.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:30:0) after 1m0s
[02:37:22.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:30:0)
[02:37:22.376] Timer tick, asking Buildbucket for the build status
[02:37:22.570] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:22.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:31:0) after 1m0s
[02:38:22.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:31:0)
[02:38:22.641] Timer tick, asking Buildbucket for the build status
[02:38:22.715] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:22.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:32:0) after 1m0s
[02:39:22.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:32:0)
[02:39:22.784] Timer tick, asking Buildbucket for the build status
[02:39:22.923] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:22.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:33:0) after 1m0s
[02:40:22.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:33:0)
[02:40:22.988] Timer tick, asking Buildbucket for the build status
[02:40:23.059] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:23.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:34:0) after 1m0s
[02:41:23.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:34:0)
[02:41:23.105] Timer tick, asking Buildbucket for the build status
[02:41:23.244] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:23.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:35:0) after 1m0s
[02:42:23.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:35:0)
[02:42:23.311] Timer tick, asking Buildbucket for the build status
[02:42:23.435] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:23.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:36:0) after 1m0s
[02:43:23.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:36:0)
[02:43:23.536] Timer tick, asking Buildbucket for the build status
[02:43:23.612] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:23.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:37:0) after 1m0s
[02:44:23.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:37:0)
[02:44:23.628] Timer tick, asking Buildbucket for the build status
[02:44:23.715] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:23.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:38:0) after 1m0s
[02:45:23.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:38:0)
[02:45:23.721] Timer tick, asking Buildbucket for the build status
[02:45:23.815] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:23.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:39:0) after 1m0s
[02:46:23.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:39:0)
[02:46:23.834] Timer tick, asking Buildbucket for the build status
[02:46:24.113] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:24.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:40:0) after 1m0s
[02:47:24.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:40:0)
[02:47:24.121] Timer tick, asking Buildbucket for the build status
[02:47:24.405] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:24.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:41:0) after 1m0s
[02:48:24.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:41:0)
[02:48:24.493] Timer tick, asking Buildbucket for the build status
[02:48:24.570] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:24.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:42:0) after 1m0s
[02:49:24.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:42:0)
[02:49:24.617] Timer tick, asking Buildbucket for the build status
[02:49:24.721] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:24.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:43:0) after 1m0s
[02:50:24.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:43:0)
[02:50:24.737] Timer tick, asking Buildbucket for the build status
[02:50:24.873] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:24.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:44:0) after 1m0s
[02:51:24.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:44:0)
[02:51:24.890] Timer tick, asking Buildbucket for the build status
[02:51:25.096] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:25.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:45:0) after 1m0s
[02:52:25.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:45:0)
[02:52:25.099] Timer tick, asking Buildbucket for the build status
[02:52:25.403] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:25.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:46:0) after 1m0s
[02:53:25.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:46:0)
[02:53:25.441] Timer tick, asking Buildbucket for the build status
[02:53:25.663] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:25.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:47:0) after 1m0s
[02:54:25.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:47:0)
[02:54:25.688] Timer tick, asking Buildbucket for the build status
[02:54:25.774] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:25.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:48:0) after 1m0s
[02:55:25.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:48:0)
[02:55:25.797] Timer tick, asking Buildbucket for the build status
[02:55:25.873] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:25.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:49:0) after 1m0s
[02:56:25.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:49:0)
[02:56:25.894] Timer tick, asking Buildbucket for the build status
[02:56:26.032] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:26.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:50:0) after 1m0s
[02:57:26.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:50:0)
[02:57:26.052] Timer tick, asking Buildbucket for the build status
[02:57:26.365] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:26.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:51:0) after 1m0s
[02:58:26.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:51:0)
[02:58:26.383] Timer tick, asking Buildbucket for the build status
[02:58:26.539] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:26.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:52:0) after 1m0s
[02:59:26.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:52:0)
[02:59:26.526] Timer tick, asking Buildbucket for the build status
[02:59:26.623] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:26.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:53:0) after 1m0s
[03:00:26.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:53:0)
[03:00:26.628] Timer tick, asking Buildbucket for the build status
[03:00:26.822] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:26.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:54:0) after 1m0s
[03:01:26.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:54:0)
[03:01:26.888] Timer tick, asking Buildbucket for the build status
[03:01:27.007] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:27.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:55:0) after 1m0s
[03:02:27.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:55:0)
[03:02:27.020] Timer tick, asking Buildbucket for the build status
[03:02:27.165] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:27.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:56:0) after 1m0s
[03:03:27.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:56:0)
[03:03:27.245] Timer tick, asking Buildbucket for the build status
[03:03:27.534] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:27.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:57:0) after 1m0s
[03:04:27.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:57:0)
[03:04:27.528] Timer tick, asking Buildbucket for the build status
[03:04:27.795] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:27.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:58:0) after 1m0s
[03:05:27.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:58:0)
[03:05:27.824] Timer tick, asking Buildbucket for the build status
[03:05:27.904] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:27.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:59:0) after 1m0s
[03:06:27.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:59:0)
[03:06:27.899] Timer tick, asking Buildbucket for the build status
[03:06:27.973] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:27.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:60:0) after 1m0s
[03:07:27.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:60:0)
[03:07:27.974] Timer tick, asking Buildbucket for the build status
[03:07:28.046] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:28.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:61:0) after 1m0s
[03:08:28.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:61:0)
[03:08:28.112] Timer tick, asking Buildbucket for the build status
[03:08:28.236] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:28.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:62:0) after 1m0s
[03:09:28.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:62:0)
[03:09:28.308] Timer tick, asking Buildbucket for the build status
[03:09:28.440] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:28.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:63:0) after 1m0s
[03:10:28.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:63:0)
[03:10:28.589] Timer tick, asking Buildbucket for the build status
[03:10:28.688] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:28.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:64:0) after 1m0s
[03:11:28.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:64:0)
[03:11:28.754] Timer tick, asking Buildbucket for the build status
[03:11:28.842] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:28.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:65:0) after 1m0s
[03:12:28.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:65:0)
[03:12:28.893] Timer tick, asking Buildbucket for the build status
[03:12:28.971] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:28.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:66:0) after 1m0s
[03:13:29.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:66:0)
[03:13:29.034] Timer tick, asking Buildbucket for the build status
[03:13:29.440] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:29.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:67:0) after 1m0s
[03:14:29.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:67:0)
[03:14:29.436] Timer tick, asking Buildbucket for the build status
[03:14:29.560] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:29.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:68:0) after 1m0s
[03:15:29.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:68:0)
[03:15:29.580] Timer tick, asking Buildbucket for the build status
[03:15:29.934] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:29.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:69:0) after 1m0s
[03:16:29.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:69:0)
[03:16:29.998] Timer tick, asking Buildbucket for the build status
[03:16:30.082] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:30.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:70:0) after 1m0s
[03:17:30.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:70:0)
[03:17:30.134] Timer tick, asking Buildbucket for the build status
[03:17:30.229] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:30.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:71:0) after 1m0s
[03:18:30.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:71:0)
[03:18:30.298] Timer tick, asking Buildbucket for the build status
[03:18:30.458] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:30.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:72:0) after 1m0s
[03:19:30.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:72:0)
[03:19:30.515] Timer tick, asking Buildbucket for the build status
[03:19:30.630] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:30.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:73:0) after 1m0s
[03:20:30.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:73:0)
[03:20:30.669] Timer tick, asking Buildbucket for the build status
[03:20:30.886] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:30.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:74:0) after 1m0s
[03:21:30.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:74:0)
[03:21:30.986] Timer tick, asking Buildbucket for the build status
[03:21:31.136] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:31.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:75:0) after 1m0s
[03:22:31.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:75:0)
[03:22:31.211] Timer tick, asking Buildbucket for the build status
[03:22:31.282] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:31.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:76:0) after 1m0s
[03:23:31.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:76:0)
[03:23:31.357] Timer tick, asking Buildbucket for the build status
[03:23:31.450] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:31.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:77:0) after 1m0s
[03:24:31.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:77:0)
[03:24:31.531] Timer tick, asking Buildbucket for the build status
[03:24:31.905] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:31.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:78:0) after 1m0s
[03:25:32.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:78:0)
[03:25:32.011] Timer tick, asking Buildbucket for the build status
[03:25:32.463] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:32.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:79:0) after 1m0s
[03:26:32.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:79:0)
[03:26:32.479] Timer tick, asking Buildbucket for the build status
[03:26:32.733] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:32.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:80:0) after 1m0s
[03:27:32.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:80:0)
[03:27:32.739] Timer tick, asking Buildbucket for the build status
[03:27:33.090] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:33.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:81:0) after 1m0s
[03:28:33.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:81:0)
[03:28:33.134] Timer tick, asking Buildbucket for the build status
[03:28:33.204] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:33.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:82:0) after 1m0s
[03:29:33.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:82:0)
[03:29:33.300] Timer tick, asking Buildbucket for the build status
[03:29:33.622] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:33.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:83:0) after 1m0s
[03:30:33.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:83:0)
[03:30:33.705] Timer tick, asking Buildbucket for the build status
[03:30:33.780] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:33.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:84:0) after 1m0s
[03:31:33.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:84:0)
[03:31:33.991] Timer tick, asking Buildbucket for the build status
[03:31:34.329] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:34.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:85:0) after 1m0s
[03:32:34.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:85:0)
[03:32:34.988] Timer tick, asking Buildbucket for the build status
[03:32:35.064] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:35.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:86:0) after 1m0s
[03:33:35.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:86:0)
[03:33:35.274] Timer tick, asking Buildbucket for the build status
[03:33:35.341] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:35.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:87:0) after 1m0s
[03:34:35.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:87:0)
[03:34:35.427] Timer tick, asking Buildbucket for the build status
[03:34:35.712] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:35.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:88:0) after 1m0s
[03:35:35.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:88:0)
[03:35:35.921] Timer tick, asking Buildbucket for the build status
[03:35:35.989] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:35.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:89:0) after 1m0s
[03:36:36.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:89:0)
[03:36:36.016] Timer tick, asking Buildbucket for the build status
[03:36:36.322] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:36.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:90:0) after 1m0s
[03:37:36.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:90:0)
[03:37:36.370] Timer tick, asking Buildbucket for the build status
[03:37:36.441] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:36.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:91:0) after 1m0s
[03:38:36.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:91:0)
[03:38:36.459] Timer tick, asking Buildbucket for the build status
[03:38:36.585] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:36.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:92:0) after 1m0s
[03:39:36.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:92:0)
[03:39:36.604] Timer tick, asking Buildbucket for the build status
[03:39:36.888] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:36.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:93:0) after 1m0s
[03:40:37.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:93:0)
[03:40:37.036] Timer tick, asking Buildbucket for the build status
[03:40:37.124] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:37.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:94:0) after 1m0s
[03:41:37.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:94:0)
[03:41:37.164] Timer tick, asking Buildbucket for the build status
[03:41:37.237] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:37.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:95:0) after 1m0s
[03:42:37.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:95:0)
[03:42:37.351] Timer tick, asking Buildbucket for the build status
[03:42:37.439] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:37.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:96:0) after 1m0s
[03:43:37.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:96:0)
[03:43:37.466] Timer tick, asking Buildbucket for the build status
[03:43:37.697] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:37.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:97:0) after 1m0s
[03:44:37.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:97:0)
[03:44:37.718] Timer tick, asking Buildbucket for the build status
[03:44:37.926] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:37.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:98:0) after 1m0s
[03:45:38.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:98:0)
[03:45:38.050] Timer tick, asking Buildbucket for the build status
[03:45:38.452] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:38.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:99:0) after 1m0s
[03:46:38.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:99:0)
[03:46:38.473] Timer tick, asking Buildbucket for the build status
[03:46:38.716] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:38.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:100:0) after 1m0s
[03:47:38.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:100:0)
[03:47:38.782] Timer tick, asking Buildbucket for the build status
[03:47:38.874] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:38.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:101:0) after 1m0s
[03:48:38.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:101:0)
[03:48:38.940] Timer tick, asking Buildbucket for the build status
[03:48:39.047] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:102:0) after 1m0s
[03:49:39.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:102:0)
[03:49:39.154] Timer tick, asking Buildbucket for the build status
[03:49:39.289] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:39.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:103:0) after 1m0s
[03:50:39.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:103:0)
[03:50:39.390] Timer tick, asking Buildbucket for the build status
[03:50:39.494] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:39.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:104:0) after 1m0s
[03:51:39.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:104:0)
[03:51:39.605] Timer tick, asking Buildbucket for the build status
[03:51:39.704] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:39.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:105:0) after 1m0s
[03:52:39.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:105:0)
[03:52:39.750] Timer tick, asking Buildbucket for the build status
[03:52:39.843] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:39.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:106:0) after 1m0s
[03:53:39.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:106:0)
[03:53:39.848] Timer tick, asking Buildbucket for the build status
[03:53:40.136] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:40.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:107:0) after 1m0s
[03:54:40.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:107:0)
[03:54:40.139] Timer tick, asking Buildbucket for the build status
[03:54:40.237] Build 8854543653224247152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:40.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019973715139789568:108:0) after 1m0s
[03:55:18.447] Received PubSub notification, asking Buildbucket for the build status
[03:55:18.528] Build 8854543653224247152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:55:18.528] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614052517507810",
"created_by": "project:chromium",
"created_ts": "1614046156630455",
"id": "8854543653224247152",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"87a7da1cf82aa086a7f4535b5dad9c02da18842e\"}}",
"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\": \"87a7da1cf82aa086a7f4535b5dad9c02da18842e\", \"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-59-1i5l\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45646, \"got_angle_revision\": \"4a95488c0edfb4590c808ee01079069da5a6b2b1\", \"got_dawn_revision\": \"2d3c2e355337037dfa6873536bfb3e3a558bf9ed\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"87a7da1cf82aa086a7f4535b5dad9c02da18842e\", \"got_revision_cp\": \"refs/heads/master@{#856556}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"4120b5f2509821e8dd460b1d033708397520750a\", \"got_v8_revision_cp\": \"refs/heads/9.0.238@{#1}\", \"got_webrtc_revision\": \"6fe3fa14c6686ba9c51095b97ad2e6833a9b03e5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33319}\", \"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\": \"87a7da1cf82aa086a7f4535b5dad9c02da18842e\", \"swarm_hashes_refs/heads/master(at){#856556}_without_patch\": {\"absl_hardening_tests\": \"3c9dd4826c8c4ccb318415964985f914e5ce86cb\", \"accessibility_unittests\": \"f276914ee696616cf1796d84fe85b558e9ce2ba4\", \"angle_unittests\": \"66ddb5f95106e1aa167e9360ab01a2c5c30141b0\", \"app_list_unittests\": \"9676b2b3d80ea58e88efb77d2ed4785a0b037619\", \"app_shell_unittests\": \"db9fd6337364f278980952e84164d28deadcb0fa\", \"ash_unittests\": \"023a38ad4d3170e6645bfac02ece01b204cee478\", \"aura_unittests\": \"c8c3635d68a45132b57f7c3f1ad69b1f020465db\", \"base_unittests\": \"5a76a5a875a8da4910c99363de22d476f8a04510\", \"base_util_unittests\": \"593fe48f705ac661ab4a13573c98851530f796b1\", \"blink_common_unittests\": \"2ce28d3b1414b36128d524b72649f3defb59ab0c\", \"blink_fuzzer_unittests\": \"ddcc3ca9158fc54c4a44293f1e52acb3b9ce0afc\", \"blink_heap_unittests\": \"9760b1e8c906106f592201415f9866a3136d45df\", \"blink_platform_unittests\": \"dd4a75dfe8738e0d621b30c495f0938125fa8fb4\", \"blink_unittests\": \"a0436f34695e022d5acf79fcee0c0a062425c708\", \"boringssl_crypto_tests\": \"daa0263a682720a16b6b10fe8a47c9d059576e02\", \"boringssl_ssl_tests\": \"ab4d22d30a37855f9635bd40ced5d0b3495acbb1\", \"browser_tests\": \"4d008148980d120eeaaf43eaf8051ec30c69d7cc\", \"capture_unittests\": \"5f3a7f34b5bf4c213401fd1c941962c9e9e56d2a\", \"cast_unittests\": \"8d9346636dc8fe0674824b0a81c1d77fa6817bb7\", \"cc_unittests\": \"645c03fbc595d8276f41115d72cd02652ece8492\", \"chrome_app_unittests\": \"7c7872b4089587ea33c96615733a2961db2cbac4\", \"chromedriver_unittests\": \"9762feb297abf271b61fd4fdc520fde25b0dd96c\", \"chromeos_components_unittests\": \"7c0c536edfa3245a50727552071948324821b4ac\", \"chromeos_unittests\": \"8d3423d4b240930efda6e8604cccbbc04d80c462\", \"components_browsertests\": \"68db9e2a4959785c072406fa40b844c32ade37de\", \"components_unittests\": \"ff8dfea9f2958a8829ae58d9bf9e33408c86d9e4\", \"compositor_unittests\": \"7ed9628ba610db1c65951c483e5405aee066099c\", \"content_browsertests\": \"f830bb7691a743d2b129803d3c1702c9c9208b85\", \"content_unittests\": \"4377e7df3324e713788b934a0255eb9af0c1be0a\", \"crashpad_tests\": \"bea33522882e4ec4252b663002a1dfd539dae484\", \"crypto_unittests\": \"c0314ba79acac3e4652c583b8d3dab341c18a1ba\", \"dbus_unittests\": \"c27090b5f95da29d2ec8d6962aba6e5c249d9b87\", \"device_unittests\": \"be324f5695d219f85260300f2562d9d72ab768db\", \"display_unittests\": \"110ef14dd8c0b23e3663ba27a6d9affe916be8c1\", \"events_unittests\": \"ae312f286e614cf4b0146c78d131443ee1f2a5d9\", \"exo_unittests\": \"987adcd9f472f1b4f01860f185034215c8dc973f\", \"extensions_browsertests\": \"4a244d28dfbca0161967e7f918687ef94dddf4dd\", \"extensions_unittests\": \"650e60c9ad1bbe5f78c927f5471b389a0b38083e\", \"filesystem_service_unittests\": \"830e213a3820d9f0a6153ce897687646c7f81ae6\", \"gcm_unit_tests\": \"81eaf8d498b2d5cf2222604ef63c87cba6b03da4\", \"gfx_unittests\": \"8709f49e49cfa0d7f091849b8ba161a62230314a\", \"gin_unittests\": \"6513a35925a005af0f91aa23dcc93cad9c44c3f6\", \"gl_unittests_ozone\": \"fac01a2d4b0b57c3bf895ae234236ab0e166a272\", \"google_apis_unittests\": \"bb0cdf4b9882fc527abc5eaddf6de799278f5168\", \"gpu_unittests\": \"9dc43e537739ec754095412e97e5f2cf952e81eb\", \"gwp_asan_unittests\": \"e8a94ef5a40dd3c759ac6545388811454e7cef06\", \"interactive_ui_tests\": \"0036ee1bc7d865b697102597e796f826e46b6c23\", \"ipc_tests\": \"b20e2e061e97b1cdce8f09cd4cb5d4bebef89070\", \"jingle_unittests\": \"dd8e500e545515960e7809c6a6e0db2703e38313\", \"keyboard_unittests\": \"1032298ea65318bc19c9f8531608a563cd89940f\", \"latency_unittests\": \"bffc4462db039d14327559776b46e3817cdf0089\", \"libjingle_xmpp_unittests\": \"1fc05fce2e990c5180f0679ba6ee3eebccf8c792\", \"liburlpattern_unittests\": \"358bb1e5b18275939432edb50ce11cad0ad58b40\", \"media_blink_unittests\": \"025c47062408e14deb54fa325f0e8d9a5fbe7d40\", \"media_unittests\": \"16ae979810bfa4a9768c0df9a0cc30630268ca2f\", \"message_center_unittests\": \"5337fb262470a40034cab4c2843851783c9c5abf\", \"midi_unittests\": \"a3c82d016dbf6c3cd295ab9ca9170f10dc288ad6\", \"mojo_core_unittests\": \"4a81f675a7f3a1a63659d59092b795ac2e63fe55\", \"mojo_unittests\": \"7046b0039a80f41ef219e65cc9cb0f2023f7ce5a\", \"nacl_helper_nonsfi_unittests\": \"1e7eba67fcbfe09ec0a881e17811fc58b0b19747\", \"nacl_loader_unittests\": \"fb34369a1d8f46c03dd0ccfe57685a71728a5a83\", \"native_theme_unittests\": \"45c0dc1bc6da17ae23a80dda66b9d984e43776b3\", \"net_unittests\": \"028421173dac454a15c9206b0543da6a5d5a312f\", \"ozone_gl_unittests\": \"eb9836d2c0b7fcf3f13914f966655074d364d2c4\", \"ozone_unittests\": \"08f8aba01ed5779fe65e3b0ab2b2e6fa67daaf87\", \"ozone_x11_unittests\": \"eddeb1fba0e381a88b71ef59f505bd5361946a85\", \"pdf_unittests\": \"8d4b661bb601896a5de9e7ec06a0ac416ddc53ef\", \"perfetto_unittests\": \"f62ba088519aff6ecacfe02237deba4c0f39d895\", \"ppapi_unittests\": \"18518c5867055f49589df75ce4882f609ce77618\", \"printing_unittests\": \"4199624771c85c63cad77617f88c94ff98c9d4e4\", \"remoting_unittests\": \"bbacb9f088732f5f951fb368e0a207127cfe0380\", \"sandbox_linux_unittests\": \"c95c343c6a88804b0704598c5e4e545c3968a25a\", \"service_manager_unittests\": \"11c00d5ec9bf3615141237bf0020b81c2f098abd\", \"services_unittests\": \"627c1e908a6218fd6c2c34f7c1d13d793a6024de\", \"shell_dialogs_unittests\": \"5d7ead790a574b06e9501fbe2abe8e14f1720bc9\", \"shell_encryption_unittests\": \"38b00278a809a9cb555d94d7ab8c1fc3b77296db\", \"skia_unittests\": \"1c970bda4bbe72e2bae337d95c77a24e1cf31bb7\", \"snapshot_unittests\": \"5a8d760f695ecaaef0342902654f9f22cff29e07\", \"sql_unittests\": \"443fd91f92e078c1ff3632b7d27c97f0560c6309\", \"storage_unittests\": \"78ae624e8cb1cc396bcdf8a2ade8d6f3613b574c\", \"sync_integration_tests\": \"190f1f905b5bcf80fbf2d49916277eab8c98ffed\", \"traffic_annotation_auditor_unittests\": \"e85fb08347aeb7dc3a4dbbb796948fa2e77e2d8d\", \"ui_base_unittests\": \"40899d845d6b9b500c3a29e83e7471a951972377\", \"ui_chromeos_unittests\": \"421e28b0b1e856eab14352aef6798190efd3883a\", \"ui_touch_selection_unittests\": \"bc3a3fc0b481852f7de23b025ed09329431e1581\", \"unit_tests\": \"8f5c9036235f83c570b9938713fa06caf6b832cf\", \"url_unittests\": \"e409c10b884e7f63dc85da7544444632cf538a93\", \"usage_time_limit_unittests\": \"c872d4fa4a5ec619054f3417424a5a3d22573e20\", \"views_unittests\": \"1b8485a411942ab5e824cced22677b8403edcbfd\", \"viz_unittests\": \"3ac425b14d63fa1e486f52650f33a9fe3459886b\", \"wayland_client_perftests\": \"b9b534073aeed16ea0edcce63309e6bc2e2df786\", \"wm_unittests\": \"123b47197dbe02131fad2d8483e38bebda79a970\", \"wtf_unittests\": \"5edf53ac7b302b21a2cab323f2fc223f2844ef7f\", \"zlib_unittests\": \"b24526bc66621bcd565d889fb7c11a0bf703f430\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0ba11647c3777752a9345c9a31488a30e4a4f8f072cd97454b4c4835355bfe20_v2\", \"builder_304607b978b8f4bcd63bac0f543c3a69f6b4c7cf202dcd2d3c5b8d4a70be201e_v2\", \"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_5f9879dd307b3e3de87d4273aaf77dfc3adf50a63c1d75c3b245bfdff463a933_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_v2\", \"builder_df0918bfe72974da8c562c0af785205b640d6f631f017829e032ab97ab701012_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-59-1i5l\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614046443416923",
"status": "COMPLETED",
"status_changed_ts": "1614052517967342",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45646",
"builder:linux-chromeos-rel",
"buildset:commit/git/87a7da1cf82aa086a7f4535b5dad9c02da18842e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87a7da1cf82aa086a7f4535b5dad9c02da18842e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019973715139789568",
"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/8854543653224247152/+/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:51e57762c2e9f110",
"user_agent:luci-scheduler"
],
"updated_ts": "1614052517972396",
"url": "https://ci.chromium.org/b/8854543653224247152",
"utcnow_ts": "1614052518519932"
}
}
[03:55:18.528] Invocation finished in 1h46m3.108966551s with status SUCCEEDED