[02:07:40.257] New invocation is queued and will start shortly
[02:07:41.311] Starting the invocation (attempt 1)
[02:07:41.344] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:07:41.345] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:07:41.345] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018071278646436144",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9018071278646436144",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1",
"gitiles_ref:refs/heads/master"
]
}
[02:07:44.261] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615860463620606",
"id": "8852641214457060128",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615860464122851",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22999",
"builder:linux-chromeos-dbg",
"buildset:commit/git/6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018071278646436144",
"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/8852641214457060128/+/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": "1615860464122955",
"url": "https://ci.chromium.org/b/8852641214457060128",
"utcnow_ts": "1615860464253869"
}
}
[02:07:44.261] Task URL: https://ci.chromium.org/b/8852641214457060128
[02:07:44.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:2:0) after 1m0s
[02:08:44.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:2:0)
[02:08:44.321] Timer tick, asking Buildbucket for the build status
[02:08:44.650] Build 8852641214457060128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:44.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:3:0) after 1m0s
[02:09:24.010] Received PubSub notification, asking Buildbucket for the build status
[02:09:24.092] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:44.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:3:0)
[02:09:44.696] Timer tick, asking Buildbucket for the build status
[02:09:45.692] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:45.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:5:0) after 1m0s
[02:10:45.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:5:0)
[02:10:45.772] Timer tick, asking Buildbucket for the build status
[02:10:45.908] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:45.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:6:0) after 1m0s
[02:11:46.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:6:0)
[02:11:46.006] Timer tick, asking Buildbucket for the build status
[02:11:46.070] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:46.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:7:0) after 1m0s
[02:12:46.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:7:0)
[02:12:46.109] Timer tick, asking Buildbucket for the build status
[02:12:46.208] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:46.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:8:0) after 1m0s
[02:13:46.279] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:8:0)
[02:13:46.279] Timer tick, asking Buildbucket for the build status
[02:13:46.357] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:46.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:9:0) after 1m0s
[02:14:46.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:9:0)
[02:14:46.432] Timer tick, asking Buildbucket for the build status
[02:14:46.899] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:46.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:10:0) after 1m0s
[02:15:46.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:10:0)
[02:15:46.915] Timer tick, asking Buildbucket for the build status
[02:15:47.078] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:47.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:11:0) after 1m0s
[02:16:47.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:11:0)
[02:16:47.169] Timer tick, asking Buildbucket for the build status
[02:16:47.267] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:47.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:12:0) after 1m0s
[02:17:47.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:12:0)
[02:17:47.363] Timer tick, asking Buildbucket for the build status
[02:17:48.063] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:48.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:13:0) after 1m0s
[02:18:48.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:13:0)
[02:18:48.187] Timer tick, asking Buildbucket for the build status
[02:18:48.293] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:48.293] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:14:0) after 1m0s
[02:19:48.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:14:0)
[02:19:48.358] Timer tick, asking Buildbucket for the build status
[02:19:48.433] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:48.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:15:0) after 1m0s
[02:20:48.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:15:0)
[02:20:48.412] Timer tick, asking Buildbucket for the build status
[02:20:52.629] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:52.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:16:0) after 1m0s
[02:21:52.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:16:0)
[02:21:52.658] Timer tick, asking Buildbucket for the build status
[02:21:52.749] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:52.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:17:0) after 1m0s
[02:22:52.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:17:0)
[02:22:52.768] Timer tick, asking Buildbucket for the build status
[02:22:52.891] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:52.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:18:0) after 1m0s
[02:23:53.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:18:0)
[02:23:53.031] Timer tick, asking Buildbucket for the build status
[02:23:53.125] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:53.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:19:0) after 1m0s
[02:24:53.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:19:0)
[02:24:53.214] Timer tick, asking Buildbucket for the build status
[02:24:53.294] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:53.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:20:0) after 1m0s
[02:25:53.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:20:0)
[02:25:53.328] Timer tick, asking Buildbucket for the build status
[02:25:53.408] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:53.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:21:0) after 1m0s
[02:26:53.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:21:0)
[02:26:53.534] Timer tick, asking Buildbucket for the build status
[02:26:53.748] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:53.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:22:0) after 1m0s
[02:27:53.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:22:0)
[02:27:53.823] Timer tick, asking Buildbucket for the build status
[02:27:53.946] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:53.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:23:0) after 1m0s
[02:28:54.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:23:0)
[02:28:54.043] Timer tick, asking Buildbucket for the build status
[02:28:54.241] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:54.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:24:0) after 1m0s
[02:29:54.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:24:0)
[02:29:54.513] Timer tick, asking Buildbucket for the build status
[02:29:54.815] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:54.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:25:0) after 1m0s
[02:30:54.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:25:0)
[02:30:54.875] Timer tick, asking Buildbucket for the build status
[02:30:54.950] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:54.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:26:0) after 1m0s
[02:31:54.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:26:0)
[02:31:54.969] Timer tick, asking Buildbucket for the build status
[02:31:55.072] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:55.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:27:0) after 1m0s
[02:32:55.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:27:0)
[02:32:55.109] Timer tick, asking Buildbucket for the build status
[02:32:55.243] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:55.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:28:0) after 1m0s
[02:33:55.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:28:0)
[02:33:55.343] Timer tick, asking Buildbucket for the build status
[02:33:55.428] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:55.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:29:0) after 1m0s
[02:34:55.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:29:0)
[02:34:55.505] Timer tick, asking Buildbucket for the build status
[02:34:55.571] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:55.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:30:0) after 1m0s
[02:35:55.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:30:0)
[02:35:55.576] Timer tick, asking Buildbucket for the build status
[02:35:55.637] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:55.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:31:0) after 1m0s
[02:36:55.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:31:0)
[02:36:55.671] Timer tick, asking Buildbucket for the build status
[02:36:55.748] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:55.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:32:0) after 1m0s
[02:37:55.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:32:0)
[02:37:55.772] Timer tick, asking Buildbucket for the build status
[02:37:56.254] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:56.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:33:0) after 1m0s
[02:38:56.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:33:0)
[02:38:56.317] Timer tick, asking Buildbucket for the build status
[02:38:56.388] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:56.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:34:0) after 1m0s
[02:39:56.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:34:0)
[02:39:56.414] Timer tick, asking Buildbucket for the build status
[02:39:56.502] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:56.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:35:0) after 1m0s
[02:40:56.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:35:0)
[02:40:56.560] Timer tick, asking Buildbucket for the build status
[02:40:56.908] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:56.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:36:0) after 1m0s
[02:41:57.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:36:0)
[02:41:57.030] Timer tick, asking Buildbucket for the build status
[02:41:57.253] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:57.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:37:0) after 1m0s
[02:42:57.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:37:0)
[02:42:57.269] Timer tick, asking Buildbucket for the build status
[02:42:57.522] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:57.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:38:0) after 1m0s
[02:43:57.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:38:0)
[02:43:57.583] Timer tick, asking Buildbucket for the build status
[02:43:57.660] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:57.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:39:0) after 1m0s
[02:44:57.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:39:0)
[02:44:57.720] Timer tick, asking Buildbucket for the build status
[02:44:57.832] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:57.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:40:0) after 1m0s
[02:45:57.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:40:0)
[02:45:57.936] Timer tick, asking Buildbucket for the build status
[02:45:58.122] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:58.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:41:0) after 1m0s
[02:46:58.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:41:0)
[02:46:58.275] Timer tick, asking Buildbucket for the build status
[02:46:58.361] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:58.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:42:0) after 1m0s
[02:47:58.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:42:0)
[02:47:58.553] Timer tick, asking Buildbucket for the build status
[02:47:58.711] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:58.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:43:0) after 1m0s
[02:48:58.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:43:0)
[02:48:58.732] Timer tick, asking Buildbucket for the build status
[02:48:58.820] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:58.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:44:0) after 1m0s
[02:49:58.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:44:0)
[02:49:58.907] Timer tick, asking Buildbucket for the build status
[02:49:59.792] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:59.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:45:0) after 1m0s
[02:50:59.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:45:0)
[02:50:59.945] Timer tick, asking Buildbucket for the build status
[02:51:00.231] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:00.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:46:0) after 1m0s
[02:52:00.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:46:0)
[02:52:00.394] Timer tick, asking Buildbucket for the build status
[02:52:00.475] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:00.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:47:0) after 1m0s
[02:53:00.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:47:0)
[02:53:00.494] Timer tick, asking Buildbucket for the build status
[02:53:00.603] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:00.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:48:0) after 1m0s
[02:54:00.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:48:0)
[02:54:00.679] Timer tick, asking Buildbucket for the build status
[02:54:00.765] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:00.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:49:0) after 1m0s
[02:55:01.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:49:0)
[02:55:01.413] Timer tick, asking Buildbucket for the build status
[02:55:01.700] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:01.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:50:0) after 1m0s
[02:56:01.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:50:0)
[02:56:01.765] Timer tick, asking Buildbucket for the build status
[02:56:01.850] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:01.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:51:0) after 1m0s
[02:57:01.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:51:0)
[02:57:01.917] Timer tick, asking Buildbucket for the build status
[02:57:02.107] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:02.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:52:0) after 1m0s
[02:58:02.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:52:0)
[02:58:02.150] Timer tick, asking Buildbucket for the build status
[02:58:02.245] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:02.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:53:0) after 1m0s
[02:59:02.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:53:0)
[02:59:02.306] Timer tick, asking Buildbucket for the build status
[02:59:03.063] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:03.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:54:0) after 1m0s
[03:00:03.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:54:0)
[03:00:03.160] Timer tick, asking Buildbucket for the build status
[03:00:03.259] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:03.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:55:0) after 1m0s
[03:01:03.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:55:0)
[03:01:03.277] Timer tick, asking Buildbucket for the build status
[03:01:03.474] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:03.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:56:0) after 1m0s
[03:02:03.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:56:0)
[03:02:03.543] Timer tick, asking Buildbucket for the build status
[03:02:03.740] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:03.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:57:0) after 1m0s
[03:03:03.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:57:0)
[03:03:03.768] Timer tick, asking Buildbucket for the build status
[03:03:03.856] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:03.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:58:0) after 1m0s
[03:04:03.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:58:0)
[03:04:03.878] Timer tick, asking Buildbucket for the build status
[03:04:04.046] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:04.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:59:0) after 1m0s
[03:05:04.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:59:0)
[03:05:04.088] Timer tick, asking Buildbucket for the build status
[03:05:04.169] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:04.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:60:0) after 1m0s
[03:06:04.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:60:0)
[03:06:04.218] Timer tick, asking Buildbucket for the build status
[03:06:04.344] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:04.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:61:0) after 1m0s
[03:07:04.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:61:0)
[03:07:04.446] Timer tick, asking Buildbucket for the build status
[03:07:04.535] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:04.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:62:0) after 1m0s
[03:08:04.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:62:0)
[03:08:04.811] Timer tick, asking Buildbucket for the build status
[03:08:04.918] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:04.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:63:0) after 1m0s
[03:09:05.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:63:0)
[03:09:05.013] Timer tick, asking Buildbucket for the build status
[03:09:05.115] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:05.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:64:0) after 1m0s
[03:10:05.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:64:0)
[03:10:05.546] Timer tick, asking Buildbucket for the build status
[03:10:05.640] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:05.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:65:0) after 1m0s
[03:11:06.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:65:0)
[03:11:06.020] Timer tick, asking Buildbucket for the build status
[03:11:06.295] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:06.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:66:0) after 1m0s
[03:12:06.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:66:0)
[03:12:06.816] Timer tick, asking Buildbucket for the build status
[03:12:06.892] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:06.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:67:0) after 1m0s
[03:13:07.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:67:0)
[03:13:07.251] Timer tick, asking Buildbucket for the build status
[03:13:07.373] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:07.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:68:0) after 1m0s
[03:14:07.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:68:0)
[03:14:07.477] Timer tick, asking Buildbucket for the build status
[03:14:07.939] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:07.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:69:0) after 1m0s
[03:15:08.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:69:0)
[03:15:08.010] Timer tick, asking Buildbucket for the build status
[03:15:08.091] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:08.091] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:70:0) after 1m0s
[03:16:08.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:70:0)
[03:16:08.082] Timer tick, asking Buildbucket for the build status
[03:16:08.417] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:08.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:71:0) after 1m0s
[03:17:09.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:71:0)
[03:17:09.396] Timer tick, asking Buildbucket for the build status
[03:17:09.543] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:09.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:72:0) after 1m0s
[03:18:09.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:72:0)
[03:18:09.734] Timer tick, asking Buildbucket for the build status
[03:18:09.879] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:09.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:73:0) after 1m0s
[03:19:09.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:73:0)
[03:19:09.985] Timer tick, asking Buildbucket for the build status
[03:19:10.164] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:10.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:74:0) after 1m0s
[03:20:10.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:74:0)
[03:20:10.573] Timer tick, asking Buildbucket for the build status
[03:20:10.713] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:10.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:75:0) after 1m0s
[03:21:11.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:75:0)
[03:21:11.560] Timer tick, asking Buildbucket for the build status
[03:21:11.902] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:11.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:76:0) after 1m0s
[03:22:13.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:76:0)
[03:22:13.346] Timer tick, asking Buildbucket for the build status
[03:22:13.535] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:13.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:77:0) after 1m0s
[03:23:13.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:77:0)
[03:23:13.892] Timer tick, asking Buildbucket for the build status
[03:23:13.989] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:13.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:78:0) after 1m0s
[03:24:14.040] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:78:0)
[03:24:14.040] Timer tick, asking Buildbucket for the build status
[03:24:14.128] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:14.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:79:0) after 1m0s
[03:25:14.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:79:0)
[03:25:14.504] Timer tick, asking Buildbucket for the build status
[03:25:14.600] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:14.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:80:0) after 1m0s
[03:26:14.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:80:0)
[03:26:14.718] Timer tick, asking Buildbucket for the build status
[03:26:14.969] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:14.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:81:0) after 1m0s
[03:27:14.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:81:0)
[03:27:14.971] Timer tick, asking Buildbucket for the build status
[03:27:15.052] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:15.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:82:0) after 1m0s
[03:28:15.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:82:0)
[03:28:15.112] Timer tick, asking Buildbucket for the build status
[03:28:15.548] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:15.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:83:0) after 1m0s
[03:29:16.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:83:0)
[03:29:16.103] Timer tick, asking Buildbucket for the build status
[03:29:16.435] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:16.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:84:0) after 1m0s
[03:30:16.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:84:0)
[03:30:16.663] Timer tick, asking Buildbucket for the build status
[03:30:16.921] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:16.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:85:0) after 1m0s
[03:31:16.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:85:0)
[03:31:16.963] Timer tick, asking Buildbucket for the build status
[03:31:17.215] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:17.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:86:0) after 1m0s
[03:32:17.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:86:0)
[03:32:17.319] Timer tick, asking Buildbucket for the build status
[03:32:17.461] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:17.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:87:0) after 1m0s
[03:33:17.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:87:0)
[03:33:17.586] Timer tick, asking Buildbucket for the build status
[03:33:17.680] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:17.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:88:0) after 1m0s
[03:34:17.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:88:0)
[03:34:17.989] Timer tick, asking Buildbucket for the build status
[03:34:18.249] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:18.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:89:0) after 1m0s
[03:35:18.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:89:0)
[03:35:18.937] Timer tick, asking Buildbucket for the build status
[03:35:19.078] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:19.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:90:0) after 1m0s
[03:36:19.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:90:0)
[03:36:19.494] Timer tick, asking Buildbucket for the build status
[03:36:19.842] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:19.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:91:0) after 1m0s
[03:37:19.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:91:0)
[03:37:19.815] Timer tick, asking Buildbucket for the build status
[03:37:19.883] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:19.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:92:0) after 1m0s
[03:38:19.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:92:0)
[03:38:19.980] Timer tick, asking Buildbucket for the build status
[03:38:20.088] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:20.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:93:0) after 1m0s
[03:39:20.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:93:0)
[03:39:20.130] Timer tick, asking Buildbucket for the build status
[03:39:20.317] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:20.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:94:0) after 1m0s
[03:40:20.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:94:0)
[03:40:20.371] Timer tick, asking Buildbucket for the build status
[03:40:22.065] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:22.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:95:0) after 1m0s
[03:41:22.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:95:0)
[03:41:22.192] Timer tick, asking Buildbucket for the build status
[03:41:23.282] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:23.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:96:0) after 1m0s
[03:42:23.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:96:0)
[03:42:23.298] Timer tick, asking Buildbucket for the build status
[03:42:23.461] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:23.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:97:0) after 1m0s
[03:43:23.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:97:0)
[03:43:23.442] Timer tick, asking Buildbucket for the build status
[03:43:23.563] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:23.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:98:0) after 1m0s
[03:44:23.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:98:0)
[03:44:23.642] Timer tick, asking Buildbucket for the build status
[03:44:23.752] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:23.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:99:0) after 1m0s
[03:45:23.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:99:0)
[03:45:23.797] Timer tick, asking Buildbucket for the build status
[03:45:24.753] Build 8852641214457060128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:24.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018071278646436144:100:0) after 1m0s
[03:46:02.990] Received PubSub notification, asking Buildbucket for the build status
[03:46:04.125] Build 8852641214457060128: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:46:04.126] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615866362565973",
"created_by": "project:chromium",
"created_ts": "1615860463620606",
"failure_reason": "BUILD_FAILURE",
"id": "8852641214457060128",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PolicyPrefsTest.PolicyToPrefsMapping\"}, \"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\": \"6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1\", \"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-171-wk3s\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22999, \"got_angle_revision\": \"06d194e2ae7b1d7e0eda0c0c911eff92dec7d3d1\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1\", \"got_revision_cp\": \"refs/heads/master@{#863104}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"375577d504d1be5ea36a67796334b0a4c6cf320f\", \"got_v8_revision_cp\": \"refs/heads/9.1.128@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"is_cached\": true, \"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\": \"6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1\", \"swarm_hashes_refs/heads/master(at){#863104}_without_patch\": {\"absl_hardening_tests\": \"1848579b88ceaff04d116c669af6a43e73c4e659\", \"accessibility_unittests\": \"00f175011813233d1368548c2fd0e674145574b6\", \"angle_unittests\": \"9320bfc91092fd7e495e526e29b12f2eff4c1ca8\", \"app_list_unittests\": \"189e6c23493c65cc1ada5549a6e7ef14567dc6bd\", \"app_shell_unittests\": \"3ddddf23edb15b2f82fe71f8507935dfa4496c2c\", \"ash_unittests\": \"326255d140118bd0fef9d4646a3148f9a34c95de\", \"aura_unittests\": \"9eb7aa2393d82ee0e4d516ab562f62f4523ad534\", \"base_unittests\": \"103ff63d7e3632e36cacfcda76a8ba8e3272e92e\", \"base_util_unittests\": \"2660d370b6f6e61292ba354edce683cc18d57d69\", \"blink_common_unittests\": \"cf080a881ce6a4eb50478ebb7020238625abbe09\", \"blink_fuzzer_unittests\": \"fe343d7a1c9c20e901fd644f7f45b7162bca96d8\", \"blink_heap_unittests\": \"603e1b89c1c5b4e0262708bc9b306159fccc9ca7\", \"blink_platform_unittests\": \"70f19d56f0cda328b6dd604f9c248fc2228287ee\", \"blink_unittests\": \"a9d86f9454a5ff6828a3dc97aeb3c3c9856016ec\", \"boringssl_crypto_tests\": \"e61a4b7318d218d60cdfde4cc07b2b45905a8fe6\", \"boringssl_ssl_tests\": \"82134987de11b2a472bd06bcac2390e15a107785\", \"browser_tests\": \"ec9fc565b76c31f36eb0368b5c73873d508f0493\", \"capture_unittests\": \"1d0b56bf53e00f604dd0eaf4fa3a7b6f90bb147e\", \"cast_unittests\": \"8a63be2a56889f5d6b2ea43069ed00870217b66f\", \"cc_unittests\": \"57451009cc748147c4bf392ed47d3bf9495d418c\", \"chrome_app_unittests\": \"38370ae99ba16cdc4005319a8d963ebabc17bf71\", \"chromedriver_unittests\": \"e237767e92e392793b4d78f2041f5134b988eb52\", \"chromeos_components_unittests\": \"19456d426ddc5f5eaf0d28505850460f04964f4d\", \"chromeos_unittests\": \"d28b0b0085ae3468156500a6662d66e4b103b511\", \"color_unittests\": \"3bb1ca2dbebab1cf4b30cce1780bf22e33e00200\", \"components_browsertests\": \"f1f66c9ee46ef3b28bccbe6e20b6926f33e1d68b\", \"components_unittests\": \"11ca2a438e1989f68efb71cea41150e3e9f7aa73\", \"compositor_unittests\": \"e3660439f1f3f8dadd9dcf758cc70033bff64617\", \"content_browsertests\": \"9bd223a0e0368350fc17592ec84118fca9c320f1\", \"content_unittests\": \"a4f6bea3217eaecf9b7f8fb1c10996b622c7aaa6\", \"crashpad_tests\": \"59e241ea7ac3e3f73253b03ccbc8fa76ecd09c2c\", \"crypto_unittests\": \"4e2c9ea354436d5014d06d8018a762f11ecdaf7f\", \"dbus_unittests\": \"4d06459b25e74b949ff291fde77373eb55a9cb70\", \"device_unittests\": \"d67bd156cb26f1b68f77622f4493c040b98e0962\", \"display_unittests\": \"9c58719521c73feb3b278f548a60b2033c8974c4\", \"events_unittests\": \"f9594fcc6b92a9fe5ef412f871d94f58fbd2a5f7\", \"exo_unittests\": \"59db8644eb5909ca608fb3cc1f27da8efa4a6275\", \"extensions_browsertests\": \"bf926394b5b309c10b9c16ec6b5b894fb98bc72a\", \"extensions_unittests\": \"4ffd48010285b419daf5c0d04c7e8b1d97c31522\", \"filesystem_service_unittests\": \"ada2dd917c224756e56bea852d43ee69599aa58a\", \"gcm_unit_tests\": \"dc7acc6f1d9a7ce828aa759230ff6eff76440a5f\", \"gfx_unittests\": \"5a33e6fda5c6a6eea53e5cf04c953866b1219355\", \"gin_unittests\": \"835abbfc18fab95e9f090d6f2d148d979a22ddb5\", \"gl_unittests_ozone\": \"529c5ce7d56c9ff41fa98c7c901e6847cabf94c4\", \"google_apis_unittests\": \"76c6c168cba63d4c2b8e9f4ab9ab13b532c0e27a\", \"gpu_unittests\": \"9e31644974ea0af6991e071cc97cd85ef8e4b1a4\", \"gwp_asan_unittests\": \"21ef6dcdbc96594726ebb732c39d1c79e39cc83c\", \"interactive_ui_tests\": \"fe9d9af116e219075eaef95ae34c6956e8712603\", \"ipc_tests\": \"cd9984bf0a15d831f30d4d86f870c00d4502effb\", \"jingle_unittests\": \"e63b452acfb3f8a5261a807b2c04de090c57200c\", \"keyboard_unittests\": \"96ee4781c30b620f9899ed696d225d94c94325a8\", \"latency_unittests\": \"4408d348b952b3cf27bed02450b251a00ddd716b\", \"libjingle_xmpp_unittests\": \"0f95cc4b46f829ac677264c35d95ecfc2e1ef274\", \"liburlpattern_unittests\": \"713f64f8a18014b8816fdfea4d9bd277b4abeba4\", \"media_blink_unittests\": \"7550a5643760b1cda37e66487d5792926888c6d4\", \"media_unittests\": \"8f63e23ddd61d0ccf896b260449ab5618fce22b8\", \"message_center_unittests\": \"bb5a1bac3fa37f79e9965e37f2e3ed10e9156035\", \"midi_unittests\": \"ba5127f058a054cd6c0a8a6364d580c170c26e98\", \"mojo_core_unittests\": \"5df7901c8d30c8651f5c40c2428286fd2ff08bf4\", \"mojo_unittests\": \"d6761ecc86e036b7317832d0f527854ac41c6263\", \"nacl_helper_nonsfi_unittests\": \"b4ed8f62a0baa2aa494646d34e95e6ee6243bf6d\", \"nacl_loader_unittests\": \"3ccfa0b82348e14f344b48b4ce8551bf36d3c943\", \"native_theme_unittests\": \"2869be844efefc77f674cf34eba4072f1e918690\", \"net_unittests\": \"01f769ad2a16ef78a1e6a6f5297cceafe13d5481\", \"ozone_gl_unittests\": \"65286b68b44927a97dd4dc11170061051c71f4bf\", \"ozone_unittests\": \"b70a7de1cec7f98eee45d2f8363476449e92b2dd\", \"ozone_x11_unittests\": \"c10121a8abebbdc02fc14a4cdd22e278bc968416\", \"pdf_unittests\": \"8068b773d4759eff106ec3bdca853819a4c9d323\", \"perfetto_unittests\": \"4175a7ba65c7347991a42709f49760894e0b9fc7\", \"ppapi_unittests\": \"8de07bf7916352452d7e9d1051c4df81f5ea877c\", \"printing_unittests\": \"1e588e83778246cdecf289dde35dce2b42c7b9a5\", \"remoting_unittests\": \"aa3cd71e9410ecc8f65bd452d454e60e4bdbf821\", \"sandbox_linux_unittests\": \"ae6304c615e6bce4d5d5c9efb053fb3f6eb3bf9c\", \"service_manager_unittests\": \"5a80a5945d6eed26358cb24e5979ee94d41b4565\", \"services_unittests\": \"c867863819bb139375c4dca2f5ad71b22b8cf742\", \"shell_dialogs_unittests\": \"d3bd68d16e2a054d0327573c99df1e4508b09e4d\", \"shell_encryption_unittests\": \"79b08433b045b39228f26d04815afcd61278d9b8\", \"skia_unittests\": \"697d8cbe510e1b759aaaa04d4b2dbe040e4738ef\", \"snapshot_unittests\": \"f3a1f36fff0540f4dd1276429b60fdd622a1ff37\", \"sql_unittests\": \"17075c7d63d60106644e39faae6e03e16b8d80af\", \"storage_unittests\": \"c0181c18ed61765cc71e4838b88035ba48781d8d\", \"sync_integration_tests\": \"f1f117647387cdf9d1f713fea5c882274566dd55\", \"traffic_annotation_auditor_unittests\": \"24c72614ab9061e6ec9c147909aa0a5b98908e83\", \"ui_base_unittests\": \"4ca5ae5fc16a3d6ebfda5754d032d7ad5b7f488b\", \"ui_chromeos_unittests\": \"c4bcb6cf1ef33467e3708d1ef94121d84bd48a06\", \"ui_touch_selection_unittests\": \"9aef0051c123a2080dc5280c477fcc596aaf5d2e\", \"unit_tests\": \"732ca0d8ef1cfaad50d4075d90846a741dd71ee5\", \"url_unittests\": \"db24bb5caa5b4aaf16dd8b022b527f1225732b0e\", \"usage_time_limit_unittests\": \"e1be5c3bae4b336b6c3677267661450189ed6fe8\", \"views_unittests\": \"1d3f118553c069718bc6a61067e55fa917781c31\", \"viz_unittests\": \"3158018ba951e659121136e97a22d8260b2a6afe\", \"wayland_client_tests\": \"87dbc2e1b2072afa52b5ba67fe2dae054620d72b\", \"wm_unittests\": \"00458ea89d360210802f6f9c0e55f26fce22129b\", \"wtf_unittests\": \"bb89bcee0a1138f14676d410cb3e557c2b508cce\", \"zlib_unittests\": \"baaf048a16592ad5960606e6caf5a00cf7a5b13c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_34caa451ddae04e30af534f20a2f04cca4108dc27b0a92529062f4f2e114a8e9_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_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-171-wk3s\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PolicyPrefsTest.PolicyToPrefsMapping\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615860563432068",
"status": "COMPLETED",
"status_changed_ts": "1615860563724086",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22999",
"builder:linux-chromeos-dbg",
"buildset:commit/git/6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7c6b6ef2158c7f60787c87ac9a3fda937ac2f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018071278646436144",
"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/8852641214457060128/+/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:52519b83df15c910",
"user_agent:luci-scheduler"
],
"updated_ts": "1615866362581297",
"url": "https://ci.chromium.org/b/8852641214457060128",
"utcnow_ts": "1615866364112294"
}
}
[03:46:04.126] Invocation finished in 1h38m23.88380009s with status FAILED