[21:26:02.550] New invocation is queued and will start shortly
[21:26:03.659] Starting the invocation (attempt 1)
[21:26:03.728] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:26:03.728] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:26:03.728] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083409409955100944",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"45b93ef93c9bc14b26d83aa56b654a781d90fe90\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083409409955100944",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/45b93ef93c9bc14b26d83aa56b654a781d90fe90",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45b93ef93c9bc14b26d83aa56b654a781d90fe90",
"gitiles_ref:refs/heads/master"
]
}
[21:26:04.597] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553549163906906",
"id": "8917979347866594336",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"45b93ef93c9bc14b26d83aa56b654a781d90fe90\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553549164456393",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11544",
"builder:linux-chromeos-dbg",
"buildset:commit/git/45b93ef93c9bc14b26d83aa56b654a781d90fe90",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45b93ef93c9bc14b26d83aa56b654a781d90fe90",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083409409955100944",
"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/8917979347866594336/+/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": "1553549164456560",
"url": "https://ci.chromium.org/b/8917979347866594336",
"utcnow_ts": "1553549164587522"
}
}
[21:26:04.597] Task URL: https://ci.chromium.org/b/8917979347866594336
[21:26:04.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:2:0) after 1m0s
[21:27:04.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:2:0)
[21:27:04.636] Timer tick, asking Buildbucket for the build status
[21:27:04.851] Build 8917979347866594336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:04.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:3:0) after 1m0s
[21:28:04.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:3:0)
[21:28:04.872] Timer tick, asking Buildbucket for the build status
[21:28:05.083] Build 8917979347866594336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:05.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:4:0) after 1m0s
[21:29:05.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:4:0)
[21:29:05.110] Timer tick, asking Buildbucket for the build status
[21:29:05.233] Build 8917979347866594336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:05.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:5:0) after 1m0s
[21:30:05.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:5:0)
[21:30:05.253] Timer tick, asking Buildbucket for the build status
[21:30:05.820] Build 8917979347866594336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:05.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:6:0) after 1m0s
[21:31:05.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:6:0)
[21:31:05.840] Timer tick, asking Buildbucket for the build status
[21:31:05.973] Build 8917979347866594336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:05.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:7:0) after 1m0s
[21:32:05.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:7:0)
[21:32:05.952] Timer tick, asking Buildbucket for the build status
[21:32:06.672] Build 8917979347866594336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:06.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:8:0) after 1m0s
[21:32:49.281] Received PubSub notification, asking Buildbucket for the build status
[21:32:49.392] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:06.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:8:0)
[21:33:06.645] Timer tick, asking Buildbucket for the build status
[21:33:06.908] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:06.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:10:0) after 1m0s
[21:34:06.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:10:0)
[21:34:06.900] Timer tick, asking Buildbucket for the build status
[21:34:07.491] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:07.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:11:0) after 1m0s
[21:35:07.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:11:0)
[21:35:07.509] Timer tick, asking Buildbucket for the build status
[21:35:07.868] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:07.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:12:0) after 1m0s
[21:36:07.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:12:0)
[21:36:07.879] Timer tick, asking Buildbucket for the build status
[21:36:08.113] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:08.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:13:0) after 1m0s
[21:37:08.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:13:0)
[21:37:08.088] Timer tick, asking Buildbucket for the build status
[21:37:08.197] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:08.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:14:0) after 1m0s
[21:38:08.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:14:0)
[21:38:08.223] Timer tick, asking Buildbucket for the build status
[21:38:08.479] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:08.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:15:0) after 1m0s
[21:39:08.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:15:0)
[21:39:08.490] Timer tick, asking Buildbucket for the build status
[21:39:09.106] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:09.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:16:0) after 1m0s
[21:40:09.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:16:0)
[21:40:09.082] Timer tick, asking Buildbucket for the build status
[21:40:09.231] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:09.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:17:0) after 1m0s
[21:41:09.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:17:0)
[21:41:09.249] Timer tick, asking Buildbucket for the build status
[21:41:09.375] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:09.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:18:0) after 1m0s
[21:42:09.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:18:0)
[21:42:09.398] Timer tick, asking Buildbucket for the build status
[21:42:09.498] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:09.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:19:0) after 1m0s
[21:43:09.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:19:0)
[21:43:09.520] Timer tick, asking Buildbucket for the build status
[21:43:09.847] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:09.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:20:0) after 1m0s
[21:44:09.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:20:0)
[21:44:09.868] Timer tick, asking Buildbucket for the build status
[21:44:09.975] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:09.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:21:0) after 1m0s
[21:53:08.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:21:0)
[21:53:08.849] Timer tick, asking Buildbucket for the build status
[21:53:09.099] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:09.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:22:0) after 1m0s
[21:54:09.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:22:0)
[21:54:09.126] Timer tick, asking Buildbucket for the build status
[21:54:09.606] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:09.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:23:0) after 1m0s
[21:55:09.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:23:0)
[21:55:09.592] Timer tick, asking Buildbucket for the build status
[21:55:10.013] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:10.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:24:0) after 1m0s
[21:56:09.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:24:0)
[21:56:09.996] Timer tick, asking Buildbucket for the build status
[21:56:10.373] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:10.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:25:0) after 1m0s
[21:57:10.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:25:0)
[21:57:10.395] Timer tick, asking Buildbucket for the build status
[21:57:10.568] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:10.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:26:0) after 1m0s
[21:58:10.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:26:0)
[21:58:10.589] Timer tick, asking Buildbucket for the build status
[21:58:11.048] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:11.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:27:0) after 1m0s
[21:59:11.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:27:0)
[21:59:11.067] Timer tick, asking Buildbucket for the build status
[21:59:11.778] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:11.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:28:0) after 1m0s
[22:00:11.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:28:0)
[22:00:11.805] Timer tick, asking Buildbucket for the build status
[22:00:12.009] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:12.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:29:0) after 1m0s
[22:01:12.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:29:0)
[22:01:12.034] Timer tick, asking Buildbucket for the build status
[22:01:12.130] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:12.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:30:0) after 1m0s
[22:02:12.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:30:0)
[22:02:12.135] Timer tick, asking Buildbucket for the build status
[22:02:12.679] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:12.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:31:0) after 1m0s
[22:03:12.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:31:0)
[22:03:12.719] Timer tick, asking Buildbucket for the build status
[22:03:12.821] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:12.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:32:0) after 1m0s
[22:04:12.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:32:0)
[22:04:12.803] Timer tick, asking Buildbucket for the build status
[22:04:12.971] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:12.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:33:0) after 1m0s
[22:05:12.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:33:0)
[22:05:12.949] Timer tick, asking Buildbucket for the build status
[22:05:13.069] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:13.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:34:0) after 1m0s
[22:06:13.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:34:0)
[22:06:13.117] Timer tick, asking Buildbucket for the build status
[22:06:13.646] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:13.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:35:0) after 1m0s
[22:07:13.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:35:0)
[22:07:13.626] Timer tick, asking Buildbucket for the build status
[22:07:13.804] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:13.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:36:0) after 1m0s
[22:08:13.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:36:0)
[22:08:13.836] Timer tick, asking Buildbucket for the build status
[22:08:14.402] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:14.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:37:0) after 1m0s
[22:09:14.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:37:0)
[22:09:14.575] Timer tick, asking Buildbucket for the build status
[22:09:15.196] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:15.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:38:0) after 1m0s
[22:10:15.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:38:0)
[22:10:15.226] Timer tick, asking Buildbucket for the build status
[22:10:15.925] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:15.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:39:0) after 1m0s
[22:11:15.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:39:0)
[22:11:15.972] Timer tick, asking Buildbucket for the build status
[22:11:16.337] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:16.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:40:0) after 1m0s
[22:12:16.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:40:0)
[22:12:16.366] Timer tick, asking Buildbucket for the build status
[22:12:16.641] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:16.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:41:0) after 1m0s
[22:13:16.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:41:0)
[22:13:16.665] Timer tick, asking Buildbucket for the build status
[22:13:17.302] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:17.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:42:0) after 1m0s
[22:14:17.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:42:0)
[22:14:17.293] Timer tick, asking Buildbucket for the build status
[22:14:17.456] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:17.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:43:0) after 1m0s
[22:15:17.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:43:0)
[22:15:17.475] Timer tick, asking Buildbucket for the build status
[22:15:17.658] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:17.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:44:0) after 1m0s
[22:16:17.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:44:0)
[22:16:17.682] Timer tick, asking Buildbucket for the build status
[22:16:17.820] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:17.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:45:0) after 1m0s
[22:17:17.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:45:0)
[22:17:17.867] Timer tick, asking Buildbucket for the build status
[22:17:18.088] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:18.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:46:0) after 1m0s
[22:18:18.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:46:0)
[22:18:18.095] Timer tick, asking Buildbucket for the build status
[22:18:18.563] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:18.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:47:0) after 1m0s
[22:19:18.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:47:0)
[22:19:18.585] Timer tick, asking Buildbucket for the build status
[22:19:19.034] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:19.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:48:0) after 1m0s
[22:20:19.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:48:0)
[22:20:19.064] Timer tick, asking Buildbucket for the build status
[22:20:19.256] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:19.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:49:0) after 1m0s
[22:21:19.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:49:0)
[22:21:19.283] Timer tick, asking Buildbucket for the build status
[22:21:20.319] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:20.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:50:0) after 1m0s
[22:22:20.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:50:0)
[22:22:20.340] Timer tick, asking Buildbucket for the build status
[22:22:20.491] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:20.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:51:0) after 1m0s
[22:23:20.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:51:0)
[22:23:20.509] Timer tick, asking Buildbucket for the build status
[22:23:20.641] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:20.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:52:0) after 1m0s
[22:24:20.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:52:0)
[22:24:20.663] Timer tick, asking Buildbucket for the build status
[22:24:20.757] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:20.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:53:0) after 1m0s
[22:25:20.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:53:0)
[22:25:20.746] Timer tick, asking Buildbucket for the build status
[22:25:20.959] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:20.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:54:0) after 1m0s
[22:26:20.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:54:0)
[22:26:20.984] Timer tick, asking Buildbucket for the build status
[22:26:21.304] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:21.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:55:0) after 1m0s
[22:27:21.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:55:0)
[22:27:21.294] Timer tick, asking Buildbucket for the build status
[22:27:21.912] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:21.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:56:0) after 1m0s
[22:28:21.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:56:0)
[22:28:21.916] Timer tick, asking Buildbucket for the build status
[22:28:22.476] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:22.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:57:0) after 1m0s
[22:29:22.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:57:0)
[22:29:22.495] Timer tick, asking Buildbucket for the build status
[22:29:22.617] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:22.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:58:0) after 1m0s
[22:30:22.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:58:0)
[22:30:22.628] Timer tick, asking Buildbucket for the build status
[22:30:22.898] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:22.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:59:0) after 1m0s
[22:31:22.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:59:0)
[22:31:22.912] Timer tick, asking Buildbucket for the build status
[22:31:23.009] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:23.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:60:0) after 1m0s
[22:32:23.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:60:0)
[22:32:23.043] Timer tick, asking Buildbucket for the build status
[22:32:23.146] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:23.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:61:0) after 1m0s
[22:33:23.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:61:0)
[22:33:23.154] Timer tick, asking Buildbucket for the build status
[22:33:24.468] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:24.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:62:0) after 1m0s
[22:34:24.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:62:0)
[22:34:24.463] Timer tick, asking Buildbucket for the build status
[22:34:24.928] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:24.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:63:0) after 1m0s
[22:35:24.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:63:0)
[22:35:24.952] Timer tick, asking Buildbucket for the build status
[22:35:25.295] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:25.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:64:0) after 1m0s
[22:36:25.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:64:0)
[22:36:25.284] Timer tick, asking Buildbucket for the build status
[22:36:26.031] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:26.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:65:0) after 1m0s
[22:37:26.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:65:0)
[22:37:26.058] Timer tick, asking Buildbucket for the build status
[22:37:26.173] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:26.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:66:0) after 1m0s
[22:38:26.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:66:0)
[22:38:26.184] Timer tick, asking Buildbucket for the build status
[22:38:27.363] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:27.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:67:0) after 1m0s
[22:39:27.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:67:0)
[22:39:27.390] Timer tick, asking Buildbucket for the build status
[22:39:27.577] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:27.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:68:0) after 1m0s
[22:40:27.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:68:0)
[22:40:27.582] Timer tick, asking Buildbucket for the build status
[22:40:28.030] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:28.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:69:0) after 1m0s
[22:41:28.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:69:0)
[22:41:28.039] Timer tick, asking Buildbucket for the build status
[22:41:29.171] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:29.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:70:0) after 1m0s
[22:42:29.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:70:0)
[22:42:29.204] Timer tick, asking Buildbucket for the build status
[22:42:29.324] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:29.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:71:0) after 1m0s
[22:43:29.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:71:0)
[22:43:29.344] Timer tick, asking Buildbucket for the build status
[22:43:29.646] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:29.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:72:0) after 1m0s
[22:44:29.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:72:0)
[22:44:29.667] Timer tick, asking Buildbucket for the build status
[22:44:29.924] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:29.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:73:0) after 1m0s
[22:45:29.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:73:0)
[22:45:29.915] Timer tick, asking Buildbucket for the build status
[22:45:30.267] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:30.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:74:0) after 1m0s
[22:46:30.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:74:0)
[22:46:30.290] Timer tick, asking Buildbucket for the build status
[22:46:30.754] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:30.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:75:0) after 1m0s
[22:47:30.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:75:0)
[22:47:30.775] Timer tick, asking Buildbucket for the build status
[22:47:30.955] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:30.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:76:0) after 1m0s
[22:48:31.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:76:0)
[22:48:31.006] Timer tick, asking Buildbucket for the build status
[22:48:31.895] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:31.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:77:0) after 1m0s
[22:49:31.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:77:0)
[22:49:31.920] Timer tick, asking Buildbucket for the build status
[22:49:32.014] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:32.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:78:0) after 1m0s
[22:50:32.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:78:0)
[22:50:32.036] Timer tick, asking Buildbucket for the build status
[22:50:32.615] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:32.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:79:0) after 1m0s
[22:51:32.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:79:0)
[22:51:32.637] Timer tick, asking Buildbucket for the build status
[22:51:33.088] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:33.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:80:0) after 1m0s
[22:52:33.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:80:0)
[22:52:33.109] Timer tick, asking Buildbucket for the build status
[22:52:34.031] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:34.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:81:0) after 1m0s
[22:53:34.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:81:0)
[22:53:34.036] Timer tick, asking Buildbucket for the build status
[22:53:34.581] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:34.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:82:0) after 1m0s
[22:54:34.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:82:0)
[22:54:34.603] Timer tick, asking Buildbucket for the build status
[22:54:34.723] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:34.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:83:0) after 1m0s
[22:55:34.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:83:0)
[22:55:34.744] Timer tick, asking Buildbucket for the build status
[22:55:35.179] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:35.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:84:0) after 1m0s
[22:56:35.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:84:0)
[22:56:35.263] Timer tick, asking Buildbucket for the build status
[22:56:35.452] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:35.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:85:0) after 1m0s
[22:57:35.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:85:0)
[22:57:35.506] Timer tick, asking Buildbucket for the build status
[22:57:35.991] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:35.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:86:0) after 1m0s
[22:58:35.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:86:0)
[22:58:35.996] Timer tick, asking Buildbucket for the build status
[22:58:36.145] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:36.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:87:0) after 1m0s
[22:59:36.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:87:0)
[22:59:36.185] Timer tick, asking Buildbucket for the build status
[22:59:36.475] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:36.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:88:0) after 1m0s
[23:00:36.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:88:0)
[23:00:36.492] Timer tick, asking Buildbucket for the build status
[23:00:36.655] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:36.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:89:0) after 1m0s
[23:01:36.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:89:0)
[23:01:36.679] Timer tick, asking Buildbucket for the build status
[23:01:36.828] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:36.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:90:0) after 1m0s
[23:02:36.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:90:0)
[23:02:36.891] Timer tick, asking Buildbucket for the build status
[23:02:37.000] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:37.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:91:0) after 1m0s
[23:03:37.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:91:0)
[23:03:37.100] Timer tick, asking Buildbucket for the build status
[23:03:37.912] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:37.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:92:0) after 1m0s
[23:04:37.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:92:0)
[23:04:37.908] Timer tick, asking Buildbucket for the build status
[23:04:38.569] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:38.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:93:0) after 1m0s
[23:05:38.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:93:0)
[23:05:38.600] Timer tick, asking Buildbucket for the build status
[23:05:39.074] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:39.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:94:0) after 1m0s
[23:06:39.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:94:0)
[23:06:39.102] Timer tick, asking Buildbucket for the build status
[23:06:39.603] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:39.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:95:0) after 1m0s
[23:07:39.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:95:0)
[23:07:39.625] Timer tick, asking Buildbucket for the build status
[23:07:39.876] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:39.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:96:0) after 1m0s
[23:08:39.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:96:0)
[23:08:39.888] Timer tick, asking Buildbucket for the build status
[23:08:40.169] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:40.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:97:0) after 1m0s
[23:09:40.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:97:0)
[23:09:40.162] Timer tick, asking Buildbucket for the build status
[23:09:40.511] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:40.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:98:0) after 1m0s
[23:10:40.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:98:0)
[23:10:40.531] Timer tick, asking Buildbucket for the build status
[23:10:40.902] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:40.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:99:0) after 1m0s
[23:11:40.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:99:0)
[23:11:40.893] Timer tick, asking Buildbucket for the build status
[23:11:41.125] Build 8917979347866594336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:41.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083409409955100944:100:0) after 1m0s
[23:11:53.097] Received PubSub notification, asking Buildbucket for the build status
[23:11:54.753] Build 8917979347866594336: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:11:54.753] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553555511667278",
"created_by": "project:chromium",
"created_ts": "1553549163906906",
"failure_reason": "BUILD_FAILURE",
"id": "8917979347866594336",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"45b93ef93c9bc14b26d83aa56b654a781d90fe90\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"5 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_single_process_mash_browser_tests', 'webui_polymer1_browser_tests', 'non_network_service_interactive_ui_tests']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 11544, \"got_angle_revision\": \"0449a902d2c23b407a08afe4ce4123c22d7eedbd\", \"got_dawn_revision\": \"041aca1620e8f905df3342882af33618803b8a41\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"45b93ef93c9bc14b26d83aa56b654a781d90fe90\", \"got_revision_cp\": \"refs/heads/master@{#644006}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"8fea8cc7643ed2cf5b66862c692492e031915617\", \"got_v8_revision_cp\": \"refs/heads/7.5.106@{#1}\", \"got_webrtc_revision\": \"7db3bb95dc9819ab8ed6e08458df110b01d97119\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27267}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"45b93ef93c9bc14b26d83aa56b654a781d90fe90\", \"swarm_hashes\": {\"accessibility_unittests\": \"62801e1762acf58a5048419a176c99b1f4af05c8\", \"angle_unittests\": \"9812eddac5ddf0c5d24b0bbd169485b3fbcfa873\", \"app_list_unittests\": \"50a26c603958b7fcca49e8288fb7325e985c1037\", \"app_shell_unittests\": \"34e23063b82eaf26abacb0f2dd39996a0ff6279a\", \"ash_unittests\": \"d622eed1c0af8b1bcab4ec14a2a42ae826c46b82\", \"aura_unittests\": \"cf47e380200a82fbf45d40d99c6f2045e1cdbd29\", \"base_unittests\": \"7f7a74d892cb3928085b000b35c019b05b52cc6f\", \"blink_common_unittests\": \"764ae8737f637129d72d3d48110750c69d1e09ec\", \"blink_fuzzer_unittests\": \"aec5d5996d5e83a27462002694a7aa395a7f54db\", \"blink_heap_unittests\": \"b7146dbd063efd027f147ae94b413e93ffbac585\", \"blink_platform_unittests\": \"06a6d6dcb53beb79860f9d10a4485022906bf8d7\", \"blink_unittests\": \"6d1fa26815aa60b61bd00f8212a61bfbe02e6f79\", \"boringssl_crypto_tests\": \"317d86e2a31edb6bd3ad67be328a05d912763427\", \"boringssl_ssl_tests\": \"e4e07d3eb5ad72b8ba56c30fd1f7e0a2da84feb0\", \"browser_tests\": \"29f42c162ac3d101a6ab7355ea49fd8874fb7206\", \"cacheinvalidation_unittests\": \"c14d053ddec6c2c8c6cf00281ba6ccaa18317382\", \"capture_unittests\": \"d3fd89468de9d55009d55f609c9f518190d853ad\", \"cast_unittests\": \"eff714b8894617dffb05af7f167e99e466c1f940\", \"cc_unittests\": \"075f873afc06b6c7966274fc1def452e6ca4081b\", \"chrome_app_unittests\": \"e2e74ff4ae5497223e088572cd22bfaa7c7a4d7d\", \"chromedriver_unittests\": \"d2b4da8cc825e62cb535caf0460f46cea90df0a7\", \"chromeos_components_unittests\": \"154a56ed72594423c887f6783c62047ee8f4caf7\", \"chromeos_unittests\": \"07ff2ebe5c77dd0f44a37c0400cdb2e96a9dc8a8\", \"components_browsertests\": \"d46391faf250e9567b038452e127cbc497909c91\", \"components_unittests\": \"05c88b8fd6fbc11aeeb0685a6ecc93c48a666b97\", \"compositor_unittests\": \"12fc67aab84dcf933a1346a4243bd552d1fb1093\", \"content_browsertests\": \"edccd48c33ecceca0076f21d0f1bb23854aec117\", \"content_unittests\": \"4c4e4c9decae3c3f645cb6e7da4051b1cfe958b9\", \"crypto_unittests\": \"f64765dbdc95c16fafab16ee8e69ec2dfaa87f6e\", \"dbus_unittests\": \"eb9574324e707053e4b60811c7f98473333ee87d\", \"device_unittests\": \"0575a1f2eba8cd3121696aae2e991786c7b9770b\", \"display_unittests\": \"1e637a7626c611538c5b0b1866fd6f9f077178c6\", \"events_unittests\": \"6cb85e64801894ae77bf848f60a3420819388ddb\", \"exo_unittests\": \"35f255f4304e14a5d2cd345664a9dfd28cfedab8\", \"extensions_browsertests\": \"b24e19201316c94aeb913cda51917bf33652df92\", \"extensions_unittests\": \"ff0a25480ab645451e244285e74af1dd85970eda\", \"filesystem_service_unittests\": \"ec2f3c550d0fb8d232aa57f3fed3871b54838576\", \"gcm_unit_tests\": \"f0b9abfac4cea24a03aaaae87884c32b234bfe16\", \"gfx_unittests\": \"e859949afe7a33673d8de2e67e78d59852f09e0f\", \"gin_unittests\": \"4724c00bff9532d6b8dbf7716a782c135d8219a5\", \"gl_unittests_ozone\": \"c4e8b342e187b7efa764633dd6c7c809f8fe633b\", \"google_apis_unittests\": \"253d3286a9b85bc02014418994078e2707a2fa8e\", \"gpu_unittests\": \"95fd5bd9fdd92812e19d6a362ecec9efb30f2056\", \"interactive_ui_tests\": \"40004c8d8689296dd58958044f507504b5ff610f\", \"ipc_tests\": \"0faca1af5a4e92d58db84da7887ba228e75f474f\", \"jingle_unittests\": \"104ab49456abbc8571e7f4a69c7cb0d73d0a3102\", \"keyboard_unittests\": \"a73bb3f7f9f475be8779955121fb9061bb83b762\", \"latency_unittests\": \"f04bf6f870adac4a32f5b1fcceb10f8e31305aa2\", \"leveldb_service_unittests\": \"2fede5ba92338d9ca4c96cbf8c181ec0b9a946ed\", \"libjingle_xmpp_unittests\": \"9c5aca6a802068f8742f5faf303cc6578f3649d2\", \"media_blink_unittests\": \"258f75e4044da7b811b0904735fe2d3db1e7096a\", \"media_service_unittests\": \"dff357334281db94e3b6d6b52cfc7ef49dedf8f5\", \"media_unittests\": \"5277db8186d07d8399425d0d8346d6aa5dbf0885\", \"message_center_unittests\": \"85bdae091273cd64cda598eb1db60995db844305\", \"midi_unittests\": \"79b889b309fcd8677926f811eb79258005906c00\", \"mojo_core_unittests\": \"e098798566b8d01fd8e84dc3fbf71ac8aeef841a\", \"mojo_unittests\": \"2c033bba4cb76e6a07291544ca0940f8d52e378e\", \"nacl_helper_nonsfi_unittests\": \"4ff4c2dd2550a32db1701b5de1b54566d0acdcda\", \"nacl_loader_unittests\": \"1f66a3bf4e0b86f72ecfd3ed9d7c72aa97c0dc6a\", \"native_theme_unittests\": \"0ed5d002e85a513d410639c5ab034ee827ebab74\", \"net_unittests\": \"e54ca96dddd034a616384fd16b06938a3e910454\", \"ozone_gl_unittests\": \"865f3e1de36429fe106f4fadd7abe0fb3242a73d\", \"ozone_unittests\": \"ff3521fe4087aaf5a4ac64ce93b44bc790808cdb\", \"ozone_x11_unittests\": \"a28850666422d275cb9f124334b52cf072e4b843\", \"pdf_unittests\": \"ac14764d19040a04ef798b5333a8fe0764101539\", \"perfetto_unittests\": \"c2c09cf14eb06831430ea7ec58b3b54020053a72\", \"ppapi_unittests\": \"ade32068f798a4d59126c574b38d7b2e26f2296d\", \"printing_unittests\": \"d782524fac7612f86f375e27dc5bf373f349beaa\", \"remoting_unittests\": \"aeecc4fb9d988bbfeb27d6df01d3efb0005fb57b\", \"sandbox_linux_unittests\": \"f90f82cd4917568c15e21c9e30be57cbcacca763\", \"service_manager_unittests\": \"7a8e60809e5a27f52a09e4e6c0a46422b044d2f0\", \"services_unittests\": \"5233f6c6596c93f6a507fb5a12cbf4940b6fe6a6\", \"shell_dialogs_unittests\": \"7066fe65e1251cbb2f1432bf745b762c7d6f8079\", \"skia_unittests\": \"bf33b4adb4543c60d3687c293112c4090660e402\", \"snapshot_unittests\": \"2f0ece627534b24581544723956a074a428e05cd\", \"sql_unittests\": \"4d470973fc7a2d4a87b20282522b85a9fe677130\", \"storage_unittests\": \"8cfe19646ae1100725135fdf37de49c88c6e2210\", \"sync_integration_tests\": \"771fc471650b0a4efde19286ebe3172f34998044\", \"traffic_annotation_auditor_unittests\": \"ba10f4de5cb09cb99fb69705925630d161ab15cb\", \"ui_base_unittests\": \"1a21e2a076467245a8ee2cd1627fc67fbe52409b\", \"ui_chromeos_unittests\": \"ce8b95bc23cd261f3d593151f258a769b80acb2e\", \"ui_touch_selection_unittests\": \"0f210828d7c8ad8ebab27431f2b1e8a3c33baa71\", \"unit_tests\": \"9f8f6ce1430a9ffa3508991e48f9d061f4e4a2b7\", \"url_unittests\": \"23409fbad222494ba49cc09437d39b99dcf12275\", \"usage_time_limit_unittests\": \"381ac7de36527d87258dbec36a9de075ac0d79a1\", \"views_unittests\": \"451bfe31ee11f70c51501545e95355add21f086a\", \"viz_unittests\": \"b19a9c92bc58fcc51f5085d2b01d28c109d74124\", \"wm_unittests\": \"cd84ba5bc5fdd3ac70fb25897f4a9f73d50be4ca\", \"wtf_unittests\": \"004247a935c2d368a40af62f26503af28f422bb4\"}, \"swarm_hashes_refs/heads/master(at){#644006}_without_patch\": {\"accessibility_unittests\": \"62801e1762acf58a5048419a176c99b1f4af05c8\", \"angle_unittests\": \"9812eddac5ddf0c5d24b0bbd169485b3fbcfa873\", \"app_list_unittests\": \"50a26c603958b7fcca49e8288fb7325e985c1037\", \"app_shell_unittests\": \"34e23063b82eaf26abacb0f2dd39996a0ff6279a\", \"ash_unittests\": \"d622eed1c0af8b1bcab4ec14a2a42ae826c46b82\", \"aura_unittests\": \"cf47e380200a82fbf45d40d99c6f2045e1cdbd29\", \"base_unittests\": \"7f7a74d892cb3928085b000b35c019b05b52cc6f\", \"blink_common_unittests\": \"764ae8737f637129d72d3d48110750c69d1e09ec\", \"blink_fuzzer_unittests\": \"aec5d5996d5e83a27462002694a7aa395a7f54db\", \"blink_heap_unittests\": \"b7146dbd063efd027f147ae94b413e93ffbac585\", \"blink_platform_unittests\": \"06a6d6dcb53beb79860f9d10a4485022906bf8d7\", \"blink_unittests\": \"6d1fa26815aa60b61bd00f8212a61bfbe02e6f79\", \"boringssl_crypto_tests\": \"317d86e2a31edb6bd3ad67be328a05d912763427\", \"boringssl_ssl_tests\": \"e4e07d3eb5ad72b8ba56c30fd1f7e0a2da84feb0\", \"browser_tests\": \"29f42c162ac3d101a6ab7355ea49fd8874fb7206\", \"cacheinvalidation_unittests\": \"c14d053ddec6c2c8c6cf00281ba6ccaa18317382\", \"capture_unittests\": \"d3fd89468de9d55009d55f609c9f518190d853ad\", \"cast_unittests\": \"eff714b8894617dffb05af7f167e99e466c1f940\", \"cc_unittests\": \"075f873afc06b6c7966274fc1def452e6ca4081b\", \"chrome_app_unittests\": \"e2e74ff4ae5497223e088572cd22bfaa7c7a4d7d\", \"chromedriver_unittests\": \"d2b4da8cc825e62cb535caf0460f46cea90df0a7\", \"chromeos_components_unittests\": \"154a56ed72594423c887f6783c62047ee8f4caf7\", \"chromeos_unittests\": \"07ff2ebe5c77dd0f44a37c0400cdb2e96a9dc8a8\", \"components_browsertests\": \"d46391faf250e9567b038452e127cbc497909c91\", \"components_unittests\": \"05c88b8fd6fbc11aeeb0685a6ecc93c48a666b97\", \"compositor_unittests\": \"12fc67aab84dcf933a1346a4243bd552d1fb1093\", \"content_browsertests\": \"edccd48c33ecceca0076f21d0f1bb23854aec117\", \"content_unittests\": \"4c4e4c9decae3c3f645cb6e7da4051b1cfe958b9\", \"crypto_unittests\": \"f64765dbdc95c16fafab16ee8e69ec2dfaa87f6e\", \"dbus_unittests\": \"eb9574324e707053e4b60811c7f98473333ee87d\", \"device_unittests\": \"0575a1f2eba8cd3121696aae2e991786c7b9770b\", \"display_unittests\": \"1e637a7626c611538c5b0b1866fd6f9f077178c6\", \"events_unittests\": \"6cb85e64801894ae77bf848f60a3420819388ddb\", \"exo_unittests\": \"35f255f4304e14a5d2cd345664a9dfd28cfedab8\", \"extensions_browsertests\": \"b24e19201316c94aeb913cda51917bf33652df92\", \"extensions_unittests\": \"ff0a25480ab645451e244285e74af1dd85970eda\", \"filesystem_service_unittests\": \"ec2f3c550d0fb8d232aa57f3fed3871b54838576\", \"gcm_unit_tests\": \"f0b9abfac4cea24a03aaaae87884c32b234bfe16\", \"gfx_unittests\": \"e859949afe7a33673d8de2e67e78d59852f09e0f\", \"gin_unittests\": \"4724c00bff9532d6b8dbf7716a782c135d8219a5\", \"gl_unittests_ozone\": \"c4e8b342e187b7efa764633dd6c7c809f8fe633b\", \"google_apis_unittests\": \"253d3286a9b85bc02014418994078e2707a2fa8e\", \"gpu_unittests\": \"95fd5bd9fdd92812e19d6a362ecec9efb30f2056\", \"interactive_ui_tests\": \"40004c8d8689296dd58958044f507504b5ff610f\", \"ipc_tests\": \"0faca1af5a4e92d58db84da7887ba228e75f474f\", \"jingle_unittests\": \"104ab49456abbc8571e7f4a69c7cb0d73d0a3102\", \"keyboard_unittests\": \"a73bb3f7f9f475be8779955121fb9061bb83b762\", \"latency_unittests\": \"f04bf6f870adac4a32f5b1fcceb10f8e31305aa2\", \"leveldb_service_unittests\": \"2fede5ba92338d9ca4c96cbf8c181ec0b9a946ed\", \"libjingle_xmpp_unittests\": \"9c5aca6a802068f8742f5faf303cc6578f3649d2\", \"media_blink_unittests\": \"258f75e4044da7b811b0904735fe2d3db1e7096a\", \"media_service_unittests\": \"dff357334281db94e3b6d6b52cfc7ef49dedf8f5\", \"media_unittests\": \"5277db8186d07d8399425d0d8346d6aa5dbf0885\", \"message_center_unittests\": \"85bdae091273cd64cda598eb1db60995db844305\", \"midi_unittests\": \"79b889b309fcd8677926f811eb79258005906c00\", \"mojo_core_unittests\": \"e098798566b8d01fd8e84dc3fbf71ac8aeef841a\", \"mojo_unittests\": \"2c033bba4cb76e6a07291544ca0940f8d52e378e\", \"nacl_helper_nonsfi_unittests\": \"4ff4c2dd2550a32db1701b5de1b54566d0acdcda\", \"nacl_loader_unittests\": \"1f66a3bf4e0b86f72ecfd3ed9d7c72aa97c0dc6a\", \"native_theme_unittests\": \"0ed5d002e85a513d410639c5ab034ee827ebab74\", \"net_unittests\": \"e54ca96dddd034a616384fd16b06938a3e910454\", \"ozone_gl_unittests\": \"865f3e1de36429fe106f4fadd7abe0fb3242a73d\", \"ozone_unittests\": \"ff3521fe4087aaf5a4ac64ce93b44bc790808cdb\", \"ozone_x11_unittests\": \"a28850666422d275cb9f124334b52cf072e4b843\", \"pdf_unittests\": \"ac14764d19040a04ef798b5333a8fe0764101539\", \"perfetto_unittests\": \"c2c09cf14eb06831430ea7ec58b3b54020053a72\", \"ppapi_unittests\": \"ade32068f798a4d59126c574b38d7b2e26f2296d\", \"printing_unittests\": \"d782524fac7612f86f375e27dc5bf373f349beaa\", \"remoting_unittests\": \"aeecc4fb9d988bbfeb27d6df01d3efb0005fb57b\", \"sandbox_linux_unittests\": \"f90f82cd4917568c15e21c9e30be57cbcacca763\", \"service_manager_unittests\": \"7a8e60809e5a27f52a09e4e6c0a46422b044d2f0\", \"services_unittests\": \"5233f6c6596c93f6a507fb5a12cbf4940b6fe6a6\", \"shell_dialogs_unittests\": \"7066fe65e1251cbb2f1432bf745b762c7d6f8079\", \"skia_unittests\": \"bf33b4adb4543c60d3687c293112c4090660e402\", \"snapshot_unittests\": \"2f0ece627534b24581544723956a074a428e05cd\", \"sql_unittests\": \"4d470973fc7a2d4a87b20282522b85a9fe677130\", \"storage_unittests\": \"8cfe19646ae1100725135fdf37de49c88c6e2210\", \"sync_integration_tests\": \"771fc471650b0a4efde19286ebe3172f34998044\", \"traffic_annotation_auditor_unittests\": \"ba10f4de5cb09cb99fb69705925630d161ab15cb\", \"ui_base_unittests\": \"1a21e2a076467245a8ee2cd1627fc67fbe52409b\", \"ui_chromeos_unittests\": \"ce8b95bc23cd261f3d593151f258a769b80acb2e\", \"ui_touch_selection_unittests\": \"0f210828d7c8ad8ebab27431f2b1e8a3c33baa71\", \"unit_tests\": \"9f8f6ce1430a9ffa3508991e48f9d061f4e4a2b7\", \"url_unittests\": \"23409fbad222494ba49cc09437d39b99dcf12275\", \"usage_time_limit_unittests\": \"381ac7de36527d87258dbec36a9de075ac0d79a1\", \"views_unittests\": \"451bfe31ee11f70c51501545e95355add21f086a\", \"viz_unittests\": \"b19a9c92bc58fcc51f5085d2b01d28c109d74124\", \"wm_unittests\": \"cd84ba5bc5fdd3ac70fb25897f4a9f73d50be4ca\", \"wtf_unittests\": \"004247a935c2d368a40af62f26503af28f422bb4\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4216-cf4a3cd\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"5 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_single_process_mash_browser_tests', 'webui_polymer1_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553549568599543",
"status": "COMPLETED",
"status_changed_ts": "1553549568959730",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11544",
"builder:linux-chromeos-dbg",
"buildset:commit/git/45b93ef93c9bc14b26d83aa56b654a781d90fe90",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45b93ef93c9bc14b26d83aa56b654a781d90fe90",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083409409955100944",
"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/8917979347866594336/+/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:43cf90887ec43510",
"user_agent:luci-scheduler"
],
"updated_ts": "1553555512871311",
"url": "https://ci.chromium.org/b/8917979347866594336",
"utcnow_ts": "1553555514690775"
}
}
[23:11:54.753] Invocation finished in 1h45m52.223090059s with status FAILED