[21:39:19.344] New invocation is queued and will start shortly
[21:39:20.432] Starting the invocation (attempt 1)
[21:39:20.474] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:39:20.475] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:39:20.475] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9072899326355890656",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9072899326355890656",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2",
"gitiles_ref:refs/heads/master"
]
}
[21:39:23.931] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1563572363201891",
"id": "8907469261642126368",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1563572363772275",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/13473",
"builder:linux-chromeos-dbg",
"buildset:commit/git/cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072899326355890656",
"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/8907469261642126368/+/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": "1563572363772426",
"url": "https://ci.chromium.org/b/8907469261642126368",
"utcnow_ts": "1563572363913767"
}
}
[21:39:23.931] Task URL: https://ci.chromium.org/b/8907469261642126368
[21:39:23.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:2:0) after 1m0s
[21:40:23.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:2:0)
[21:40:23.988] Timer tick, asking Buildbucket for the build status
[21:40:24.331] Build 8907469261642126368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:24.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:3:0) after 1m0s
[21:40:35.132] Received PubSub notification, asking Buildbucket for the build status
[21:40:35.724] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:24.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:3:0)
[21:41:24.318] Timer tick, asking Buildbucket for the build status
[21:41:26.103] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:26.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:5:0) after 1m0s
[21:42:26.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:5:0)
[21:42:26.267] Timer tick, asking Buildbucket for the build status
[21:42:26.656] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:26.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:6:0) after 1m0s
[21:43:26.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:6:0)
[21:43:26.919] Timer tick, asking Buildbucket for the build status
[21:43:27.118] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:27.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:7:0) after 1m0s
[21:44:27.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:7:0)
[21:44:27.346] Timer tick, asking Buildbucket for the build status
[21:44:28.538] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:28.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:8:0) after 1m0s
[21:45:28.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:8:0)
[21:45:28.620] Timer tick, asking Buildbucket for the build status
[21:45:29.133] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:9:0) after 1m0s
[21:46:29.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:9:0)
[21:46:29.199] Timer tick, asking Buildbucket for the build status
[21:46:29.595] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:29.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:10:0) after 1m0s
[21:47:29.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:10:0)
[21:47:29.700] Timer tick, asking Buildbucket for the build status
[21:47:30.195] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:30.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:11:0) after 1m0s
[21:48:30.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:11:0)
[21:48:30.388] Timer tick, asking Buildbucket for the build status
[21:48:31.161] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:31.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:12:0) after 1m0s
[21:49:31.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:12:0)
[21:49:31.401] Timer tick, asking Buildbucket for the build status
[21:49:32.281] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:32.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:13:0) after 1m0s
[21:50:32.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:13:0)
[21:50:32.522] Timer tick, asking Buildbucket for the build status
[21:50:32.667] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:32.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:14:0) after 1m0s
[21:51:32.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:14:0)
[21:51:32.850] Timer tick, asking Buildbucket for the build status
[21:51:33.782] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:33.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:15:0) after 1m0s
[21:52:33.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:15:0)
[21:52:33.805] Timer tick, asking Buildbucket for the build status
[21:52:34.232] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:34.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:16:0) after 1m0s
[21:53:34.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:16:0)
[21:53:34.345] Timer tick, asking Buildbucket for the build status
[21:53:34.827] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:34.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:17:0) after 1m0s
[21:54:34.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:17:0)
[21:54:34.847] Timer tick, asking Buildbucket for the build status
[21:54:35.102] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:35.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:18:0) after 1m0s
[21:55:35.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:18:0)
[21:55:35.193] Timer tick, asking Buildbucket for the build status
[21:55:37.521] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:37.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:19:0) after 1m0s
[21:56:37.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:19:0)
[21:56:37.628] Timer tick, asking Buildbucket for the build status
[21:56:37.905] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:37.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:20:0) after 1m0s
[21:57:38.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:20:0)
[21:57:38.002] Timer tick, asking Buildbucket for the build status
[21:57:38.826] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:38.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:21:0) after 1m0s
[21:58:38.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:21:0)
[21:58:38.806] Timer tick, asking Buildbucket for the build status
[21:58:39.022] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:39.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:22:0) after 1m0s
[21:59:39.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:22:0)
[21:59:39.130] Timer tick, asking Buildbucket for the build status
[21:59:39.567] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:39.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:23:0) after 1m0s
[22:00:39.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:23:0)
[22:00:39.718] Timer tick, asking Buildbucket for the build status
[22:00:39.976] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:39.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:24:0) after 1m0s
[22:01:40.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:24:0)
[22:01:40.037] Timer tick, asking Buildbucket for the build status
[22:01:40.743] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:40.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:25:0) after 1m0s
[22:02:40.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:25:0)
[22:02:40.809] Timer tick, asking Buildbucket for the build status
[22:02:40.966] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:40.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:26:0) after 1m0s
[22:03:41.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:26:0)
[22:03:41.138] Timer tick, asking Buildbucket for the build status
[22:03:41.335] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:41.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:27:0) after 1m0s
[22:04:41.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:27:0)
[22:04:41.383] Timer tick, asking Buildbucket for the build status
[22:04:43.171] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:43.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:28:0) after 1m0s
[22:05:43.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:28:0)
[22:05:43.257] Timer tick, asking Buildbucket for the build status
[22:05:44.217] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:44.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:29:0) after 1m0s
[22:06:44.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:29:0)
[22:06:44.308] Timer tick, asking Buildbucket for the build status
[22:06:44.606] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:44.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:30:0) after 1m0s
[22:07:44.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:30:0)
[22:07:44.623] Timer tick, asking Buildbucket for the build status
[22:07:44.942] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:44.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:31:0) after 1m0s
[22:08:44.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:31:0)
[22:08:44.979] Timer tick, asking Buildbucket for the build status
[22:08:46.107] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:46.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:32:0) after 1m0s
[22:09:46.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:32:0)
[22:09:46.136] Timer tick, asking Buildbucket for the build status
[22:09:46.331] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:46.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:33:0) after 1m0s
[22:10:46.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:33:0)
[22:10:46.438] Timer tick, asking Buildbucket for the build status
[22:10:50.432] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:50.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:34:0) after 1m0s
[22:11:50.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:34:0)
[22:11:50.457] Timer tick, asking Buildbucket for the build status
[22:11:50.655] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:50.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:35:0) after 1m0s
[22:12:50.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:35:0)
[22:12:50.678] Timer tick, asking Buildbucket for the build status
[22:12:50.886] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:50.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:36:0) after 1m0s
[22:13:50.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:36:0)
[22:13:50.915] Timer tick, asking Buildbucket for the build status
[22:13:52.493] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:52.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:37:0) after 1m0s
[22:14:52.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:37:0)
[22:14:52.517] Timer tick, asking Buildbucket for the build status
[22:14:53.569] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:38:0) after 1m0s
[22:15:53.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:38:0)
[22:15:53.610] Timer tick, asking Buildbucket for the build status
[22:15:53.849] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:53.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:39:0) after 1m0s
[22:16:53.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:39:0)
[22:16:53.941] Timer tick, asking Buildbucket for the build status
[22:16:54.155] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:54.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:40:0) after 1m0s
[22:17:54.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:40:0)
[22:17:54.362] Timer tick, asking Buildbucket for the build status
[22:17:55.543] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:55.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:41:0) after 1m0s
[22:18:55.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:41:0)
[22:18:55.682] Timer tick, asking Buildbucket for the build status
[22:18:58.357] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:58.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:42:0) after 1m0s
[22:19:58.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:42:0)
[22:19:58.656] Timer tick, asking Buildbucket for the build status
[22:19:58.831] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:58.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:43:0) after 1m0s
[22:20:58.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:43:0)
[22:20:58.938] Timer tick, asking Buildbucket for the build status
[22:20:59.693] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:59.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:44:0) after 1m0s
[22:21:59.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:44:0)
[22:21:59.846] Timer tick, asking Buildbucket for the build status
[22:22:00.044] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:00.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:45:0) after 1m0s
[22:23:00.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:45:0)
[22:23:00.088] Timer tick, asking Buildbucket for the build status
[22:23:00.431] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:00.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:46:0) after 1m0s
[22:24:00.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:46:0)
[22:24:00.457] Timer tick, asking Buildbucket for the build status
[22:24:00.643] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:00.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:47:0) after 1m0s
[22:25:00.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:47:0)
[22:25:00.675] Timer tick, asking Buildbucket for the build status
[22:25:01.093] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:01.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:48:0) after 1m0s
[22:26:01.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:48:0)
[22:26:01.151] Timer tick, asking Buildbucket for the build status
[22:26:01.917] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:01.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:49:0) after 1m0s
[22:27:01.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:49:0)
[22:27:01.950] Timer tick, asking Buildbucket for the build status
[22:27:04.247] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:04.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:50:0) after 1m0s
[22:28:04.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:50:0)
[22:28:04.287] Timer tick, asking Buildbucket for the build status
[22:28:04.468] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:04.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:51:0) after 1m0s
[22:29:04.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:51:0)
[22:29:04.496] Timer tick, asking Buildbucket for the build status
[22:29:04.795] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:04.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:52:0) after 1m0s
[22:30:04.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:52:0)
[22:30:04.822] Timer tick, asking Buildbucket for the build status
[22:30:05.014] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:05.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:53:0) after 1m0s
[22:31:05.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:53:0)
[22:31:05.035] Timer tick, asking Buildbucket for the build status
[22:31:05.882] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:05.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:54:0) after 1m0s
[22:32:05.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:54:0)
[22:32:05.942] Timer tick, asking Buildbucket for the build status
[22:32:11.333] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:11.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:55:0) after 1m0s
[22:33:11.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:55:0)
[22:33:11.358] Timer tick, asking Buildbucket for the build status
[22:33:12.569] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:12.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:56:0) after 1m0s
[22:34:12.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:56:0)
[22:34:12.719] Timer tick, asking Buildbucket for the build status
[22:34:13.583] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:13.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:57:0) after 1m0s
[22:35:13.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:57:0)
[22:35:13.690] Timer tick, asking Buildbucket for the build status
[22:35:13.890] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:13.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:58:0) after 1m0s
[22:36:13.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:58:0)
[22:36:13.942] Timer tick, asking Buildbucket for the build status
[22:36:14.535] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:14.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:59:0) after 1m0s
[22:37:14.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:59:0)
[22:37:14.612] Timer tick, asking Buildbucket for the build status
[22:37:14.953] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:14.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:60:0) after 1m0s
[22:38:15.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:60:0)
[22:38:15.140] Timer tick, asking Buildbucket for the build status
[22:38:15.456] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:15.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:61:0) after 1m0s
[22:39:15.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:61:0)
[22:39:15.531] Timer tick, asking Buildbucket for the build status
[22:39:16.253] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:16.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:62:0) after 1m0s
[22:40:16.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:62:0)
[22:40:16.456] Timer tick, asking Buildbucket for the build status
[22:40:17.082] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:17.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:63:0) after 1m0s
[22:41:17.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:63:0)
[22:41:17.158] Timer tick, asking Buildbucket for the build status
[22:41:17.796] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:17.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:64:0) after 1m0s
[22:42:17.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:64:0)
[22:42:17.959] Timer tick, asking Buildbucket for the build status
[22:42:18.366] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:18.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:65:0) after 1m0s
[22:43:18.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:65:0)
[22:43:18.546] Timer tick, asking Buildbucket for the build status
[22:43:19.231] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:19.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:66:0) after 1m0s
[22:44:19.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:66:0)
[22:44:19.287] Timer tick, asking Buildbucket for the build status
[22:44:20.632] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:20.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:67:0) after 1m0s
[22:45:20.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:67:0)
[22:45:20.920] Timer tick, asking Buildbucket for the build status
[22:45:21.161] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:21.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:68:0) after 1m0s
[22:46:21.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:68:0)
[22:46:21.364] Timer tick, asking Buildbucket for the build status
[22:46:21.544] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:21.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:69:0) after 1m0s
[22:47:21.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:69:0)
[22:47:21.741] Timer tick, asking Buildbucket for the build status
[22:47:22.710] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:22.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:70:0) after 1m0s
[22:48:23.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:70:0)
[22:48:23.174] Timer tick, asking Buildbucket for the build status
[22:48:23.302] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:23.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:71:0) after 1m0s
[22:49:23.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:71:0)
[22:49:23.366] Timer tick, asking Buildbucket for the build status
[22:49:23.686] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:23.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:72:0) after 1m0s
[22:50:23.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:72:0)
[22:50:23.702] Timer tick, asking Buildbucket for the build status
[22:50:24.202] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:24.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:73:0) after 1m0s
[22:51:24.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:73:0)
[22:51:24.265] Timer tick, asking Buildbucket for the build status
[22:51:25.571] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:25.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:74:0) after 1m0s
[22:52:25.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:74:0)
[22:52:25.657] Timer tick, asking Buildbucket for the build status
[22:52:26.107] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:26.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:75:0) after 1m0s
[22:53:26.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:75:0)
[22:53:26.111] Timer tick, asking Buildbucket for the build status
[22:53:27.045] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:27.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:76:0) after 1m0s
[22:54:27.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:76:0)
[22:54:27.070] Timer tick, asking Buildbucket for the build status
[22:54:27.303] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:27.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:77:0) after 1m0s
[22:55:27.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:77:0)
[22:55:27.347] Timer tick, asking Buildbucket for the build status
[22:55:28.540] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:28.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:78:0) after 1m0s
[22:56:28.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:78:0)
[22:56:28.564] Timer tick, asking Buildbucket for the build status
[22:56:28.845] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:28.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:79:0) after 1m0s
[22:57:28.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:79:0)
[22:57:28.875] Timer tick, asking Buildbucket for the build status
[22:57:29.461] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:29.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:80:0) after 1m0s
[22:58:29.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:80:0)
[22:58:29.550] Timer tick, asking Buildbucket for the build status
[22:58:29.837] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:29.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:81:0) after 1m0s
[22:59:29.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:81:0)
[22:59:29.884] Timer tick, asking Buildbucket for the build status
[22:59:30.173] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:30.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:82:0) after 1m0s
[23:00:30.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:82:0)
[23:00:30.268] Timer tick, asking Buildbucket for the build status
[23:00:30.488] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:30.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:83:0) after 1m0s
[23:01:30.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:83:0)
[23:01:30.645] Timer tick, asking Buildbucket for the build status
[23:01:30.857] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:30.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:84:0) after 1m0s
[23:02:30.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:84:0)
[23:02:30.917] Timer tick, asking Buildbucket for the build status
[23:02:31.507] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:31.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:85:0) after 1m0s
[23:03:31.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:85:0)
[23:03:31.501] Timer tick, asking Buildbucket for the build status
[23:03:31.929] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:31.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:86:0) after 1m0s
[23:04:31.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:86:0)
[23:04:31.959] Timer tick, asking Buildbucket for the build status
[23:04:32.556] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:32.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:87:0) after 1m0s
[23:05:32.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:87:0)
[23:05:32.585] Timer tick, asking Buildbucket for the build status
[23:05:33.065] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:33.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:88:0) after 1m0s
[23:06:33.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:88:0)
[23:06:33.126] Timer tick, asking Buildbucket for the build status
[23:06:34.251] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:34.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:89:0) after 1m0s
[23:07:34.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:89:0)
[23:07:34.370] Timer tick, asking Buildbucket for the build status
[23:07:34.642] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:34.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:90:0) after 1m0s
[23:08:34.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:90:0)
[23:08:34.721] Timer tick, asking Buildbucket for the build status
[23:08:35.989] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:35.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:91:0) after 1m0s
[23:09:36.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:91:0)
[23:09:36.120] Timer tick, asking Buildbucket for the build status
[23:09:36.376] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:36.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:92:0) after 1m0s
[23:10:36.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:92:0)
[23:10:36.399] Timer tick, asking Buildbucket for the build status
[23:10:36.785] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:36.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:93:0) after 1m0s
[23:11:36.934] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:93:0)
[23:11:36.934] Timer tick, asking Buildbucket for the build status
[23:11:38.736] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:38.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:94:0) after 1m0s
[23:12:38.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:94:0)
[23:12:38.904] Timer tick, asking Buildbucket for the build status
[23:12:39.217] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:39.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:95:0) after 1m0s
[23:13:39.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:95:0)
[23:13:39.244] Timer tick, asking Buildbucket for the build status
[23:13:39.502] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:39.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:96:0) after 1m0s
[23:14:39.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:96:0)
[23:14:39.613] Timer tick, asking Buildbucket for the build status
[23:14:40.484] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:40.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:97:0) after 1m0s
[23:15:40.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:97:0)
[23:15:40.549] Timer tick, asking Buildbucket for the build status
[23:15:41.820] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:41.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:98:0) after 1m0s
[23:16:41.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:98:0)
[23:16:41.888] Timer tick, asking Buildbucket for the build status
[23:16:42.007] Build 8907469261642126368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:42.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9072899326355890656:99:0) after 1m0s
[23:16:57.487] Received PubSub notification, asking Buildbucket for the build status
[23:16:57.845] Build 8907469261642126368: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:16:57.845] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1563578216329134",
"created_by": "project:chromium",
"created_ts": "1563572363201891",
"failure_reason": "BUILD_FAILURE",
"id": "8907469261642126368",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"properties\": {\"$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-52-3nla\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 13473, \"got_angle_revision\": \"765ee7b70b7e650b8a6ab90e2ad134b8cae7affc\", \"got_dawn_revision\": \"49aae0f3bde201d10f1b17d362b0d5b8f85dceb4\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2\", \"got_revision_cp\": \"refs/heads/master@{#679275}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"4c7ef0427fa6df8b3629ab0e68bd18ae2a23dd35\", \"got_v8_revision_cp\": \"refs/heads/7.7.282@{#1}\", \"got_webrtc_revision\": \"1a49c1338830b222bc6b22b4e0690615bcd6fa52\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28604}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2\", \"swarm_hashes\": {\"accessibility_unittests\": \"2fede8d386e9447cd176e351dc67607a1ef8b984\", \"angle_unittests\": \"e0c2984b254bc93bcc72d601b9a39207525b32dc\", \"app_list_unittests\": \"fa4dfcca392a29baf12dedcbf92b17723209507b\", \"app_shell_unittests\": \"156a62372e357b3d7b8b1c2c9a78b9c4d098cf93\", \"ash_unittests\": \"775b1eeee3ef600b17d27decc66678d5b47e0387\", \"aura_unittests\": \"1a39376ad1819e4fcaf8f50870887944b3eaca77\", \"base_unittests\": \"79dc14f0071f09a0acfcc93127eb6ed11934dbcb\", \"base_util_unittests\": \"007a3b06fbbff7efba179e8dc808f0054c99014a\", \"blink_common_unittests\": \"8c24b307096ca0f4d31f288639c70f2d40c37b43\", \"blink_fuzzer_unittests\": \"9e5c435f84c57ce7bcd25fcbbeb910827e9375cb\", \"blink_heap_unittests\": \"33a395ac2e23dd919262cd38425014622aaff2a2\", \"blink_platform_unittests\": \"aa16698ff4bc5ba173b05f9724f291cf8f13b778\", \"blink_unittests\": \"879adfcb937eea034ff55e7730fb93433cfa02ef\", \"boringssl_crypto_tests\": \"134ab459265a8349d31df34124252b60aeeabec3\", \"boringssl_ssl_tests\": \"791ffb43c30b39de28ea82ee03ef0988a74b1265\", \"browser_tests\": \"659026d8d1af3ed597a7876a3a4db2dbef1fc2e7\", \"cacheinvalidation_unittests\": \"c91c527379e4834bae5a6e6b1f9aeaeff9a2e5d6\", \"capture_unittests\": \"c7c9b3ae6163095ba27ff08611e8bd3f8c245991\", \"cast_unittests\": \"5dd38b2ec64adc2a0dc1b5ec58ffbf5386f5d8eb\", \"cc_unittests\": \"8a66c44bbda1c6add3254ee87f699483a842964b\", \"chrome_app_unittests\": \"64f91c9b795299b8d74b33d130068f918313ddbd\", \"chromedriver_unittests\": \"51e36e5a02d68c5030e823fdf2f6e85340e6d67f\", \"chromeos_components_unittests\": \"4f5abddc963c9d2feb8f4fa2e951f5b1aaa13d25\", \"chromeos_unittests\": \"73da515791c68f440236712186e79be4cdd5e61e\", \"components_browsertests\": \"6e0cb6050502fc8df47d21b7bb74393ee797a0f6\", \"components_unittests\": \"a6875ed7b6187d94395774a4d40b8a2f0ac44d26\", \"compositor_unittests\": \"b740f58cf3ddc343a452957ca2019a05c48e1e19\", \"content_browsertests\": \"8b026c0f204072c2f9d600010e9bba34df48de82\", \"content_unittests\": \"dab42052f37d79c98cbf3de439dd7581347959a7\", \"crypto_unittests\": \"21be20c60e2820f643a089301ba40db27bd2631f\", \"dbus_unittests\": \"450af92cdb647065f917aeaa231d5618b13b87d2\", \"device_unittests\": \"72dce4e8f28751160e8485bd72d2b61e590043f5\", \"display_unittests\": \"61682ada4ec9bb9b7054b65ff9e647a5b65cc844\", \"events_unittests\": \"ab032ef4329ef7c18aef8ddf351a1179f5d5e314\", \"exo_unittests\": \"22d734c220b7477f42f2fca498918e5d438c9833\", \"extensions_browsertests\": \"119164019021b6ea766a126197b67ca68d1c0960\", \"extensions_unittests\": \"edf606dc5152e74a6bfc126b7710a81d407cf524\", \"filesystem_service_unittests\": \"4e85b18fe1910dcc839981f9185c2b62d55a2eb8\", \"gcm_unit_tests\": \"079bde143a1acedd63f5b05af8aee7e1f7f08712\", \"gfx_unittests\": \"d75bac9391a375d6be182ee35595c59e5768887d\", \"gin_unittests\": \"e2f329aabc795b820133e6d654f51a6a8787c133\", \"gl_unittests_ozone\": \"c3a54aca277e55bb738daaaaae0dea1b3ec16934\", \"google_apis_unittests\": \"b217422fa16d82d2730fd27b11a65b36f56ce516\", \"gpu_unittests\": \"a381778dd422c81b1c65647c2e9783c1b1f36d2e\", \"interactive_ui_tests\": \"9d5b2015a48f7fb67d649e06e4e2fe52818d7fd8\", \"ipc_tests\": \"d9d504b4e186c62668bdf3d1eadb77fa715efb64\", \"jingle_unittests\": \"20a8bf1c34042ffb3c9adfe4d36a3af60107bc3e\", \"keyboard_unittests\": \"1507f2481c28e06888dfb1ae59580e8866cd6bad\", \"latency_unittests\": \"79ef4a466e76cd7b13d012762b4edec91063a0ff\", \"leveldb_service_unittests\": \"e78fba2b5ef00f08554be4cf5ad94f00088a4239\", \"libjingle_xmpp_unittests\": \"cbf42f48e94dec2c7de9ec86071d2afed2b3c594\", \"media_blink_unittests\": \"404ee07d4ff6d20a71989ded79a4c7db193cccef\", \"media_service_unittests\": \"1486027462f8c94206f55a43227a7a9562a6e706\", \"media_unittests\": \"e454b17439b78f32983132fa350485f0db18810f\", \"message_center_unittests\": \"f42d383d810020798a80ea8c11f67f07b1b8898d\", \"midi_unittests\": \"a8bc08c7ca2b8104d4362bc0a370a756de71e196\", \"mojo_core_unittests\": \"ecdd3374cf17a3fadda9b469641a4c8ba4c7a2c3\", \"mojo_unittests\": \"dc3763f8798999bd29a099a6463f452fd3f87be5\", \"nacl_helper_nonsfi_unittests\": \"ac4275320811c608a53ebeeb65d269ee3fe27388\", \"nacl_loader_unittests\": \"3564923f3f63f068368c4b3dc310e08aacef22a7\", \"native_theme_unittests\": \"f7b26cc651b739d25cfd2ec266bfb21af41f539f\", \"net_unittests\": \"258fe81094d80ae7e6ab7a340ef1bb36114ea6e1\", \"ozone_gl_unittests\": \"d408f17de643554a03ba782f0eee8461d95076cc\", \"ozone_unittests\": \"7c6ee27f678df81ac4dfbaf66cf6e90d02d2f0cb\", \"ozone_x11_unittests\": \"6264bb6580f1299004b4fd58eb50ce8309ec4db3\", \"pdf_unittests\": \"60d1aa06ad344351cfa3ac990effd61865d60339\", \"perfetto_unittests\": \"1af44d9805aaaa09e43c4ed31a79a44027f50c2f\", \"ppapi_unittests\": \"9ba81a13a4757730688aa6fa8fd9382d27ab5f4a\", \"printing_unittests\": \"9aded4c00a3a9d562b5c4b2af0d2ce1a9065c496\", \"remoting_unittests\": \"e954ebd5e889f2ef07f288871e3062cc6273ae19\", \"sandbox_linux_unittests\": \"61fd2c5890808507165521f805a781e651e9d8ab\", \"service_manager_unittests\": \"aae5979cef335a7430aba8a9b1392f055c163098\", \"services_unittests\": \"b7f9552323edf22b4daa42ab9c7379894f0eb276\", \"shell_dialogs_unittests\": \"15bafbf43706ae6e90659cacb14a45e453aa735c\", \"skia_unittests\": \"0c8a3fa84ee15adcc12ba30097674baacb4857c9\", \"snapshot_unittests\": \"8d6fcca813e0b8675fc4c9fc36ce8a8b67201193\", \"sql_unittests\": \"d507c05eaf78334953773f428a46fb01309edc18\", \"storage_unittests\": \"9c87105f98e06bae5bb37592c05309c1ce1c550e\", \"sync_integration_tests\": \"a12d0ca25fc61c2c699d105c20472392a9f0a49a\", \"traffic_annotation_auditor_unittests\": \"df77d1dc6e718de276bbac027ecf7adb719b4c55\", \"ui_base_unittests\": \"e994e9626de04de636032e49715ea8a4cd6682e2\", \"ui_chromeos_unittests\": \"8783216533e3b7d8576f641701b497b0338fd1dd\", \"ui_touch_selection_unittests\": \"f6f0dee9e2b35b3f13051a6e544757400c873e9a\", \"unit_tests\": \"c032ac3c8519a42279923d8e2dff82fc84be3acc\", \"url_unittests\": \"ea71dd8f8469c4fc2807327c9ff8f27b52607fb0\", \"usage_time_limit_unittests\": \"da0e8f77a8d7a3dba1cb40413e717390305f2624\", \"views_unittests\": \"efe53b3ae3336ce1dae839bd03a801e4c166bb12\", \"viz_unittests\": \"2932ad63f9f35448ae50370e62bf2b1fd9ca76d0\", \"wm_unittests\": \"ae39f20782d05c2a065391c0f5d66d506336ebf2\", \"wtf_unittests\": \"9c609b63d256018415b75fc3531cb784296dfed4\"}, \"swarm_hashes_refs/heads/master(at){#679275}_without_patch\": {\"accessibility_unittests\": \"2fede8d386e9447cd176e351dc67607a1ef8b984\", \"angle_unittests\": \"e0c2984b254bc93bcc72d601b9a39207525b32dc\", \"app_list_unittests\": \"fa4dfcca392a29baf12dedcbf92b17723209507b\", \"app_shell_unittests\": \"156a62372e357b3d7b8b1c2c9a78b9c4d098cf93\", \"ash_unittests\": \"775b1eeee3ef600b17d27decc66678d5b47e0387\", \"aura_unittests\": \"1a39376ad1819e4fcaf8f50870887944b3eaca77\", \"base_unittests\": \"79dc14f0071f09a0acfcc93127eb6ed11934dbcb\", \"base_util_unittests\": \"007a3b06fbbff7efba179e8dc808f0054c99014a\", \"blink_common_unittests\": \"8c24b307096ca0f4d31f288639c70f2d40c37b43\", \"blink_fuzzer_unittests\": \"9e5c435f84c57ce7bcd25fcbbeb910827e9375cb\", \"blink_heap_unittests\": \"33a395ac2e23dd919262cd38425014622aaff2a2\", \"blink_platform_unittests\": \"aa16698ff4bc5ba173b05f9724f291cf8f13b778\", \"blink_unittests\": \"879adfcb937eea034ff55e7730fb93433cfa02ef\", \"boringssl_crypto_tests\": \"134ab459265a8349d31df34124252b60aeeabec3\", \"boringssl_ssl_tests\": \"791ffb43c30b39de28ea82ee03ef0988a74b1265\", \"browser_tests\": \"659026d8d1af3ed597a7876a3a4db2dbef1fc2e7\", \"cacheinvalidation_unittests\": \"c91c527379e4834bae5a6e6b1f9aeaeff9a2e5d6\", \"capture_unittests\": \"c7c9b3ae6163095ba27ff08611e8bd3f8c245991\", \"cast_unittests\": \"5dd38b2ec64adc2a0dc1b5ec58ffbf5386f5d8eb\", \"cc_unittests\": \"8a66c44bbda1c6add3254ee87f699483a842964b\", \"chrome_app_unittests\": \"64f91c9b795299b8d74b33d130068f918313ddbd\", \"chromedriver_unittests\": \"51e36e5a02d68c5030e823fdf2f6e85340e6d67f\", \"chromeos_components_unittests\": \"4f5abddc963c9d2feb8f4fa2e951f5b1aaa13d25\", \"chromeos_unittests\": \"73da515791c68f440236712186e79be4cdd5e61e\", \"components_browsertests\": \"6e0cb6050502fc8df47d21b7bb74393ee797a0f6\", \"components_unittests\": \"a6875ed7b6187d94395774a4d40b8a2f0ac44d26\", \"compositor_unittests\": \"b740f58cf3ddc343a452957ca2019a05c48e1e19\", \"content_browsertests\": \"8b026c0f204072c2f9d600010e9bba34df48de82\", \"content_unittests\": \"dab42052f37d79c98cbf3de439dd7581347959a7\", \"crypto_unittests\": \"21be20c60e2820f643a089301ba40db27bd2631f\", \"dbus_unittests\": \"450af92cdb647065f917aeaa231d5618b13b87d2\", \"device_unittests\": \"72dce4e8f28751160e8485bd72d2b61e590043f5\", \"display_unittests\": \"61682ada4ec9bb9b7054b65ff9e647a5b65cc844\", \"events_unittests\": \"ab032ef4329ef7c18aef8ddf351a1179f5d5e314\", \"exo_unittests\": \"22d734c220b7477f42f2fca498918e5d438c9833\", \"extensions_browsertests\": \"119164019021b6ea766a126197b67ca68d1c0960\", \"extensions_unittests\": \"edf606dc5152e74a6bfc126b7710a81d407cf524\", \"filesystem_service_unittests\": \"4e85b18fe1910dcc839981f9185c2b62d55a2eb8\", \"gcm_unit_tests\": \"079bde143a1acedd63f5b05af8aee7e1f7f08712\", \"gfx_unittests\": \"d75bac9391a375d6be182ee35595c59e5768887d\", \"gin_unittests\": \"e2f329aabc795b820133e6d654f51a6a8787c133\", \"gl_unittests_ozone\": \"c3a54aca277e55bb738daaaaae0dea1b3ec16934\", \"google_apis_unittests\": \"b217422fa16d82d2730fd27b11a65b36f56ce516\", \"gpu_unittests\": \"a381778dd422c81b1c65647c2e9783c1b1f36d2e\", \"interactive_ui_tests\": \"9d5b2015a48f7fb67d649e06e4e2fe52818d7fd8\", \"ipc_tests\": \"d9d504b4e186c62668bdf3d1eadb77fa715efb64\", \"jingle_unittests\": \"20a8bf1c34042ffb3c9adfe4d36a3af60107bc3e\", \"keyboard_unittests\": \"1507f2481c28e06888dfb1ae59580e8866cd6bad\", \"latency_unittests\": \"79ef4a466e76cd7b13d012762b4edec91063a0ff\", \"leveldb_service_unittests\": \"e78fba2b5ef00f08554be4cf5ad94f00088a4239\", \"libjingle_xmpp_unittests\": \"cbf42f48e94dec2c7de9ec86071d2afed2b3c594\", \"media_blink_unittests\": \"404ee07d4ff6d20a71989ded79a4c7db193cccef\", \"media_service_unittests\": \"1486027462f8c94206f55a43227a7a9562a6e706\", \"media_unittests\": \"e454b17439b78f32983132fa350485f0db18810f\", \"message_center_unittests\": \"f42d383d810020798a80ea8c11f67f07b1b8898d\", \"midi_unittests\": \"a8bc08c7ca2b8104d4362bc0a370a756de71e196\", \"mojo_core_unittests\": \"ecdd3374cf17a3fadda9b469641a4c8ba4c7a2c3\", \"mojo_unittests\": \"dc3763f8798999bd29a099a6463f452fd3f87be5\", \"nacl_helper_nonsfi_unittests\": \"ac4275320811c608a53ebeeb65d269ee3fe27388\", \"nacl_loader_unittests\": \"3564923f3f63f068368c4b3dc310e08aacef22a7\", \"native_theme_unittests\": \"f7b26cc651b739d25cfd2ec266bfb21af41f539f\", \"net_unittests\": \"258fe81094d80ae7e6ab7a340ef1bb36114ea6e1\", \"ozone_gl_unittests\": \"d408f17de643554a03ba782f0eee8461d95076cc\", \"ozone_unittests\": \"7c6ee27f678df81ac4dfbaf66cf6e90d02d2f0cb\", \"ozone_x11_unittests\": \"6264bb6580f1299004b4fd58eb50ce8309ec4db3\", \"pdf_unittests\": \"60d1aa06ad344351cfa3ac990effd61865d60339\", \"perfetto_unittests\": \"1af44d9805aaaa09e43c4ed31a79a44027f50c2f\", \"ppapi_unittests\": \"9ba81a13a4757730688aa6fa8fd9382d27ab5f4a\", \"printing_unittests\": \"9aded4c00a3a9d562b5c4b2af0d2ce1a9065c496\", \"remoting_unittests\": \"e954ebd5e889f2ef07f288871e3062cc6273ae19\", \"sandbox_linux_unittests\": \"61fd2c5890808507165521f805a781e651e9d8ab\", \"service_manager_unittests\": \"aae5979cef335a7430aba8a9b1392f055c163098\", \"services_unittests\": \"b7f9552323edf22b4daa42ab9c7379894f0eb276\", \"shell_dialogs_unittests\": \"15bafbf43706ae6e90659cacb14a45e453aa735c\", \"skia_unittests\": \"0c8a3fa84ee15adcc12ba30097674baacb4857c9\", \"snapshot_unittests\": \"8d6fcca813e0b8675fc4c9fc36ce8a8b67201193\", \"sql_unittests\": \"d507c05eaf78334953773f428a46fb01309edc18\", \"storage_unittests\": \"9c87105f98e06bae5bb37592c05309c1ce1c550e\", \"sync_integration_tests\": \"a12d0ca25fc61c2c699d105c20472392a9f0a49a\", \"traffic_annotation_auditor_unittests\": \"df77d1dc6e718de276bbac027ecf7adb719b4c55\", \"ui_base_unittests\": \"e994e9626de04de636032e49715ea8a4cd6682e2\", \"ui_chromeos_unittests\": \"8783216533e3b7d8576f641701b497b0338fd1dd\", \"ui_touch_selection_unittests\": \"f6f0dee9e2b35b3f13051a6e544757400c873e9a\", \"unit_tests\": \"c032ac3c8519a42279923d8e2dff82fc84be3acc\", \"url_unittests\": \"ea71dd8f8469c4fc2807327c9ff8f27b52607fb0\", \"usage_time_limit_unittests\": \"da0e8f77a8d7a3dba1cb40413e717390305f2624\", \"views_unittests\": \"efe53b3ae3336ce1dae839bd03a801e4c166bb12\", \"viz_unittests\": \"2932ad63f9f35448ae50370e62bf2b1fd9ca76d0\", \"wm_unittests\": \"ae39f20782d05c2a065391c0f5d66d506336ebf2\", \"wtf_unittests\": \"9c609b63d256018415b75fc3531cb784296dfed4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_34caa451ddae04e30af534f20a2f04cca4108dc27b0a92529062f4f2e114a8e9_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_eb75bb97ad9a82e6e51bc242be3605b13562b7c1ffaba99d94159b9b70e03009_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-52-3nla\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4370-0e704a3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1563572434085205",
"status": "COMPLETED",
"status_changed_ts": "1563578216739132",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/13473",
"builder:linux-chromeos-dbg",
"buildset:commit/git/cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cbdd97a8989e260207ca5a3f95ccd1c2c75b9fa2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072899326355890656",
"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/8907469261642126368/+/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:4624fe737c747610",
"user_agent:luci-scheduler"
],
"updated_ts": "1563578216756538",
"url": "https://ci.chromium.org/b/8907469261642126368",
"utcnow_ts": "1563578217834978"
}
}
[23:16:57.845] Invocation finished in 1h37m38.522588236s with status FAILED