[21:10:48.238] New invocation is queued and will start shortly
[21:10:49.471] Starting the invocation (attempt 1)
[21:10:49.504] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:10:49.680] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:10:49.680] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023978758719096864",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"28ecef438c2c6cf1efe78c23d9c09581a86cbcfc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9023978758719096864",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/28ecef438c2c6cf1efe78c23d9c09581a86cbcfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/28ecef438c2c6cf1efe78c23d9c09581a86cbcfc",
"gitiles_ref:refs/heads/master"
]
}
[21:10:50.331] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610226649766381",
"id": "8858548696453275344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"28ecef438c2c6cf1efe78c23d9c09581a86cbcfc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610226650206366",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22132",
"builder:linux-chromeos-dbg",
"buildset:commit/git/28ecef438c2c6cf1efe78c23d9c09581a86cbcfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/28ecef438c2c6cf1efe78c23d9c09581a86cbcfc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023978758719096864",
"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/8858548696453275344/+/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": "1610226650206472",
"url": "https://ci.chromium.org/b/8858548696453275344",
"utcnow_ts": "1610226650322692"
}
}
[21:10:50.331] Task URL: https://ci.chromium.org/b/8858548696453275344
[21:10:50.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:2:0) after 1m0s
[21:11:50.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:2:0)
[21:11:50.403] Timer tick, asking Buildbucket for the build status
[21:11:53.250] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:53.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:3:0) after 1m0s
[21:12:53.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:3:0)
[21:12:53.299] Timer tick, asking Buildbucket for the build status
[21:12:53.401] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:53.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:4:0) after 1m0s
[21:13:53.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:4:0)
[21:13:53.374] Timer tick, asking Buildbucket for the build status
[21:13:53.476] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:53.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:5:0) after 1m0s
[21:14:53.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:5:0)
[21:14:53.486] Timer tick, asking Buildbucket for the build status
[21:14:53.593] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:53.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:6:0) after 1m0s
[21:15:53.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:6:0)
[21:15:53.615] Timer tick, asking Buildbucket for the build status
[21:15:53.705] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:53.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:7:0) after 1m0s
[21:16:53.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:7:0)
[21:16:53.729] Timer tick, asking Buildbucket for the build status
[21:16:53.838] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:53.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:8:0) after 1m0s
[21:17:53.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:8:0)
[21:17:53.859] Timer tick, asking Buildbucket for the build status
[21:17:54.023] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:54.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:9:0) after 1m0s
[21:18:54.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:9:0)
[21:18:54.026] Timer tick, asking Buildbucket for the build status
[21:18:54.298] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:54.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:10:0) after 1m0s
[21:19:54.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:10:0)
[21:19:54.305] Timer tick, asking Buildbucket for the build status
[21:19:54.374] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:54.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:11:0) after 1m0s
[21:20:54.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:11:0)
[21:20:54.442] Timer tick, asking Buildbucket for the build status
[21:20:54.562] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:54.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:12:0) after 1m0s
[21:21:54.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:12:0)
[21:21:54.615] Timer tick, asking Buildbucket for the build status
[21:21:54.682] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:54.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:13:0) after 1m0s
[21:22:54.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:13:0)
[21:22:54.669] Timer tick, asking Buildbucket for the build status
[21:22:54.753] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:54.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:14:0) after 1m0s
[21:23:54.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:14:0)
[21:23:54.816] Timer tick, asking Buildbucket for the build status
[21:23:54.874] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:54.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:15:0) after 1m0s
[21:24:54.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:15:0)
[21:24:54.930] Timer tick, asking Buildbucket for the build status
[21:24:55.001] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:55.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:16:0) after 1m0s
[21:25:55.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:16:0)
[21:25:55.043] Timer tick, asking Buildbucket for the build status
[21:25:55.123] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:55.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:17:0) after 1m0s
[21:26:55.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:17:0)
[21:26:55.274] Timer tick, asking Buildbucket for the build status
[21:26:55.510] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:55.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:18:0) after 1m0s
[21:27:55.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:18:0)
[21:27:55.495] Timer tick, asking Buildbucket for the build status
[21:27:55.558] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:55.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:19:0) after 1m0s
[21:28:55.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:19:0)
[21:28:55.720] Timer tick, asking Buildbucket for the build status
[21:28:55.784] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:55.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:20:0) after 1m0s
[21:29:55.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:20:0)
[21:29:55.847] Timer tick, asking Buildbucket for the build status
[21:29:55.913] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:55.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:21:0) after 1m0s
[21:30:55.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:21:0)
[21:30:55.962] Timer tick, asking Buildbucket for the build status
[21:30:56.046] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:56.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:22:0) after 1m0s
[21:31:56.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:22:0)
[21:31:56.142] Timer tick, asking Buildbucket for the build status
[21:31:56.210] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:56.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:23:0) after 1m0s
[21:32:56.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:23:0)
[21:32:56.400] Timer tick, asking Buildbucket for the build status
[21:32:56.491] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:56.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:24:0) after 1m0s
[21:33:56.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:24:0)
[21:33:56.524] Timer tick, asking Buildbucket for the build status
[21:33:56.935] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:56.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:25:0) after 1m0s
[21:34:57.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:25:0)
[21:34:57.071] Timer tick, asking Buildbucket for the build status
[21:34:57.307] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:57.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:26:0) after 1m0s
[21:35:57.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:26:0)
[21:35:57.375] Timer tick, asking Buildbucket for the build status
[21:35:57.481] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:57.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:27:0) after 1m0s
[21:36:57.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:27:0)
[21:36:57.513] Timer tick, asking Buildbucket for the build status
[21:36:57.580] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:57.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:28:0) after 1m0s
[21:37:57.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:28:0)
[21:37:57.616] Timer tick, asking Buildbucket for the build status
[21:37:57.814] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:57.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:29:0) after 1m0s
[21:38:58.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:29:0)
[21:38:58.413] Timer tick, asking Buildbucket for the build status
[21:38:58.692] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:58.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:30:0) after 1m0s
[21:39:58.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:30:0)
[21:39:58.762] Timer tick, asking Buildbucket for the build status
[21:39:58.824] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:58.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:31:0) after 1m0s
[21:40:58.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:31:0)
[21:40:58.845] Timer tick, asking Buildbucket for the build status
[21:40:58.981] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:58.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:32:0) after 1m0s
[21:41:59.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:32:0)
[21:41:59.073] Timer tick, asking Buildbucket for the build status
[21:41:59.152] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:59.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:33:0) after 1m0s
[21:42:59.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:33:0)
[21:42:59.266] Timer tick, asking Buildbucket for the build status
[21:42:59.416] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:59.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:34:0) after 1m0s
[21:43:59.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:34:0)
[21:43:59.442] Timer tick, asking Buildbucket for the build status
[21:43:59.595] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:59.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:35:0) after 1m0s
[21:44:59.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:35:0)
[21:44:59.660] Timer tick, asking Buildbucket for the build status
[21:44:59.840] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:59.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:36:0) after 1m0s
[21:45:59.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:36:0)
[21:45:59.859] Timer tick, asking Buildbucket for the build status
[21:45:59.942] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:59.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:37:0) after 1m0s
[21:46:59.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:37:0)
[21:46:59.994] Timer tick, asking Buildbucket for the build status
[21:47:00.198] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:00.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:38:0) after 1m0s
[21:48:00.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:38:0)
[21:48:00.220] Timer tick, asking Buildbucket for the build status
[21:48:00.279] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:00.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:39:0) after 1m0s
[21:49:00.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:39:0)
[21:49:00.302] Timer tick, asking Buildbucket for the build status
[21:49:00.368] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:00.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:40:0) after 1m0s
[21:50:00.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:40:0)
[21:50:00.398] Timer tick, asking Buildbucket for the build status
[21:50:00.464] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:00.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:41:0) after 1m0s
[21:51:01.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:41:0)
[21:51:01.195] Timer tick, asking Buildbucket for the build status
[21:51:01.617] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:01.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:42:0) after 1m0s
[21:52:01.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:42:0)
[21:52:01.724] Timer tick, asking Buildbucket for the build status
[21:52:01.858] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:01.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:43:0) after 1m0s
[21:53:02.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:43:0)
[21:53:02.163] Timer tick, asking Buildbucket for the build status
[21:53:02.443] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:02.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:44:0) after 1m0s
[21:54:02.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:44:0)
[21:54:02.716] Timer tick, asking Buildbucket for the build status
[21:54:02.949] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:02.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:45:0) after 1m0s
[21:55:03.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:45:0)
[21:55:03.146] Timer tick, asking Buildbucket for the build status
[21:55:03.325] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:03.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:46:0) after 1m0s
[21:56:03.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:46:0)
[21:56:03.410] Timer tick, asking Buildbucket for the build status
[21:56:03.561] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:03.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:47:0) after 1m0s
[21:57:03.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:47:0)
[21:57:03.531] Timer tick, asking Buildbucket for the build status
[21:57:03.606] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:03.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:48:0) after 1m0s
[21:58:03.629] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:48:0)
[21:58:03.629] Timer tick, asking Buildbucket for the build status
[21:58:03.743] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:03.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:49:0) after 1m0s
[21:59:03.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:49:0)
[21:59:03.856] Timer tick, asking Buildbucket for the build status
[21:59:03.933] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:03.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:50:0) after 1m0s
[22:00:04.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:50:0)
[22:00:04.085] Timer tick, asking Buildbucket for the build status
[22:00:04.451] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:04.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:51:0) after 1m0s
[22:01:04.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:51:0)
[22:01:04.602] Timer tick, asking Buildbucket for the build status
[22:01:04.757] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:04.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:52:0) after 1m0s
[22:02:04.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:52:0)
[22:02:04.802] Timer tick, asking Buildbucket for the build status
[22:02:04.894] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:04.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:53:0) after 1m0s
[22:03:05.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:53:0)
[22:03:05.034] Timer tick, asking Buildbucket for the build status
[22:03:05.117] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:05.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:54:0) after 1m0s
[22:04:05.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:54:0)
[22:04:05.144] Timer tick, asking Buildbucket for the build status
[22:04:05.221] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:05.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:55:0) after 1m0s
[22:05:05.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:55:0)
[22:05:05.247] Timer tick, asking Buildbucket for the build status
[22:05:05.312] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:05.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:56:0) after 1m0s
[22:06:05.356] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:56:0)
[22:06:05.356] Timer tick, asking Buildbucket for the build status
[22:06:05.462] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:05.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:57:0) after 1m0s
[22:07:05.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:57:0)
[22:07:05.534] Timer tick, asking Buildbucket for the build status
[22:07:05.606] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:05.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:58:0) after 1m0s
[22:08:05.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:58:0)
[22:08:05.655] Timer tick, asking Buildbucket for the build status
[22:08:05.764] Build 8858548696453275344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:05.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:59:0) after 1m0s
[22:09:05.451] Received PubSub notification, asking Buildbucket for the build status
[22:09:05.536] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:05.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:59:0)
[22:09:05.834] Timer tick, asking Buildbucket for the build status
[22:09:05.946] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:05.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:61:0) after 1m0s
[22:10:05.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:61:0)
[22:10:05.969] Timer tick, asking Buildbucket for the build status
[22:10:06.116] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:06.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:62:0) after 1m0s
[22:11:06.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:62:0)
[22:11:06.141] Timer tick, asking Buildbucket for the build status
[22:11:06.245] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:06.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:63:0) after 1m0s
[22:12:06.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:63:0)
[22:12:06.329] Timer tick, asking Buildbucket for the build status
[22:12:06.765] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:06.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:64:0) after 1m0s
[22:13:06.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:64:0)
[22:13:06.788] Timer tick, asking Buildbucket for the build status
[22:13:06.891] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:06.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:65:0) after 1m0s
[22:14:06.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:65:0)
[22:14:06.910] Timer tick, asking Buildbucket for the build status
[22:14:06.966] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:06.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:66:0) after 1m0s
[22:15:07.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:66:0)
[22:15:07.017] Timer tick, asking Buildbucket for the build status
[22:15:07.118] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:07.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:67:0) after 1m0s
[22:16:07.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:67:0)
[22:16:07.180] Timer tick, asking Buildbucket for the build status
[22:16:07.399] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:07.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:68:0) after 1m0s
[22:17:07.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:68:0)
[22:17:07.484] Timer tick, asking Buildbucket for the build status
[22:17:07.544] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:07.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:69:0) after 1m0s
[22:18:07.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:69:0)
[22:18:07.707] Timer tick, asking Buildbucket for the build status
[22:18:07.767] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:07.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:70:0) after 1m0s
[22:19:07.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:70:0)
[22:19:07.839] Timer tick, asking Buildbucket for the build status
[22:19:07.908] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:07.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:71:0) after 1m0s
[22:20:07.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:71:0)
[22:20:07.971] Timer tick, asking Buildbucket for the build status
[22:20:08.032] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:08.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:72:0) after 1m0s
[22:21:08.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:72:0)
[22:21:08.052] Timer tick, asking Buildbucket for the build status
[22:21:08.299] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:08.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:73:0) after 1m0s
[22:22:08.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:73:0)
[22:22:08.410] Timer tick, asking Buildbucket for the build status
[22:22:08.521] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:08.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:74:0) after 1m0s
[22:23:08.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:74:0)
[22:23:08.676] Timer tick, asking Buildbucket for the build status
[22:23:08.855] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:08.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:75:0) after 1m0s
[22:24:09.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:75:0)
[22:24:09.024] Timer tick, asking Buildbucket for the build status
[22:24:09.147] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:09.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:76:0) after 1m0s
[22:25:09.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:76:0)
[22:25:09.222] Timer tick, asking Buildbucket for the build status
[22:25:09.513] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:09.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:77:0) after 1m0s
[22:26:09.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:77:0)
[22:26:09.735] Timer tick, asking Buildbucket for the build status
[22:26:10.211] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:10.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:78:0) after 1m0s
[22:27:10.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:78:0)
[22:27:10.287] Timer tick, asking Buildbucket for the build status
[22:27:10.690] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:10.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:79:0) after 1m0s
[22:28:10.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:79:0)
[22:28:10.878] Timer tick, asking Buildbucket for the build status
[22:28:10.956] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:80:0) after 1m0s
[22:29:11.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:80:0)
[22:29:11.022] Timer tick, asking Buildbucket for the build status
[22:29:11.096] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:11.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:81:0) after 1m0s
[22:30:11.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:81:0)
[22:30:11.138] Timer tick, asking Buildbucket for the build status
[22:30:11.273] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:11.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:82:0) after 1m0s
[22:31:11.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:82:0)
[22:31:11.337] Timer tick, asking Buildbucket for the build status
[22:31:11.845] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:11.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:83:0) after 1m0s
[22:32:11.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:83:0)
[22:32:11.931] Timer tick, asking Buildbucket for the build status
[22:32:12.046] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:12.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:84:0) after 1m0s
[22:33:12.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:84:0)
[22:33:12.071] Timer tick, asking Buildbucket for the build status
[22:33:12.153] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:12.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:85:0) after 1m0s
[22:34:12.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:85:0)
[22:34:12.223] Timer tick, asking Buildbucket for the build status
[22:34:12.356] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:12.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:86:0) after 1m0s
[22:35:12.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:86:0)
[22:35:12.383] Timer tick, asking Buildbucket for the build status
[22:35:12.714] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:12.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:87:0) after 1m0s
[22:36:12.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:87:0)
[22:36:12.872] Timer tick, asking Buildbucket for the build status
[22:36:12.980] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:12.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:88:0) after 1m0s
[22:37:13.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:88:0)
[22:37:13.066] Timer tick, asking Buildbucket for the build status
[22:37:13.371] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:13.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:89:0) after 1m0s
[22:38:13.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:89:0)
[22:38:13.423] Timer tick, asking Buildbucket for the build status
[22:38:13.487] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:13.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:90:0) after 1m0s
[22:39:13.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:90:0)
[22:39:13.797] Timer tick, asking Buildbucket for the build status
[22:39:13.858] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:13.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:91:0) after 1m0s
[22:40:13.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:91:0)
[22:40:13.879] Timer tick, asking Buildbucket for the build status
[22:40:14.028] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:14.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:92:0) after 1m0s
[22:41:14.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:92:0)
[22:41:14.048] Timer tick, asking Buildbucket for the build status
[22:41:14.203] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:14.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:93:0) after 1m0s
[22:42:14.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:93:0)
[22:42:14.266] Timer tick, asking Buildbucket for the build status
[22:42:14.625] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:14.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:94:0) after 1m0s
[22:43:14.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:94:0)
[22:43:14.758] Timer tick, asking Buildbucket for the build status
[22:43:14.812] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:14.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:95:0) after 1m0s
[22:44:14.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:95:0)
[22:44:14.886] Timer tick, asking Buildbucket for the build status
[22:44:14.970] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:14.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:96:0) after 1m0s
[22:45:15.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:96:0)
[22:45:15.029] Timer tick, asking Buildbucket for the build status
[22:45:15.220] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:15.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:97:0) after 1m0s
[22:46:15.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:97:0)
[22:46:15.296] Timer tick, asking Buildbucket for the build status
[22:46:15.571] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:15.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:98:0) after 1m0s
[22:47:15.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:98:0)
[22:47:15.615] Timer tick, asking Buildbucket for the build status
[22:47:15.667] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:15.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:99:0) after 1m0s
[22:48:15.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:99:0)
[22:48:15.654] Timer tick, asking Buildbucket for the build status
[22:48:15.775] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:15.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:100:0) after 1m0s
[22:49:15.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:100:0)
[22:49:15.792] Timer tick, asking Buildbucket for the build status
[22:49:15.859] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:15.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:101:0) after 1m0s
[22:50:15.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:101:0)
[22:50:15.913] Timer tick, asking Buildbucket for the build status
[22:50:16.184] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:16.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:102:0) after 1m0s
[22:51:16.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:102:0)
[22:51:16.206] Timer tick, asking Buildbucket for the build status
[22:51:16.513] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:16.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:103:0) after 1m0s
[22:52:16.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:103:0)
[22:52:16.546] Timer tick, asking Buildbucket for the build status
[22:52:16.609] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:16.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:104:0) after 1m0s
[22:53:16.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:104:0)
[22:53:16.596] Timer tick, asking Buildbucket for the build status
[22:53:16.644] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:16.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:105:0) after 1m0s
[22:54:16.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:105:0)
[22:54:16.701] Timer tick, asking Buildbucket for the build status
[22:54:16.787] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:16.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:106:0) after 1m0s
[22:55:16.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:106:0)
[22:55:16.837] Timer tick, asking Buildbucket for the build status
[22:55:16.990] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:16.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:107:0) after 1m0s
[22:56:17.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:107:0)
[22:56:17.013] Timer tick, asking Buildbucket for the build status
[22:56:17.311] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:17.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:108:0) after 1m0s
[22:57:17.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:108:0)
[22:57:17.387] Timer tick, asking Buildbucket for the build status
[22:57:17.490] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:17.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:109:0) after 1m0s
[22:58:17.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:109:0)
[22:58:17.552] Timer tick, asking Buildbucket for the build status
[22:58:17.704] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:17.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:110:0) after 1m0s
[22:59:17.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:110:0)
[22:59:17.725] Timer tick, asking Buildbucket for the build status
[22:59:17.819] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:17.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:111:0) after 1m0s
[23:00:17.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:111:0)
[23:00:17.841] Timer tick, asking Buildbucket for the build status
[23:00:17.935] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:17.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:112:0) after 1m0s
[23:01:17.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:112:0)
[23:01:17.986] Timer tick, asking Buildbucket for the build status
[23:01:18.089] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:18.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:113:0) after 1m0s
[23:02:18.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:113:0)
[23:02:18.177] Timer tick, asking Buildbucket for the build status
[23:02:18.240] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:18.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:114:0) after 1m0s
[23:03:18.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:114:0)
[23:03:18.294] Timer tick, asking Buildbucket for the build status
[23:03:18.371] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:18.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:115:0) after 1m0s
[23:04:18.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:115:0)
[23:04:18.424] Timer tick, asking Buildbucket for the build status
[23:04:18.543] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:18.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:116:0) after 1m0s
[23:05:18.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:116:0)
[23:05:18.681] Timer tick, asking Buildbucket for the build status
[23:05:18.988] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:18.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:117:0) after 1m0s
[23:06:19.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:117:0)
[23:06:19.084] Timer tick, asking Buildbucket for the build status
[23:06:19.321] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:19.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:118:0) after 1m0s
[23:07:19.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:118:0)
[23:07:19.347] Timer tick, asking Buildbucket for the build status
[23:07:19.438] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:19.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:119:0) after 1m0s
[23:08:19.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:119:0)
[23:08:19.498] Timer tick, asking Buildbucket for the build status
[23:08:19.608] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:19.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:120:0) after 1m0s
[23:09:19.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:120:0)
[23:09:19.616] Timer tick, asking Buildbucket for the build status
[23:09:19.728] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:19.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:121:0) after 1m0s
[23:10:19.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:121:0)
[23:10:19.802] Timer tick, asking Buildbucket for the build status
[23:10:19.904] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:19.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:122:0) after 1m0s
[23:11:19.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:122:0)
[23:11:19.928] Timer tick, asking Buildbucket for the build status
[23:11:20.031] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:20.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:123:0) after 1m0s
[23:12:20.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:123:0)
[23:12:20.113] Timer tick, asking Buildbucket for the build status
[23:12:20.214] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:20.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:124:0) after 1m0s
[23:13:20.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:124:0)
[23:13:20.330] Timer tick, asking Buildbucket for the build status
[23:13:20.691] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:20.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:125:0) after 1m0s
[23:14:20.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:125:0)
[23:14:20.997] Timer tick, asking Buildbucket for the build status
[23:14:21.077] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:21.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:126:0) after 1m0s
[23:15:21.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:126:0)
[23:15:21.157] Timer tick, asking Buildbucket for the build status
[23:15:21.232] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:21.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:127:0) after 1m0s
[23:16:21.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:127:0)
[23:16:21.282] Timer tick, asking Buildbucket for the build status
[23:16:21.496] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:21.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:128:0) after 1m0s
[23:17:21.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:128:0)
[23:17:21.515] Timer tick, asking Buildbucket for the build status
[23:17:21.634] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:21.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:129:0) after 1m0s
[23:18:21.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:129:0)
[23:18:21.655] Timer tick, asking Buildbucket for the build status
[23:18:21.742] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:21.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:130:0) after 1m0s
[23:19:21.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:130:0)
[23:19:21.773] Timer tick, asking Buildbucket for the build status
[23:19:21.983] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:21.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:131:0) after 1m0s
[23:20:22.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:131:0)
[23:20:22.016] Timer tick, asking Buildbucket for the build status
[23:20:22.337] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:22.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:132:0) after 1m0s
[23:21:22.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:132:0)
[23:21:22.336] Timer tick, asking Buildbucket for the build status
[23:21:22.510] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:22.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:133:0) after 1m0s
[23:22:22.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:133:0)
[23:22:22.529] Timer tick, asking Buildbucket for the build status
[23:22:22.595] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:22.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:134:0) after 1m0s
[23:23:22.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:134:0)
[23:23:22.628] Timer tick, asking Buildbucket for the build status
[23:23:22.740] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:22.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:135:0) after 1m0s
[23:24:22.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:135:0)
[23:24:22.764] Timer tick, asking Buildbucket for the build status
[23:24:23.162] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:23.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:136:0) after 1m0s
[23:25:23.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:136:0)
[23:25:23.189] Timer tick, asking Buildbucket for the build status
[23:25:23.296] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:23.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:137:0) after 1m0s
[23:26:23.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:137:0)
[23:26:23.320] Timer tick, asking Buildbucket for the build status
[23:26:23.420] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:23.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:138:0) after 1m0s
[23:27:23.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:138:0)
[23:27:23.462] Timer tick, asking Buildbucket for the build status
[23:27:23.790] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:23.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:139:0) after 1m0s
[23:28:23.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:139:0)
[23:28:23.796] Timer tick, asking Buildbucket for the build status
[23:28:23.853] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:23.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:140:0) after 1m0s
[23:29:23.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:140:0)
[23:29:23.954] Timer tick, asking Buildbucket for the build status
[23:29:24.016] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:24.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:141:0) after 1m0s
[23:30:24.041] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:141:0)
[23:30:24.041] Timer tick, asking Buildbucket for the build status
[23:30:24.110] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:24.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:142:0) after 1m0s
[23:31:24.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:142:0)
[23:31:24.088] Timer tick, asking Buildbucket for the build status
[23:31:24.625] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:24.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:143:0) after 1m0s
[23:32:24.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:143:0)
[23:32:24.665] Timer tick, asking Buildbucket for the build status
[23:32:24.794] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:24.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:144:0) after 1m0s
[23:33:24.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:144:0)
[23:33:24.821] Timer tick, asking Buildbucket for the build status
[23:33:24.911] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:24.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:145:0) after 1m0s
[23:34:24.992] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:145:0)
[23:34:24.992] Timer tick, asking Buildbucket for the build status
[23:34:25.047] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:25.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:146:0) after 1m0s
[23:35:25.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:146:0)
[23:35:25.112] Timer tick, asking Buildbucket for the build status
[23:35:25.188] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:25.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:147:0) after 1m0s
[23:36:25.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:147:0)
[23:36:25.241] Timer tick, asking Buildbucket for the build status
[23:36:25.428] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:25.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:148:0) after 1m0s
[23:37:25.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:148:0)
[23:37:25.512] Timer tick, asking Buildbucket for the build status
[23:37:25.626] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:25.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:149:0) after 1m0s
[23:38:25.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:149:0)
[23:38:25.725] Timer tick, asking Buildbucket for the build status
[23:38:25.793] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:25.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:150:0) after 1m0s
[23:39:25.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:150:0)
[23:39:25.879] Timer tick, asking Buildbucket for the build status
[23:39:25.946] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:25.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:151:0) after 1m0s
[23:40:25.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:151:0)
[23:40:25.960] Timer tick, asking Buildbucket for the build status
[23:40:26.121] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:26.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:152:0) after 1m0s
[23:41:26.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:152:0)
[23:41:26.144] Timer tick, asking Buildbucket for the build status
[23:41:26.249] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:26.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:153:0) after 1m0s
[23:42:26.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:153:0)
[23:42:26.304] Timer tick, asking Buildbucket for the build status
[23:42:26.400] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:26.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:154:0) after 1m0s
[23:43:26.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:154:0)
[23:43:26.476] Timer tick, asking Buildbucket for the build status
[23:43:26.586] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:26.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:155:0) after 1m0s
[23:44:26.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:155:0)
[23:44:26.646] Timer tick, asking Buildbucket for the build status
[23:44:26.709] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:26.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:156:0) after 1m0s
[23:45:26.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:156:0)
[23:45:26.734] Timer tick, asking Buildbucket for the build status
[23:45:26.801] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:26.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:157:0) after 1m0s
[23:46:26.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:157:0)
[23:46:26.864] Timer tick, asking Buildbucket for the build status
[23:46:26.977] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:26.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:158:0) after 1m0s
[23:47:27.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:158:0)
[23:47:27.000] Timer tick, asking Buildbucket for the build status
[23:47:27.089] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:27.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:159:0) after 1m0s
[23:48:27.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:159:0)
[23:48:27.119] Timer tick, asking Buildbucket for the build status
[23:48:27.182] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:27.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:160:0) after 1m0s
[23:49:27.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:160:0)
[23:49:27.205] Timer tick, asking Buildbucket for the build status
[23:49:27.276] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:27.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:161:0) after 1m0s
[23:50:27.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:161:0)
[23:50:27.328] Timer tick, asking Buildbucket for the build status
[23:50:27.428] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:27.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:162:0) after 1m0s
[23:51:27.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:162:0)
[23:51:27.454] Timer tick, asking Buildbucket for the build status
[23:51:27.600] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:27.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:163:0) after 1m0s
[23:52:27.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:163:0)
[23:52:27.647] Timer tick, asking Buildbucket for the build status
[23:52:27.784] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:27.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:164:0) after 1m0s
[23:53:27.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:164:0)
[23:53:27.892] Timer tick, asking Buildbucket for the build status
[23:53:28.004] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:28.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:165:0) after 1m0s
[23:54:28.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:165:0)
[23:54:28.053] Timer tick, asking Buildbucket for the build status
[23:54:28.265] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:28.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:166:0) after 1m0s
[23:55:28.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:166:0)
[23:55:28.309] Timer tick, asking Buildbucket for the build status
[23:55:28.365] Build 8858548696453275344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:28.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023978758719096864:167:0) after 1m0s
[23:55:59.957] Received PubSub notification, asking Buildbucket for the build status
[23:56:00.179] Build 8858548696453275344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:56:00.179] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610236558447754",
"created_by": "project:chromium",
"created_ts": "1610226649766381",
"id": "8858548696453275344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"28ecef438c2c6cf1efe78c23d9c09581a86cbcfc\"}}",
"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\": \"28ecef438c2c6cf1efe78c23d9c09581a86cbcfc\", \"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-25-91ui\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22132, \"got_angle_revision\": \"51603c63d1cb9e36afaf2069fc069e542ac2f3fb\", \"got_dawn_revision\": \"3a0405597374c9ae12fb93e33aac35826455a958\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"28ecef438c2c6cf1efe78c23d9c09581a86cbcfc\", \"got_revision_cp\": \"refs/heads/master@{#841845}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"588d32cb3f172d1a6092c2b3c7e5a5c5d8b3b06e\", \"got_v8_revision\": \"5d60a181de0863d8d00ae42ec0ee3521f51b501a\", \"got_v8_revision_cp\": \"refs/heads/8.9.229@{#1}\", \"got_webrtc_revision\": \"42082f9045e74e65b9e3a66b51093e0dc2f9375a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32929}\", \"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\": \"28ecef438c2c6cf1efe78c23d9c09581a86cbcfc\", \"swarm_hashes_refs/heads/master(at){#841845}_without_patch\": {\"absl_hardening_tests\": \"c6411cc03afe462cf9803ed5b6d708383a3d7fc9\", \"accessibility_unittests\": \"776bce183ca30fb32abe743a573b7d199831dec5\", \"angle_unittests\": \"acc1be109962915a845992783447bbbaf7c810ff\", \"app_list_unittests\": \"2dbf2947931e5abfb0e9a9ebdfae39307e06072a\", \"app_shell_unittests\": \"2540e3ed8926479d378cc714a0c130b073836070\", \"ash_unittests\": \"7098c146aa63e60d2fc02a1f5c33336be73ec8c5\", \"aura_unittests\": \"4f8a16baa6ffcb57850de96e4bfb0acee661ca62\", \"base_unittests\": \"775e0005edd796ebe8e35ae7da43766a1cee45e7\", \"base_util_unittests\": \"2a1a389aec708f3db20380d541bee09456bae1c7\", \"blink_common_unittests\": \"c54c7ecf5af0ae8e2871ec117662800f0ffee1eb\", \"blink_fuzzer_unittests\": \"4fe38f5712d1eeb670af18ef5fb5320ed59249cc\", \"blink_heap_unittests\": \"f692ecb17240cf0015f6948596c8f906b40d9ca5\", \"blink_platform_unittests\": \"b6b563805f9aade58ce5a93500f195917ff30a6a\", \"blink_unittests\": \"e77333b49fa90b88dfd45ffa4ae8c40bb492194b\", \"boringssl_crypto_tests\": \"ec0964ee01061653efcfb32a145e4af460add661\", \"boringssl_ssl_tests\": \"e1cd0db01939a71f09bc99c64d2baba84007b0ce\", \"browser_tests\": \"d0cb08b81f7c8e5ee4508c73078763afd3908550\", \"capture_unittests\": \"ac1dbc3fa0fd761886bf56dcec2f031a9019b805\", \"cast_unittests\": \"e8dd65afea43e9790a245d7c4c8f84c6e4b8b801\", \"cc_unittests\": \"ea3c6305b601247d87919b3ddfa6241d41fe16a0\", \"chrome_app_unittests\": \"17bcaa4553169e2f7a0eea61ee98005dc8f50117\", \"chromedriver_unittests\": \"583c25c7ca0cbdc446abbee570d4c0bd879ab602\", \"chromeos_components_unittests\": \"d6fc236d2c9d15c9f4c79e5a04b002e7e4974bab\", \"chromeos_unittests\": \"c37cfc21d9503aba1db4ea71fd530c3edb747581\", \"components_browsertests\": \"70bcaba17629fcdc4767aaf83d3eb8934c6d4917\", \"components_unittests\": \"b9f63f453d6a81e816d162ac572347a2df2f2edc\", \"compositor_unittests\": \"70893b2d3deb27243025c5230a72ceab9197cc1c\", \"content_browsertests\": \"1e44f4e3479df3597536036dc72d3dc3be46dcf1\", \"content_unittests\": \"d8383f42ccca16ec2a15f2c95e9770fe8bff6fe7\", \"crashpad_tests\": \"eb9f3cc3d640807714ccb1c0e139e99fc26c5670\", \"crypto_unittests\": \"a7bfb1c5daf5f46174049690ce71a248216eb03a\", \"dbus_unittests\": \"95fa8e4643078d789f2163a1fa0ca4d1e18d0985\", \"device_unittests\": \"4aab2e01ca86aa5c41d427b6bf4b0cc39d58bb13\", \"display_unittests\": \"619a6007f3c11c322594efba0849b0893bb43c30\", \"events_unittests\": \"a6c3126a1482e11659f16b38802f5cdbb3dffca9\", \"exo_unittests\": \"62cacf70d7e7710c54093d29928aab11168588a5\", \"extensions_browsertests\": \"814572e5f87615c4787bc2cff4c616e7c0cab025\", \"extensions_unittests\": \"fe12cb1c463d4524e397799973cd0ed57f1a9b41\", \"filesystem_service_unittests\": \"4115332df04667427a6323ab629e279bbfa092ae\", \"gcm_unit_tests\": \"9e6d020693ef4284911c11b6a9e1240c3c10e836\", \"gfx_unittests\": \"7e0dc93c6e184206df51aaefe8603811348074ee\", \"gin_unittests\": \"84d6ba3a2761760c197298ecc59f6114a90de229\", \"gl_unittests_ozone\": \"901f6bc5e64f773c971599ae7673fa710c49b53b\", \"google_apis_unittests\": \"2a35422f66fbc7372c14091ab64dfee2dca1eaca\", \"gpu_unittests\": \"ee96f1b84e2bb8bae1715cdc5315e5aa9a53da99\", \"gwp_asan_unittests\": \"b08b594580bd17aee44a54328faecbde87d24ac8\", \"interactive_ui_tests\": \"587458decccc2272be62296f3bd34b442b44f617\", \"ipc_tests\": \"b71ea8c2cf8c4d05ac13c226f2e6a4207acc4d4c\", \"jingle_unittests\": \"7706e031667e8829640a73a4e87e2d2f81c2cb65\", \"keyboard_unittests\": \"d64a680b41272427b2ffc5e3c558e85dedb05f39\", \"latency_unittests\": \"0820093189f902f80ef9e93ca288e35da493953d\", \"libjingle_xmpp_unittests\": \"67e57edbbee16221cd88104a1677f3a505826e4d\", \"liburlpattern_unittests\": \"21d7452a8e6a4f50711b8296ca3a6463b73b4281\", \"media_blink_unittests\": \"91ea73350ed231b285912f7bd244b87d4e4e5e19\", \"media_unittests\": \"8f39923e60e5abb92f592f3cc6139708399b1023\", \"message_center_unittests\": \"4b485b2ece1567bd637b396c6f83927c218be4c9\", \"midi_unittests\": \"16998c522bd56abafe2320aac6b043f9ca20cc6c\", \"mojo_core_unittests\": \"2648ad5bfe3caed0841fa490e5bc827d50fcfb4f\", \"mojo_unittests\": \"bffe531682023f4209263f40b85313e2c62f8d25\", \"nacl_helper_nonsfi_unittests\": \"f43c38c00ffcf98ba30635020f2c472cf832fd03\", \"nacl_loader_unittests\": \"06d8d41a8ffc0a2d61e1e2a77531570f2c99d132\", \"native_theme_unittests\": \"6277770c91d5686d2bd933acacbe44d9626746db\", \"net_unittests\": \"1705c49fab6f1d6ea89779d9fc45560d230b121e\", \"ozone_gl_unittests\": \"b4e19fbe235920704a11d2bd1cf403a029970391\", \"ozone_unittests\": \"5a265cf4db9dbdf3eb91ad9f863f05a4f146b774\", \"ozone_x11_unittests\": \"c4cdf5822112430ccbde91a723b290fd53a087d4\", \"pdf_unittests\": \"10b016faff3d44d489b59945d68cd29e6f0cdaa6\", \"perfetto_unittests\": \"9054041a44ac3915bcccb1b304aa790f23963f38\", \"ppapi_unittests\": \"8cdc36b8610de1463fac98a77e1353af68020792\", \"printing_unittests\": \"25063c2f2030e1fb8d871c36e35f497890c6f07f\", \"remoting_unittests\": \"03502309b8d55692c71790771d561c2b89c9da49\", \"sandbox_linux_unittests\": \"c822f47da4a486e96078b440f85a010b36019a35\", \"service_manager_unittests\": \"3d970be6e72ae47981f61201e1c83ac2ae4274b6\", \"services_unittests\": \"6ab06c2e1de920e8f4250fef9855ace2dcf7c042\", \"shell_dialogs_unittests\": \"46c1dbb52d89ec81f76c6d5f2fe61704213d776e\", \"shell_encryption_unittests\": \"cec6fbaf56e6369f8b10f8e651650bea71202dc4\", \"skia_unittests\": \"aefff4d786d0ccfd9c4888a6096ac7e4ce733fd4\", \"snapshot_unittests\": \"a86c9995ee3c5cfab10415503ecab16cabb1a1e6\", \"sql_unittests\": \"8b88791518771050487657d2438ca7032746b744\", \"storage_unittests\": \"c98874fb6f64efffb4bddd48ca67169389ef7121\", \"sync_integration_tests\": \"8f7cc2c4f3c11dc414475dabae22cbcd5d527b97\", \"traffic_annotation_auditor_unittests\": \"dc1c0070dc2471527493ec09b31ec10391ab5b38\", \"ui_base_unittests\": \"316fa228f3aee75f1460a8773e9f2b17a26c9584\", \"ui_chromeos_unittests\": \"9f2b526e704a4271af7691126d0189dedc4b5d27\", \"ui_touch_selection_unittests\": \"a1143304de9171b06d4c1b3584ad7f1133b18a0e\", \"unit_tests\": \"afdefc0a519f5b9b12aa1cda4e09da7464e5ca65\", \"url_unittests\": \"94ba90b4e011559769b0c60bdd4379ecbca94aa0\", \"usage_time_limit_unittests\": \"f5bad110dc53bfc4e4b50e4dc1bedc286b78a031\", \"views_unittests\": \"354664e3bca4d78b0901fb49a544091ede27e71e\", \"viz_unittests\": \"06e70782c861f9d05ceae6a5799bdfd189aa3dba\", \"wm_unittests\": \"e49dbf473f2050766c3f2e4b05323398d70749d9\", \"wtf_unittests\": \"0f51111088b36d709a8fb94653f4e57725b6b887\", \"zlib_unittests\": \"32b147e9f1a5403fe22ab5707f3d626b88bbb99e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1b8c0e295e0040ae60b46798151163eecc632ef935bd1ea9777aec71f94caa4f_v2\", \"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_cb8f28a5d59bb80cbafed89b56af03ba2ea85f5e24941631d330b6bd1d886cb5_v2\", \"builder_f0afc432815c064941e1e5f998abb72673d4068febb53723f4aedad56e1b9c62_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-25-91ui\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610230143641893",
"status": "COMPLETED",
"status_changed_ts": "1610236558741521",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22132",
"builder:linux-chromeos-dbg",
"buildset:commit/git/28ecef438c2c6cf1efe78c23d9c09581a86cbcfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/28ecef438c2c6cf1efe78c23d9c09581a86cbcfc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023978758719096864",
"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/8858548696453275344/+/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:5101ce5559e09510",
"user_agent:luci-scheduler"
],
"updated_ts": "1610236558748212",
"url": "https://ci.chromium.org/b/8858548696453275344",
"utcnow_ts": "1610236560162098"
}
}
[23:56:00.179] Invocation finished in 2h45m11.957491286s with status SUCCEEDED