[03:46:07.189] New invocation is queued and will start shortly
[03:46:08.691] Starting the invocation (attempt 1)
[03:46:08.744] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:46:08.744] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:46:08.744] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018065084778589088",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9c1f87160b92048bd32f58fd4037eff607d99a79\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9018065084778589088",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/9c1f87160b92048bd32f58fd4037eff607d99a79",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c1f87160b92048bd32f58fd4037eff607d99a79",
"gitiles_ref:refs/heads/master"
]
}
[03:46:09.595] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615866368900395",
"id": "8852635022322085456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9c1f87160b92048bd32f58fd4037eff607d99a79\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615866369374748",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/23000",
"builder:linux-chromeos-dbg",
"buildset:commit/git/9c1f87160b92048bd32f58fd4037eff607d99a79",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c1f87160b92048bd32f58fd4037eff607d99a79",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018065084778589088",
"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/8852635022322085456/+/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": "1615866369374856",
"url": "https://ci.chromium.org/b/8852635022322085456",
"utcnow_ts": "1615866369586006"
}
}
[03:46:09.595] Task URL: https://ci.chromium.org/b/8852635022322085456
[03:46:09.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:2:0) after 1m0s
[03:47:09.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:2:0)
[03:47:09.620] Timer tick, asking Buildbucket for the build status
[03:47:09.732] Build 8852635022322085456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:09.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:3:0) after 1m0s
[03:48:09.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:3:0)
[03:48:09.756] Timer tick, asking Buildbucket for the build status
[03:48:09.919] Build 8852635022322085456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:09.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:4:0) after 1m0s
[03:48:59.961] Received PubSub notification, asking Buildbucket for the build status
[03:49:00.200] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:09.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:4:0)
[03:49:09.959] Timer tick, asking Buildbucket for the build status
[03:49:10.024] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:10.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:6:0) after 1m0s
[03:50:10.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:6:0)
[03:50:10.042] Timer tick, asking Buildbucket for the build status
[03:50:10.102] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:10.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:7:0) after 1m0s
[03:51:10.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:7:0)
[03:51:10.117] Timer tick, asking Buildbucket for the build status
[03:51:10.332] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:10.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:8:0) after 1m0s
[03:52:10.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:8:0)
[03:52:10.347] Timer tick, asking Buildbucket for the build status
[03:52:11.062] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:11.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:9:0) after 1m0s
[03:53:11.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:9:0)
[03:53:11.285] Timer tick, asking Buildbucket for the build status
[03:53:12.171] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:12.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:10:0) after 1m0s
[03:54:12.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:10:0)
[03:54:12.166] Timer tick, asking Buildbucket for the build status
[03:54:12.331] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:12.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:11:0) after 1m0s
[03:55:12.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:11:0)
[03:55:12.316] Timer tick, asking Buildbucket for the build status
[03:55:12.505] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:12.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:12:0) after 1m0s
[03:56:12.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:12:0)
[03:56:12.551] Timer tick, asking Buildbucket for the build status
[03:56:12.633] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:12.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:13:0) after 1m0s
[03:57:12.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:13:0)
[03:57:12.664] Timer tick, asking Buildbucket for the build status
[03:57:14.155] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:14.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:14:0) after 1m0s
[03:58:14.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:14:0)
[03:58:14.192] Timer tick, asking Buildbucket for the build status
[03:58:14.302] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:14.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:15:0) after 1m0s
[03:59:14.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:15:0)
[03:59:14.340] Timer tick, asking Buildbucket for the build status
[03:59:14.429] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:14.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:16:0) after 1m0s
[04:00:14.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:16:0)
[04:00:14.472] Timer tick, asking Buildbucket for the build status
[04:00:14.563] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:14.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:17:0) after 1m0s
[04:01:14.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:17:0)
[04:01:14.577] Timer tick, asking Buildbucket for the build status
[04:01:14.678] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:14.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:18:0) after 1m0s
[04:02:14.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:18:0)
[04:02:14.711] Timer tick, asking Buildbucket for the build status
[04:02:15.082] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:15.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:19:0) after 1m0s
[04:03:15.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:19:0)
[04:03:15.092] Timer tick, asking Buildbucket for the build status
[04:03:15.185] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:15.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:20:0) after 1m0s
[04:04:15.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:20:0)
[04:04:15.325] Timer tick, asking Buildbucket for the build status
[04:04:15.432] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:15.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:21:0) after 1m0s
[04:05:15.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:21:0)
[04:05:15.498] Timer tick, asking Buildbucket for the build status
[04:05:15.680] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:15.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:22:0) after 1m0s
[04:06:15.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:22:0)
[04:06:15.726] Timer tick, asking Buildbucket for the build status
[04:06:15.911] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:15.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:23:0) after 1m0s
[04:07:15.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:23:0)
[04:07:15.968] Timer tick, asking Buildbucket for the build status
[04:07:16.040] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:16.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:24:0) after 1m0s
[04:08:16.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:24:0)
[04:08:16.057] Timer tick, asking Buildbucket for the build status
[04:08:16.137] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:16.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:25:0) after 1m0s
[04:09:16.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:25:0)
[04:09:16.141] Timer tick, asking Buildbucket for the build status
[04:09:16.415] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:16.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:26:0) after 1m0s
[04:10:16.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:26:0)
[04:10:16.394] Timer tick, asking Buildbucket for the build status
[04:10:16.479] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:16.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:27:0) after 1m0s
[04:11:16.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:27:0)
[04:11:16.499] Timer tick, asking Buildbucket for the build status
[04:11:16.587] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:16.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:28:0) after 1m0s
[04:12:16.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:28:0)
[04:12:16.641] Timer tick, asking Buildbucket for the build status
[04:12:16.719] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:16.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:29:0) after 1m0s
[04:13:16.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:29:0)
[04:13:16.721] Timer tick, asking Buildbucket for the build status
[04:13:16.840] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:16.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:30:0) after 1m0s
[04:14:16.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:30:0)
[04:14:16.881] Timer tick, asking Buildbucket for the build status
[04:14:16.957] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:16.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:31:0) after 1m0s
[04:15:16.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:31:0)
[04:15:16.943] Timer tick, asking Buildbucket for the build status
[04:15:17.009] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:17.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:32:0) after 1m0s
[04:16:17.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:32:0)
[04:16:17.081] Timer tick, asking Buildbucket for the build status
[04:16:17.678] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:17.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:33:0) after 1m0s
[04:17:17.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:33:0)
[04:17:17.720] Timer tick, asking Buildbucket for the build status
[04:17:17.800] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:17.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:34:0) after 1m0s
[04:18:17.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:34:0)
[04:18:17.817] Timer tick, asking Buildbucket for the build status
[04:18:17.904] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:17.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:35:0) after 1m0s
[04:19:17.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:35:0)
[04:19:17.926] Timer tick, asking Buildbucket for the build status
[04:19:17.983] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:17.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:36:0) after 1m0s
[04:20:18.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:36:0)
[04:20:18.028] Timer tick, asking Buildbucket for the build status
[04:20:18.166] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:18.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:37:0) after 1m0s
[04:21:18.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:37:0)
[04:21:18.260] Timer tick, asking Buildbucket for the build status
[04:21:18.391] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:18.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:38:0) after 1m0s
[04:22:18.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:38:0)
[04:22:18.467] Timer tick, asking Buildbucket for the build status
[04:22:18.537] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:18.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:39:0) after 1m0s
[04:23:18.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:39:0)
[04:23:18.579] Timer tick, asking Buildbucket for the build status
[04:23:18.882] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:18.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:40:0) after 1m0s
[04:24:18.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:40:0)
[04:24:18.970] Timer tick, asking Buildbucket for the build status
[04:24:19.043] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:19.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:41:0) after 1m0s
[04:25:19.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:41:0)
[04:25:19.184] Timer tick, asking Buildbucket for the build status
[04:25:19.455] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:19.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:42:0) after 1m0s
[04:26:19.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:42:0)
[04:26:19.557] Timer tick, asking Buildbucket for the build status
[04:26:19.866] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:19.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:43:0) after 1m0s
[04:27:19.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:43:0)
[04:27:19.885] Timer tick, asking Buildbucket for the build status
[04:27:20.127] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:20.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:44:0) after 1m0s
[04:28:20.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:44:0)
[04:28:20.138] Timer tick, asking Buildbucket for the build status
[04:28:21.049] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:21.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:45:0) after 1m0s
[04:29:21.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:45:0)
[04:29:21.223] Timer tick, asking Buildbucket for the build status
[04:29:21.302] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:21.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:46:0) after 1m0s
[04:30:21.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:46:0)
[04:30:21.291] Timer tick, asking Buildbucket for the build status
[04:30:21.383] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:21.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:47:0) after 1m0s
[04:31:21.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:47:0)
[04:31:21.401] Timer tick, asking Buildbucket for the build status
[04:31:21.573] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:21.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:48:0) after 1m0s
[04:32:21.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:48:0)
[04:32:21.601] Timer tick, asking Buildbucket for the build status
[04:32:21.773] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:21.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:49:0) after 1m0s
[04:33:21.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:49:0)
[04:33:21.822] Timer tick, asking Buildbucket for the build status
[04:33:21.901] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:21.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:50:0) after 1m0s
[04:34:21.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:50:0)
[04:34:21.949] Timer tick, asking Buildbucket for the build status
[04:34:22.042] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:22.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:51:0) after 1m0s
[04:35:22.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:51:0)
[04:35:22.038] Timer tick, asking Buildbucket for the build status
[04:35:22.121] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:22.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:52:0) after 1m0s
[04:36:22.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:52:0)
[04:36:22.139] Timer tick, asking Buildbucket for the build status
[04:36:22.222] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:22.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:53:0) after 1m0s
[04:37:22.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:53:0)
[04:37:22.278] Timer tick, asking Buildbucket for the build status
[04:37:22.368] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:22.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:54:0) after 1m0s
[04:38:22.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:54:0)
[04:38:22.412] Timer tick, asking Buildbucket for the build status
[04:38:22.704] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:22.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:55:0) after 1m0s
[04:39:22.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:55:0)
[04:39:22.805] Timer tick, asking Buildbucket for the build status
[04:39:22.889] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:22.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:56:0) after 1m0s
[04:40:23.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:56:0)
[04:40:23.675] Timer tick, asking Buildbucket for the build status
[04:40:23.754] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:23.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:57:0) after 1m0s
[04:41:23.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:57:0)
[04:41:23.806] Timer tick, asking Buildbucket for the build status
[04:41:23.905] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:23.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:58:0) after 1m0s
[04:42:23.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:58:0)
[04:42:23.969] Timer tick, asking Buildbucket for the build status
[04:42:25.361] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:25.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:59:0) after 1m0s
[04:43:25.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:59:0)
[04:43:25.483] Timer tick, asking Buildbucket for the build status
[04:43:28.051] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:28.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:60:0) after 1m0s
[04:44:28.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:60:0)
[04:44:28.022] Timer tick, asking Buildbucket for the build status
[04:44:28.110] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:28.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:61:0) after 1m0s
[04:45:28.202] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:61:0)
[04:45:28.202] Timer tick, asking Buildbucket for the build status
[04:45:28.294] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:28.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:62:0) after 1m0s
[04:46:28.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:62:0)
[04:46:28.280] Timer tick, asking Buildbucket for the build status
[04:46:28.398] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:28.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:63:0) after 1m0s
[04:47:28.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:63:0)
[04:47:28.467] Timer tick, asking Buildbucket for the build status
[04:47:28.575] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:28.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:64:0) after 1m0s
[04:48:28.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:64:0)
[04:48:28.595] Timer tick, asking Buildbucket for the build status
[04:48:28.898] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:28.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:65:0) after 1m0s
[04:49:28.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:65:0)
[04:49:28.984] Timer tick, asking Buildbucket for the build status
[04:49:29.152] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:29.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:66:0) after 1m0s
[04:50:29.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:66:0)
[04:50:29.146] Timer tick, asking Buildbucket for the build status
[04:50:29.239] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:29.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:67:0) after 1m0s
[04:51:29.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:67:0)
[04:51:29.232] Timer tick, asking Buildbucket for the build status
[04:51:29.694] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:29.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:68:0) after 1m0s
[04:52:29.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:68:0)
[04:52:29.720] Timer tick, asking Buildbucket for the build status
[04:52:29.960] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:29.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:69:0) after 1m0s
[04:53:30.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:69:0)
[04:53:30.001] Timer tick, asking Buildbucket for the build status
[04:53:30.079] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:30.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:70:0) after 1m0s
[04:54:30.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:70:0)
[04:54:30.123] Timer tick, asking Buildbucket for the build status
[04:54:30.193] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:30.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:71:0) after 1m0s
[04:55:30.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:71:0)
[04:55:30.236] Timer tick, asking Buildbucket for the build status
[04:55:30.358] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:30.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:72:0) after 1m0s
[04:56:30.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:72:0)
[04:56:30.342] Timer tick, asking Buildbucket for the build status
[04:56:30.445] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:30.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:73:0) after 1m0s
[04:57:30.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:73:0)
[04:57:30.466] Timer tick, asking Buildbucket for the build status
[04:57:30.555] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:30.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:74:0) after 1m0s
[04:58:30.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:74:0)
[04:58:30.616] Timer tick, asking Buildbucket for the build status
[04:58:30.752] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:30.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:75:0) after 1m0s
[04:59:30.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:75:0)
[04:59:30.802] Timer tick, asking Buildbucket for the build status
[04:59:30.873] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:30.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:76:0) after 1m0s
[05:00:30.982] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:76:0)
[05:00:30.982] Timer tick, asking Buildbucket for the build status
[05:00:31.086] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:31.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:77:0) after 1m0s
[05:01:31.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:77:0)
[05:01:31.478] Timer tick, asking Buildbucket for the build status
[05:01:31.567] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:31.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:78:0) after 1m0s
[05:02:31.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:78:0)
[05:02:31.803] Timer tick, asking Buildbucket for the build status
[05:02:31.940] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:31.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:79:0) after 1m0s
[05:03:32.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:79:0)
[05:03:32.070] Timer tick, asking Buildbucket for the build status
[05:03:32.146] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:32.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:80:0) after 1m0s
[05:04:32.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:80:0)
[05:04:32.307] Timer tick, asking Buildbucket for the build status
[05:04:32.411] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:32.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:81:0) after 1m0s
[05:05:32.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:81:0)
[05:05:32.527] Timer tick, asking Buildbucket for the build status
[05:05:32.621] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:32.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:82:0) after 1m0s
[05:06:32.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:82:0)
[05:06:32.878] Timer tick, asking Buildbucket for the build status
[05:06:32.967] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:32.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:83:0) after 1m0s
[05:07:33.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:83:0)
[05:07:33.104] Timer tick, asking Buildbucket for the build status
[05:07:33.178] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:33.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:84:0) after 1m0s
[05:08:33.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:84:0)
[05:08:33.259] Timer tick, asking Buildbucket for the build status
[05:08:33.416] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:33.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:85:0) after 1m0s
[05:09:33.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:85:0)
[05:09:33.514] Timer tick, asking Buildbucket for the build status
[05:09:33.580] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:33.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:86:0) after 1m0s
[05:10:33.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:86:0)
[05:10:33.754] Timer tick, asking Buildbucket for the build status
[05:10:33.847] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:33.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:87:0) after 1m0s
[05:11:33.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:87:0)
[05:11:33.935] Timer tick, asking Buildbucket for the build status
[05:11:35.010] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:35.010] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:88:0) after 1m0s
[05:12:35.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:88:0)
[05:12:35.260] Timer tick, asking Buildbucket for the build status
[05:12:35.424] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:35.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:89:0) after 1m0s
[05:13:35.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:89:0)
[05:13:35.561] Timer tick, asking Buildbucket for the build status
[05:13:35.645] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:35.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:90:0) after 1m0s
[05:14:35.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:90:0)
[05:14:35.662] Timer tick, asking Buildbucket for the build status
[05:14:35.735] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:35.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:91:0) after 1m0s
[05:15:35.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:91:0)
[05:15:35.917] Timer tick, asking Buildbucket for the build status
[05:15:36.221] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:36.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:92:0) after 1m0s
[05:16:36.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:92:0)
[05:16:36.319] Timer tick, asking Buildbucket for the build status
[05:16:36.415] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:36.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:93:0) after 1m0s
[05:17:36.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:93:0)
[05:17:36.439] Timer tick, asking Buildbucket for the build status
[05:17:36.536] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:36.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:94:0) after 1m0s
[05:18:36.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:94:0)
[05:18:36.581] Timer tick, asking Buildbucket for the build status
[05:18:36.738] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:36.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:95:0) after 1m0s
[05:19:36.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:95:0)
[05:19:36.754] Timer tick, asking Buildbucket for the build status
[05:19:36.820] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:36.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:96:0) after 1m0s
[05:20:36.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:96:0)
[05:20:36.888] Timer tick, asking Buildbucket for the build status
[05:20:36.968] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:36.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:97:0) after 1m0s
[05:21:37.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:97:0)
[05:21:37.030] Timer tick, asking Buildbucket for the build status
[05:21:37.117] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:37.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:98:0) after 1m0s
[05:22:37.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:98:0)
[05:22:37.140] Timer tick, asking Buildbucket for the build status
[05:22:37.254] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:37.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:99:0) after 1m0s
[05:23:37.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:99:0)
[05:23:37.270] Timer tick, asking Buildbucket for the build status
[05:23:37.342] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:37.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:100:0) after 1m0s
[05:24:37.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:100:0)
[05:24:37.409] Timer tick, asking Buildbucket for the build status
[05:24:37.624] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:37.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:101:0) after 1m0s
[05:25:37.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:101:0)
[05:25:37.850] Timer tick, asking Buildbucket for the build status
[05:25:39.788] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:39.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:102:0) after 1m0s
[05:26:39.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:102:0)
[05:26:39.832] Timer tick, asking Buildbucket for the build status
[05:26:40.858] Build 8852635022322085456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:40.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018065084778589088:103:0) after 1m0s
[05:27:15.181] Received PubSub notification, asking Buildbucket for the build status
[05:27:15.268] Build 8852635022322085456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:27:15.268] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615872433472916",
"created_by": "project:chromium",
"created_ts": "1615866368900395",
"failure_reason": "BUILD_FAILURE",
"id": "8852635022322085456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9c1f87160b92048bd32f58fd4037eff607d99a79\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AppLaunchHandlerBrowserTest.RestoreChromeApp\"}, \"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\": \"9c1f87160b92048bd32f58fd4037eff607d99a79\", \"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\": 23000, \"got_angle_revision\": \"687d3153197e5ef162e7bc75e931a09208ba3fe6\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"9c1f87160b92048bd32f58fd4037eff607d99a79\", \"got_revision_cp\": \"refs/heads/master@{#863127}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"4e2fbf2b6d4fc2e9bbf239e146973a15bb3a0d40\", \"got_v8_revision_cp\": \"refs/heads/9.1.129@{#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\": \"9c1f87160b92048bd32f58fd4037eff607d99a79\", \"swarm_hashes_refs/heads/master(at){#863127}_without_patch\": {\"absl_hardening_tests\": \"de45585fb50123ab6ef5b0be4ab1cb83b432cd84\", \"accessibility_unittests\": \"7dcbfa49ee4ced682bad10c8b2a5316c2c5d938d\", \"angle_unittests\": \"b57eb2201034dba2db86b3e5405de5de803b229d\", \"app_list_unittests\": \"d6eb0931f13606b35ca39eb3f6185c62ad1718fa\", \"app_shell_unittests\": \"acee209ba9de855340d54426476fedf8be5c7142\", \"ash_unittests\": \"805a576e96b83e79e1d6eda1570de5deee15e044\", \"aura_unittests\": \"9add780e3e7035b7753f55ff33b51a9064e80977\", \"base_unittests\": \"8cdea870bbf9fc492ad2f89334dfa630168623ce\", \"base_util_unittests\": \"9185ab6988e1bc773a595f0b2820e71de27d1c66\", \"blink_common_unittests\": \"ee1b1dcf65e8cc9fa3539f19309a3d96a09524af\", \"blink_fuzzer_unittests\": \"4cd6b39f9153d14b35937f9389817ed62844facb\", \"blink_heap_unittests\": \"dfc6c2d8b76175b07bc51d89161e50efa14147d5\", \"blink_platform_unittests\": \"d9a471f7f3ea5608e0c2b048127af997044c03d3\", \"blink_unittests\": \"138ba17bad11ccbc0dfed5a43b076b93ba53cbfa\", \"boringssl_crypto_tests\": \"d59af2ee6e4f7b16f1397cf45a0e83a38b2d8901\", \"boringssl_ssl_tests\": \"2b993179d3244f0cf9005c8348540d51ec60e08d\", \"browser_tests\": \"64cf41736549bb74b01886cdef84984f0dfb8106\", \"capture_unittests\": \"ace9a3b1b4b7c30397869ed5cae94c6809406d93\", \"cast_unittests\": \"40f219914a614920a29632dc0de551cb65e2d1f4\", \"cc_unittests\": \"7be001fd493728a9c72173b298b1b07c5b9f7152\", \"chrome_app_unittests\": \"bcd7d5aea04a90d38f7421b62c1fb4e52edc39f6\", \"chromedriver_unittests\": \"43f2acdbe7ba8d2b2cf5b64785ffe6e2f0931bb1\", \"chromeos_components_unittests\": \"15ea4219748dc6ae2bd63dc6714097809aa305d6\", \"chromeos_unittests\": \"5fefd67e2684787d5f33545126d941f71bafc58d\", \"color_unittests\": \"11e9aa1a0e117f5cad774b66d34a131866cf754f\", \"components_browsertests\": \"7aa0ebd00ea52c1a4ecc6ffc2aef808c4b119ce6\", \"components_unittests\": \"e60acb6e4efd768cb8de97ab1bb6336ce60514cc\", \"compositor_unittests\": \"e9e0418ae703e144c31b26ca41df3f59cd801378\", \"content_browsertests\": \"66a9e1bb1a36c333bcb5bae6c2a98699cbb09d66\", \"content_unittests\": \"7f77b6e6a599ba88161e5dc91f325e7785283970\", \"crashpad_tests\": \"695dcc52b16ff335bed4b852394b4b02f246e108\", \"crypto_unittests\": \"2c4815af498fe58b24aa4f2c5ddd4925e886c2a7\", \"dbus_unittests\": \"cf6b92741f73ef67f158f3e3ca53412ec9ba0aee\", \"device_unittests\": \"2366218be016e1be3ae80f63dbec80fc3e01f663\", \"display_unittests\": \"6f3c68bfedd064735fb5d37ed215a66391ec4e5e\", \"events_unittests\": \"6e43bb2c8480beb88b266548bc214e1b5197858b\", \"exo_unittests\": \"3ff3d27115cb995d79c3f305173858d21da34db4\", \"extensions_browsertests\": \"fed3dda842a20182de6997dde23380d09a29e42c\", \"extensions_unittests\": \"c4b2da520c52a6b8255feaac17b17448aea37198\", \"filesystem_service_unittests\": \"c46d078e4e80e4d85fa02a9bbbebf389a92d76fa\", \"gcm_unit_tests\": \"ec68560dfe6188f15fb6c5ea3783af1739ebfae9\", \"gfx_unittests\": \"63684919dc0e9dc6a6ae47d872ea96476a0e1291\", \"gin_unittests\": \"4c39557993bbf3fe3d1a51ffe94342b0d9f36fc5\", \"gl_unittests_ozone\": \"4046ac39a6cba7595a4acc8e8b27b3504e6d4a2d\", \"google_apis_unittests\": \"64c760bf57adc934541775cd702e41e6df27749d\", \"gpu_unittests\": \"f40f327421d0e1cdfd3326d915a86fe4abbed729\", \"gwp_asan_unittests\": \"d5570929e398d885438a8fbc803739abf00402c3\", \"interactive_ui_tests\": \"adaef7a62a9e88f1d43b9ad39a790b4f902758f9\", \"ipc_tests\": \"cbf1c74de282c9131e5557e60fb4f6b1e371a305\", \"jingle_unittests\": \"dcf13fe226f72774460fcd8fede30e646a7c6712\", \"keyboard_unittests\": \"9a1262c70b55eb31177cd91bdc80baaa89245083\", \"latency_unittests\": \"6dcd39873e1372642caa773bbd17b1d18284b24d\", \"libjingle_xmpp_unittests\": \"7089492f344fe35ee0bf39d58d159d82ded84fc3\", \"liburlpattern_unittests\": \"40323dd42e8b7af1e41b85d9d6d72dbd884d1b8c\", \"media_blink_unittests\": \"2cc100761ff2c2ff2bffc4c0bb025b80ead9860f\", \"media_unittests\": \"04e72c25dfe151e820860beea9851c3f91aeb4cd\", \"message_center_unittests\": \"1dcf3358302e0a03bea55f58ed962a6e7eb02810\", \"midi_unittests\": \"129e09a43f9b878c1376f773cbc945d25f2c2b83\", \"mojo_core_unittests\": \"2e45a13da37c81a8fdd95c477a329bba064807dd\", \"mojo_unittests\": \"8ffb2721f60cbe0bbbfb95a686cb88bcd3219972\", \"nacl_helper_nonsfi_unittests\": \"521ab74f0fe8d267a4bd561f75ef86e74b67a351\", \"nacl_loader_unittests\": \"5619d6f777158907cacad9b8d68970659497106b\", \"native_theme_unittests\": \"d7201371d89f8651e8d77938c5ea9849e4858b09\", \"net_unittests\": \"84030e0f14dc230bc117c1c777a7348e8f4011a1\", \"ozone_gl_unittests\": \"a582b8773b6ef25f99923ac0eaca127f68f7bed9\", \"ozone_unittests\": \"903792e06c427d594ac618ee3f99d13c1449e1a8\", \"ozone_x11_unittests\": \"3c06aba14304a83fb522372db3b004a376f0f3a4\", \"pdf_unittests\": \"c154c4a76614ce7cb743d4b740b4f7a21b5b1f4f\", \"perfetto_unittests\": \"bb2f2e4ca76ee26ba0236980286c84f94f1ac7d5\", \"ppapi_unittests\": \"f80f1779a141c8acdc86f88bee8e531f322bcdb8\", \"printing_unittests\": \"da6e03fc3d455c46bbfab6a338bc7b53d0ef030f\", \"remoting_unittests\": \"abeb7188a22970c007ef2f846d5d9465862fd03d\", \"sandbox_linux_unittests\": \"42dac71f0596cce140b30163c60c4dc38cafb64a\", \"service_manager_unittests\": \"f35f78424ba7f26913ebe418ea8fe3022125c5f6\", \"services_unittests\": \"a02a1dabd580ddea47c288835c918713d7acc0bc\", \"shell_dialogs_unittests\": \"0b154cfeb321f3f975d3348a5d46b9be88c9fb29\", \"shell_encryption_unittests\": \"e3aafbc6b2bd66334463a6357e90f272d2662e17\", \"skia_unittests\": \"640dd7fce07691ed71cf749c5819a359a2b021ba\", \"snapshot_unittests\": \"8561d57c52513b37e5bda144312116c493ff190f\", \"sql_unittests\": \"2887eeaeaeef2fca13e6f61c4f0d78908c748b54\", \"storage_unittests\": \"dc9797fd7ad5a4283b90e5cb590c5c8020aa9531\", \"sync_integration_tests\": \"2cff835607bb9322a4ea5b37556b552fba6e8155\", \"traffic_annotation_auditor_unittests\": \"e0f040f834e4a41d1362888e7067b41a928b9091\", \"ui_base_unittests\": \"6683ab965676bc607ceb1ff8e1e87400fb235c6c\", \"ui_chromeos_unittests\": \"e18bf6b441d85302b15cf41e5fec258a54a6582c\", \"ui_touch_selection_unittests\": \"d4ef65937fc01e984ad377395dfedd15a7922379\", \"unit_tests\": \"d855f433d4d031425ad4fc23f4966584aa3408ab\", \"url_unittests\": \"481f50dc20ef251a8cb3f45b0d8acefe476481db\", \"usage_time_limit_unittests\": \"59d028ee837a9efc8e891c0af4ebba7ff55c23b8\", \"views_unittests\": \"a6fb266bd7c716616c0c5c61ab123216317c445b\", \"viz_unittests\": \"83a419c3075b99dc2fde5e8832ab6e385ae89cbd\", \"wayland_client_tests\": \"e468febf807074c2a3943b19c336498f613f2a7d\", \"wm_unittests\": \"9b7d701e0ea3b78b9fdfcdedd7d67ebb9c36cbcf\", \"wtf_unittests\": \"76eb700bbace8c6c08f6938951dcb4e6b10ba60c\", \"zlib_unittests\": \"079e5d8bd34ced24af0e13194cbfab0cb43638af\"}}, \"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- AppLaunchHandlerBrowserTest.RestoreChromeApp\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615866539237997",
"status": "COMPLETED",
"status_changed_ts": "1615866539579567",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/23000",
"builder:linux-chromeos-dbg",
"buildset:commit/git/9c1f87160b92048bd32f58fd4037eff607d99a79",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c1f87160b92048bd32f58fd4037eff607d99a79",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018065084778589088",
"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/8852635022322085456/+/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:5251f59f80372010",
"user_agent:luci-scheduler"
],
"updated_ts": "1615872433473299",
"url": "https://ci.chromium.org/b/8852635022322085456",
"utcnow_ts": "1615872435260550"
}
}
[05:27:15.268] Invocation finished in 1h41m8.094107203s with status FAILED