[20:14:36.829] New invocation is queued and will start shortly
[20:14:38.039] Starting the invocation (attempt 1)
[20:14:38.169] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:14:38.170] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:14:38.170] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085225843204294896",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"63182a5be6cf9502bf84c54a4a63ac4d8f448c52\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9085225843204294896",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/63182a5be6cf9502bf84c54a4a63ac4d8f448c52",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63182a5be6cf9502bf84c54a4a63ac4d8f448c52",
"gitiles_ref:refs/heads/master"
]
}
[20:14:40.043] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551816878296981",
"id": "8919795780982080720",
"lease_expiration_ts": "1551935679796308",
"lease_key": "2057709852",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"63182a5be6cf9502bf84c54a4a63ac4d8f448c52\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551816879916901",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11209",
"builder:linux-chromeos-dbg",
"buildset:commit/git/63182a5be6cf9502bf84c54a4a63ac4d8f448c52",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63182a5be6cf9502bf84c54a4a63ac4d8f448c52",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085225843204294896",
"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/8919795780982080720/+/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:43684ff568c98310",
"user_agent:luci-scheduler"
],
"updated_ts": "1551816879916989",
"url": "https://ci.chromium.org/b/8919795780982080720",
"utcnow_ts": "1551816880034927"
}
}
[20:14:40.043] Task URL: https://ci.chromium.org/b/8919795780982080720
[20:14:40.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:2:0) after 1m0s
[20:15:18.051] Received PubSub notification, asking Buildbucket for the build status
[20:15:18.097] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:40.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:2:0)
[20:15:40.066] Timer tick, asking Buildbucket for the build status
[20:15:40.431] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:40.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:4:0) after 1m0s
[20:16:40.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:4:0)
[20:16:40.466] Timer tick, asking Buildbucket for the build status
[20:16:40.532] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:40.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:5:0) after 1m0s
[20:17:40.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:5:0)
[20:17:40.551] Timer tick, asking Buildbucket for the build status
[20:17:40.589] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:40.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:6:0) after 1m0s
[20:18:40.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:6:0)
[20:18:40.610] Timer tick, asking Buildbucket for the build status
[20:18:40.692] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:40.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:7:0) after 1m0s
[20:19:40.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:7:0)
[20:19:40.695] Timer tick, asking Buildbucket for the build status
[20:19:40.854] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:40.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:8:0) after 1m0s
[20:20:40.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:8:0)
[20:20:40.823] Timer tick, asking Buildbucket for the build status
[20:20:40.935] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:40.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:9:0) after 1m0s
[20:21:40.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:9:0)
[20:21:40.953] Timer tick, asking Buildbucket for the build status
[20:21:41.035] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:41.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:10:0) after 1m0s
[20:22:41.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:10:0)
[20:22:41.037] Timer tick, asking Buildbucket for the build status
[20:22:41.587] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:41.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:11:0) after 1m0s
[20:23:41.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:11:0)
[20:23:41.599] Timer tick, asking Buildbucket for the build status
[20:23:41.880] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:41.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:12:0) after 1m0s
[20:24:41.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:12:0)
[20:24:41.870] Timer tick, asking Buildbucket for the build status
[20:24:42.078] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:42.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:13:0) after 1m0s
[20:25:42.090] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:13:0)
[20:25:42.090] Timer tick, asking Buildbucket for the build status
[20:25:42.136] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:42.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:14:0) after 1m0s
[20:26:42.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:14:0)
[20:26:42.171] Timer tick, asking Buildbucket for the build status
[20:26:42.221] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:42.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:15:0) after 1m0s
[20:27:42.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:15:0)
[20:27:42.196] Timer tick, asking Buildbucket for the build status
[20:27:42.242] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:42.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:16:0) after 1m0s
[20:28:42.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:16:0)
[20:28:42.269] Timer tick, asking Buildbucket for the build status
[20:28:42.308] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:42.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:17:0) after 1m0s
[20:29:42.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:17:0)
[20:29:42.318] Timer tick, asking Buildbucket for the build status
[20:29:42.478] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:42.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:18:0) after 1m0s
[20:30:42.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:18:0)
[20:30:42.463] Timer tick, asking Buildbucket for the build status
[20:30:42.506] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:42.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:19:0) after 1m0s
[20:31:42.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:19:0)
[20:31:42.530] Timer tick, asking Buildbucket for the build status
[20:31:42.790] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:42.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:20:0) after 1m0s
[20:32:42.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:20:0)
[20:32:42.791] Timer tick, asking Buildbucket for the build status
[20:32:42.846] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:42.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:21:0) after 1m0s
[20:33:42.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:21:0)
[20:33:42.855] Timer tick, asking Buildbucket for the build status
[20:33:42.987] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:42.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:22:0) after 1m0s
[20:34:42.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:22:0)
[20:34:42.988] Timer tick, asking Buildbucket for the build status
[20:34:43.172] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:43.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:23:0) after 1m0s
[20:35:43.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:23:0)
[20:35:43.192] Timer tick, asking Buildbucket for the build status
[20:35:43.232] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:43.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:24:0) after 1m0s
[20:36:43.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:24:0)
[20:36:43.255] Timer tick, asking Buildbucket for the build status
[20:36:43.308] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:43.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:25:0) after 1m0s
[20:37:43.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:25:0)
[20:37:43.326] Timer tick, asking Buildbucket for the build status
[20:37:43.401] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:43.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:26:0) after 1m0s
[20:38:43.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:26:0)
[20:38:43.421] Timer tick, asking Buildbucket for the build status
[20:38:43.464] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:43.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:27:0) after 1m0s
[20:39:43.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:27:0)
[20:39:43.500] Timer tick, asking Buildbucket for the build status
[20:39:43.542] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:43.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:28:0) after 1m0s
[20:40:43.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:28:0)
[20:40:43.559] Timer tick, asking Buildbucket for the build status
[20:40:43.599] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:43.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:29:0) after 1m0s
[20:41:43.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:29:0)
[20:41:43.620] Timer tick, asking Buildbucket for the build status
[20:41:43.655] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:43.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:30:0) after 1m0s
[20:42:43.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:30:0)
[20:42:43.680] Timer tick, asking Buildbucket for the build status
[20:42:43.786] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:43.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:31:0) after 1m0s
[20:43:43.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:31:0)
[20:43:43.806] Timer tick, asking Buildbucket for the build status
[20:43:44.721] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:44.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:32:0) after 1m0s
[20:44:44.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:32:0)
[20:44:44.757] Timer tick, asking Buildbucket for the build status
[20:44:44.869] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:44.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:33:0) after 1m0s
[20:45:44.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:33:0)
[20:45:44.906] Timer tick, asking Buildbucket for the build status
[20:45:44.942] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:44.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:34:0) after 1m0s
[20:46:44.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:34:0)
[20:46:44.960] Timer tick, asking Buildbucket for the build status
[20:46:45.059] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:45.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:35:0) after 1m0s
[20:47:45.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:35:0)
[20:47:45.044] Timer tick, asking Buildbucket for the build status
[20:47:45.103] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:45.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:36:0) after 1m0s
[20:48:45.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:36:0)
[20:48:45.131] Timer tick, asking Buildbucket for the build status
[20:48:45.247] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:45.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:37:0) after 1m0s
[20:49:45.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:37:0)
[20:49:45.269] Timer tick, asking Buildbucket for the build status
[20:49:45.389] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:45.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:38:0) after 1m0s
[20:50:45.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:38:0)
[20:50:45.405] Timer tick, asking Buildbucket for the build status
[20:50:45.489] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:45.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:39:0) after 1m0s
[20:51:45.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:39:0)
[20:51:45.511] Timer tick, asking Buildbucket for the build status
[20:51:45.601] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:45.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:40:0) after 1m0s
[20:52:45.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:40:0)
[20:52:45.620] Timer tick, asking Buildbucket for the build status
[20:52:45.759] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:45.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:41:0) after 1m0s
[20:53:45.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:41:0)
[20:53:45.785] Timer tick, asking Buildbucket for the build status
[20:53:46.009] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:46.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:42:0) after 1m0s
[20:54:46.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:42:0)
[20:54:46.047] Timer tick, asking Buildbucket for the build status
[20:54:46.197] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:46.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:43:0) after 1m0s
[20:55:46.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:43:0)
[20:55:46.215] Timer tick, asking Buildbucket for the build status
[20:55:46.398] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:46.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:44:0) after 1m0s
[20:56:46.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:44:0)
[20:56:46.414] Timer tick, asking Buildbucket for the build status
[20:56:46.509] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:46.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:45:0) after 1m0s
[20:57:46.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:45:0)
[20:57:46.506] Timer tick, asking Buildbucket for the build status
[20:57:46.640] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:46.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:46:0) after 1m0s
[20:58:46.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:46:0)
[20:58:46.671] Timer tick, asking Buildbucket for the build status
[20:58:46.775] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:46.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:47:0) after 1m0s
[20:59:46.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:47:0)
[20:59:46.795] Timer tick, asking Buildbucket for the build status
[20:59:46.840] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:46.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:48:0) after 1m0s
[21:00:46.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:48:0)
[21:00:46.824] Timer tick, asking Buildbucket for the build status
[21:00:46.901] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:46.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:49:0) after 1m0s
[21:01:46.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:49:0)
[21:01:46.929] Timer tick, asking Buildbucket for the build status
[21:01:47.072] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:47.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:50:0) after 1m0s
[21:02:47.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:50:0)
[21:02:47.099] Timer tick, asking Buildbucket for the build status
[21:02:47.149] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:47.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:51:0) after 1m0s
[21:03:47.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:51:0)
[21:03:47.187] Timer tick, asking Buildbucket for the build status
[21:03:47.307] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:47.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:52:0) after 1m0s
[21:04:47.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:52:0)
[21:04:47.349] Timer tick, asking Buildbucket for the build status
[21:04:47.399] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:47.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:53:0) after 1m0s
[21:05:47.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:53:0)
[21:05:47.406] Timer tick, asking Buildbucket for the build status
[21:05:47.522] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:47.522] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:54:0) after 1m0s
[21:06:47.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:54:0)
[21:06:47.513] Timer tick, asking Buildbucket for the build status
[21:06:47.599] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:47.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:55:0) after 1m0s
[21:07:47.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:55:0)
[21:07:47.636] Timer tick, asking Buildbucket for the build status
[21:07:47.730] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:47.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:56:0) after 1m0s
[21:08:47.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:56:0)
[21:08:47.705] Timer tick, asking Buildbucket for the build status
[21:08:47.801] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:47.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:57:0) after 1m0s
[21:09:47.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:57:0)
[21:09:47.819] Timer tick, asking Buildbucket for the build status
[21:09:48.044] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:48.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:58:0) after 1m0s
[21:10:48.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:58:0)
[21:10:48.079] Timer tick, asking Buildbucket for the build status
[21:10:48.155] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:48.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:59:0) after 1m0s
[21:11:48.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:59:0)
[21:11:48.245] Timer tick, asking Buildbucket for the build status
[21:11:48.500] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:48.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:60:0) after 1m0s
[21:12:48.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:60:0)
[21:12:48.522] Timer tick, asking Buildbucket for the build status
[21:12:48.795] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:48.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:61:0) after 1m0s
[21:13:48.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:61:0)
[21:13:48.798] Timer tick, asking Buildbucket for the build status
[21:13:48.850] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:48.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:62:0) after 1m0s
[21:14:48.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:62:0)
[21:14:48.879] Timer tick, asking Buildbucket for the build status
[21:14:49.108] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:49.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:63:0) after 1m0s
[21:15:49.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:63:0)
[21:15:49.091] Timer tick, asking Buildbucket for the build status
[21:15:49.411] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:49.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:64:0) after 1m0s
[21:16:49.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:64:0)
[21:16:49.402] Timer tick, asking Buildbucket for the build status
[21:16:49.697] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:49.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:65:0) after 1m0s
[21:17:49.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:65:0)
[21:17:49.722] Timer tick, asking Buildbucket for the build status
[21:17:49.891] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:49.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:66:0) after 1m0s
[21:18:49.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:66:0)
[21:18:49.891] Timer tick, asking Buildbucket for the build status
[21:18:49.945] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:49.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:67:0) after 1m0s
[21:19:49.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:67:0)
[21:19:49.962] Timer tick, asking Buildbucket for the build status
[21:19:50.017] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:50.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:68:0) after 1m0s
[21:20:50.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:68:0)
[21:20:50.015] Timer tick, asking Buildbucket for the build status
[21:20:50.219] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:50.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:69:0) after 1m0s
[21:21:50.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:69:0)
[21:21:50.243] Timer tick, asking Buildbucket for the build status
[21:21:50.294] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:50.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:70:0) after 1m0s
[21:22:50.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:70:0)
[21:22:50.295] Timer tick, asking Buildbucket for the build status
[21:22:50.392] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:50.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:71:0) after 1m0s
[21:23:50.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:71:0)
[21:23:50.390] Timer tick, asking Buildbucket for the build status
[21:23:50.573] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:50.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:72:0) after 1m0s
[21:24:50.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:72:0)
[21:24:50.579] Timer tick, asking Buildbucket for the build status
[21:24:50.874] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:50.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:73:0) after 1m0s
[21:25:50.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:73:0)
[21:25:50.884] Timer tick, asking Buildbucket for the build status
[21:25:50.966] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:50.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:74:0) after 1m0s
[21:26:50.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:74:0)
[21:26:50.990] Timer tick, asking Buildbucket for the build status
[21:26:51.170] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:51.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:75:0) after 1m0s
[21:27:51.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:75:0)
[21:27:51.201] Timer tick, asking Buildbucket for the build status
[21:27:51.716] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:51.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:76:0) after 1m0s
[21:28:51.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:76:0)
[21:28:51.691] Timer tick, asking Buildbucket for the build status
[21:28:51.968] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:51.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:77:0) after 1m0s
[21:29:51.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:77:0)
[21:29:51.988] Timer tick, asking Buildbucket for the build status
[21:29:52.200] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:52.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:78:0) after 1m0s
[21:30:52.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:78:0)
[21:30:52.224] Timer tick, asking Buildbucket for the build status
[21:30:52.465] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:52.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:79:0) after 1m0s
[21:31:52.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:79:0)
[21:31:52.448] Timer tick, asking Buildbucket for the build status
[21:31:52.519] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:52.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:80:0) after 1m0s
[21:32:52.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:80:0)
[21:32:52.516] Timer tick, asking Buildbucket for the build status
[21:32:52.754] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:52.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:81:0) after 1m0s
[21:33:52.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:81:0)
[21:33:52.766] Timer tick, asking Buildbucket for the build status
[21:33:52.842] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:52.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:82:0) after 1m0s
[21:34:52.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:82:0)
[21:34:52.866] Timer tick, asking Buildbucket for the build status
[21:34:52.926] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:52.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:83:0) after 1m0s
[21:35:52.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:83:0)
[21:35:52.953] Timer tick, asking Buildbucket for the build status
[21:35:53.008] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:53.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:84:0) after 1m0s
[21:36:53.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:84:0)
[21:36:53.007] Timer tick, asking Buildbucket for the build status
[21:36:53.083] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:53.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:85:0) after 1m0s
[21:37:53.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:85:0)
[21:37:53.108] Timer tick, asking Buildbucket for the build status
[21:37:53.179] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:53.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:86:0) after 1m0s
[21:38:53.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:86:0)
[21:38:53.194] Timer tick, asking Buildbucket for the build status
[21:38:53.242] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:53.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:87:0) after 1m0s
[21:39:53.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:87:0)
[21:39:53.251] Timer tick, asking Buildbucket for the build status
[21:39:53.385] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:53.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:88:0) after 1m0s
[21:40:53.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:88:0)
[21:40:53.379] Timer tick, asking Buildbucket for the build status
[21:40:53.431] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:53.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:89:0) after 1m0s
[21:41:53.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:89:0)
[21:41:53.458] Timer tick, asking Buildbucket for the build status
[21:41:53.594] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:53.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:90:0) after 1m0s
[21:42:53.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:90:0)
[21:42:53.610] Timer tick, asking Buildbucket for the build status
[21:42:53.712] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:53.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:91:0) after 1m0s
[21:43:53.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:91:0)
[21:43:53.695] Timer tick, asking Buildbucket for the build status
[21:43:53.793] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:53.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:92:0) after 1m0s
[21:44:53.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:92:0)
[21:44:53.831] Timer tick, asking Buildbucket for the build status
[21:44:54.102] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:54.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:93:0) after 1m0s
[21:45:54.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:93:0)
[21:45:54.129] Timer tick, asking Buildbucket for the build status
[21:45:54.213] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:54.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:94:0) after 1m0s
[21:46:54.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:94:0)
[21:46:54.234] Timer tick, asking Buildbucket for the build status
[21:46:54.304] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:54.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:95:0) after 1m0s
[21:47:54.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:95:0)
[21:47:54.292] Timer tick, asking Buildbucket for the build status
[21:47:54.384] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:54.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:96:0) after 1m0s
[21:48:54.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:96:0)
[21:48:54.402] Timer tick, asking Buildbucket for the build status
[21:48:54.830] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:54.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:97:0) after 1m0s
[21:49:54.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:97:0)
[21:49:54.859] Timer tick, asking Buildbucket for the build status
[21:49:54.902] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:54.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:98:0) after 1m0s
[21:50:54.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:98:0)
[21:50:54.908] Timer tick, asking Buildbucket for the build status
[21:50:55.008] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:55.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:99:0) after 1m0s
[21:51:55.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:99:0)
[21:51:55.035] Timer tick, asking Buildbucket for the build status
[21:51:55.194] Build 8919795780982080720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:55.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9085225843204294896:100:0) after 1m0s
[21:52:45.149] Received PubSub notification, asking Buildbucket for the build status
[21:52:45.203] Build 8919795780982080720: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:52:45.203] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551822763075937",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551816878296981",
"failure_reason": "BUILD_FAILURE",
"id": "8919795780982080720",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"63182a5be6cf9502bf84c54a4a63ac4d8f448c52\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"linux-chromeos-dbg\"], \"kvm\": [\"1\"], \"id\": [\"swarm1567-c4\"], \"server_version\": [\"4143-92e279d\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure non_network_service_browser_tests\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"got_revision\": \"63182a5be6cf9502bf84c54a4a63ac4d8f448c52\", \"recipe\": \"chromium\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"got_dawn_revision\": \"b47470daa7377a804df5d94e7a93b5925a57f84a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26973}\", \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#637775}\", \"branch\": \"refs/heads/master\", \"swarm_hashes\": {\"mojo_core_unittests\": \"e267d5e0a5d7e326748b6e63c66ea9b88bb70fad\", \"leveldb_service_unittests\": \"ec57cf7ad69c1c92089f1362798057b8842e9117\", \"storage_unittests\": \"e3ece589034b9e1a631ba3174488b68ffaa21b62\", \"jingle_unittests\": \"2228cf73b5dcca0e1fce8234d7f36d408af122b7\", \"angle_unittests\": \"fd77aaeb04bcc63d9667042711d70acdc3f0e346\", \"cast_unittests\": \"98ab06cf1d5467baf8e2a61d0248c6edec32d798\", \"blink_unittests\": \"b54b8e57a7a173f905417ca8fb0b63124ab7d547\", \"cc_unittests\": \"cf90287015edc53cb1d326a28bf64105cb6cf1d7\", \"blink_common_unittests\": \"7de51fe98d5c41b464ae3c9cc79906dd230a0b57\", \"chromeos_components_unittests\": \"2851ae9590ca3cecb4759a9a7d2b168497ad75ef\", \"shell_dialogs_unittests\": \"0e830af00360ac87b7d70c09d483c3b6b6b2ee39\", \"message_center_unittests\": \"439a68385f705691b5274bc7d9b2ddf45ecdc361\", \"ppapi_unittests\": \"872ed0b3f2c05f919f063725c3f9365e0ab378e6\", \"extensions_unittests\": \"4151be801191754e2fa28351bd53112e7b00ea47\", \"gl_unittests_ozone\": \"92eb463648a5a4a6b85183a317d53078fe743a84\", \"app_shell_unittests\": \"24f92ff0e333db5ed406f0a6de6275c3cbe2e98e\", \"chrome_app_unittests\": \"5b3484f8c7357dd0ef66d0aa76e960326082e5af\", \"gcm_unit_tests\": \"d0005e33637829297229320e67c2c3e3bcc38bee\", \"views_unittests\": \"e4894965523e1fb58cbe39577dea25255e6ac8c2\", \"ash_unittests\": \"a088e245637dd1c160a545853af78e6572d2188d\", \"ui_base_unittests\": \"c26e90ad27c56e12f582897950629828cd291cc3\", \"wm_unittests\": \"e306e1de2156b46e5802bf62fc95e01ead8e616a\", \"boringssl_ssl_tests\": \"18a2cd88554a61efb0c8212e200325a2b50a271f\", \"gin_unittests\": \"7a104a5ac9ef9940461448c73979f13a23a1a36a\", \"extensions_browsertests\": \"c15475ddfe31e1e6389d3453c0c247a7d10c6344\", \"content_unittests\": \"87f24b1f0316772748849b2e11cb06ee8d3c7f0e\", \"ozone_gl_unittests\": \"2f8e1f15613eb2974c482bf6ca878ea04e753bf7\", \"libjingle_xmpp_unittests\": \"105bada07dc013f12e6be7b9afe0485c1e4699d2\", \"remoting_unittests\": \"fa5e923f0221ca98e3392a839b4e67ecfc5a665b\", \"filesystem_service_unittests\": \"2c9db6760c19195d8697e2c9de5191c4568f8eac\", \"gfx_unittests\": \"4a48bd99fb46c92bf71645d059a1f3a5a200ae60\", \"capture_unittests\": \"d525049c087c22195c09f2e0e2f1b65d8515ba8b\", \"boringssl_crypto_tests\": \"d307ffbb933f9d2e0c545729a2b389426e1a2d04\", \"display_unittests\": \"eac4da1c051fe96281a5d2408578ba21d8318329\", \"chromedriver_unittests\": \"26b833afbb38b268fe53fa0a982882fbfa2e8868\", \"traffic_annotation_auditor_unittests\": \"5b588b6bd65af2ddfb93b311e79824407f3c9cf1\", \"chromeos_unittests\": \"a733de57cc2f09de6c6efd39fb4af61da70438df\", \"media_service_unittests\": \"3a0e6498a6af68a5c6e782690859f0c7336d529d\", \"compositor_unittests\": \"fe3fe3802a8ff4518efbead2a88b0e3167a6c7fb\", \"latency_unittests\": \"4c00a47f0f5dd3f6ad6a2e2f16842da8f72de1a0\", \"ui_chromeos_unittests\": \"343a792923fb35751bfd157bfe7d1ae2a11e4e80\", \"service_manager_unittests\": \"52fe6a2f4ed477af7e4b702289af37f7b634fa76\", \"media_blink_unittests\": \"90cc9ab6cad3de6972089cd1218e939d7e6579df\", \"components_unittests\": \"04a04d24ad38d356567d33cea66fd82443d44bec\", \"snapshot_unittests\": \"b6f360ec455316ed0e5ea97973578b6f45de7c33\", \"accessibility_unittests\": \"fc03bdcde6932f9c030e9971847ebf68455aa135\", \"aura_unittests\": \"a85af2993b88e79a413e0c0589dce6f2a709a4e3\", \"services_unittests\": \"38c635007e97086d1983c518831f3b2a375d9912\", \"ozone_unittests\": \"f445e49034a1e7585723f810b4d0c1f6801657fe\", \"events_unittests\": \"b40bed1c934d7ae2d5f3e1334d8a89428e6ea012\", \"ui_touch_selection_unittests\": \"ff0ff3f49f0bf93dd9a823e6bd0bedc987556e59\", \"cacheinvalidation_unittests\": \"5b031a3c64aee8ee22bdb16efc7e51101542bd7f\", \"viz_unittests\": \"ace41c66e8af751d56f373c6714904f30b77a652\", \"browser_tests\": \"26f9475418614f87a8877408856b0ef9d2d52808\", \"net_unittests\": \"7eef49e2b9909f0c190f93b6b1b197b8bd06cd10\", \"midi_unittests\": \"ec45cf9ec17fe13ce1ee1d82c04733032655a7b0\", \"dbus_unittests\": \"8579f32acf6d28e6961c3dd6462e3920f8fb2dc4\", \"gpu_unittests\": \"4d3d2feee92f83c4c8cba6476b3c2c39ba35dc97\", \"exo_unittests\": \"dc29c895fe3cf9556320bb102c97e7da12c87c61\", \"native_theme_unittests\": \"bec166df51bc434af2eeb40dfabd46f41d446857\", \"ozone_x11_unittests\": \"2a744b5857b33730e02d3ff872e4a05c3013dd42\", \"interactive_ui_tests\": \"3e83fdbc6083723d3bf4690d10afa240041f415f\", \"mojo_unittests\": \"0b60e4592bfa3a98c0a97bc2033ca0fce67aca9c\", \"url_unittests\": \"1453f3260e521ac8823011131bb2c36c007b7dc7\", \"google_apis_unittests\": \"af619b80fc371fd5777c3667180334cca062ee4c\", \"blink_heap_unittests\": \"81ff97c4926f993a1ffb70edc2fe554ac6c8f852\", \"base_unittests\": \"3c2f332f6f1b008cc18c5dc0bb385478e95908cd\", \"sync_integration_tests\": \"177cbae88721f9643a13eab3366a3b086bdfd83a\", \"sql_unittests\": \"4885adeef38c3a95b0ddeed8c1bb4c520ee0b7fd\", \"nacl_helper_nonsfi_unittests\": \"5a10d9ae58d14b850f042d3bd6f29407399a3cab\", \"keyboard_unittests\": \"ad42bf79e304dc96ab5ea9d0a5db7b1c455d2071\", \"blink_fuzzer_unittests\": \"f594cc742f52f7f75fd823eb088cf9183e1cc80e\", \"app_list_unittests\": \"9b5675a77c4307ebd660d958ced8f329d63a6a2c\", \"ipc_tests\": \"0c75d17b150e2b64dcdc9e42b4890536e5b5d4dc\", \"blink_platform_unittests\": \"b8aebd0a857c314fb8547af04099fb43f2eb8db5\", \"components_browsertests\": \"45a34312ba9878619a627a1f3dbb3c369920ff27\", \"media_unittests\": \"6233b40fcced322fb86b2a8b836caddf51ecee5b\", \"wtf_unittests\": \"d1bc7c204b6c6a2858b61f8ed22f74965c292755\", \"pdf_unittests\": \"a9794f8262c12baf08b5811176cacbdda62ea864\", \"skia_unittests\": \"bb7c487324cf4babd530a5aade0e65df780eabf2\", \"crypto_unittests\": \"c80ba6c63bc0b5b928f3650e4d410c98c7e8af02\", \"nacl_loader_unittests\": \"add5c0a36d0012ca104c7c4f3a55931a44c6d570\", \"unit_tests\": \"cff2154d1db3d09e36fa7bfe0bed8880137441c0\", \"sandbox_linux_unittests\": \"44010ede354f339b0c73da1309d6b6d8d8e6beb8\", \"content_browsertests\": \"fc17165a5ecfb7a83eed0141e3672554afda6b9e\", \"printing_unittests\": \"b7ee8bf7557035859282d4b361d0a4557d3232b8\", \"device_unittests\": \"77179174b325ba2ce2a8ed837b657f8887b461e8\"}, \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"linux-chromeos-dbg\", \"got_webrtc_revision\": \"d36c08623d9085dad821fbb1462a7fe03badcef5\", \"mastername\": \"chromium.chromiumos\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"got_v8_revision\": \"6641700a356c9a321198ea8beefcd9323a4da862\", \"got_v8_revision_cp\": \"refs/heads/7.4.282@{#1}\", \"got_angle_revision\": \"f094bac949922dabc3dd6944f3dec04111e953b6\", \"swarm_hashes_refs/heads/master(at){#637775}_without_patch\": {\"mojo_core_unittests\": \"e267d5e0a5d7e326748b6e63c66ea9b88bb70fad\", \"leveldb_service_unittests\": \"ec57cf7ad69c1c92089f1362798057b8842e9117\", \"storage_unittests\": \"e3ece589034b9e1a631ba3174488b68ffaa21b62\", \"jingle_unittests\": \"2228cf73b5dcca0e1fce8234d7f36d408af122b7\", \"angle_unittests\": \"fd77aaeb04bcc63d9667042711d70acdc3f0e346\", \"cast_unittests\": \"98ab06cf1d5467baf8e2a61d0248c6edec32d798\", \"blink_unittests\": \"b54b8e57a7a173f905417ca8fb0b63124ab7d547\", \"cc_unittests\": \"cf90287015edc53cb1d326a28bf64105cb6cf1d7\", \"blink_common_unittests\": \"7de51fe98d5c41b464ae3c9cc79906dd230a0b57\", \"chromeos_components_unittests\": \"2851ae9590ca3cecb4759a9a7d2b168497ad75ef\", \"shell_dialogs_unittests\": \"0e830af00360ac87b7d70c09d483c3b6b6b2ee39\", \"message_center_unittests\": \"439a68385f705691b5274bc7d9b2ddf45ecdc361\", \"ppapi_unittests\": \"872ed0b3f2c05f919f063725c3f9365e0ab378e6\", \"extensions_unittests\": \"4151be801191754e2fa28351bd53112e7b00ea47\", \"gl_unittests_ozone\": \"92eb463648a5a4a6b85183a317d53078fe743a84\", \"app_shell_unittests\": \"24f92ff0e333db5ed406f0a6de6275c3cbe2e98e\", \"chrome_app_unittests\": \"5b3484f8c7357dd0ef66d0aa76e960326082e5af\", \"gcm_unit_tests\": \"d0005e33637829297229320e67c2c3e3bcc38bee\", \"views_unittests\": \"e4894965523e1fb58cbe39577dea25255e6ac8c2\", \"ash_unittests\": \"a088e245637dd1c160a545853af78e6572d2188d\", \"ui_base_unittests\": \"c26e90ad27c56e12f582897950629828cd291cc3\", \"wm_unittests\": \"e306e1de2156b46e5802bf62fc95e01ead8e616a\", \"boringssl_ssl_tests\": \"18a2cd88554a61efb0c8212e200325a2b50a271f\", \"gpu_unittests\": \"4d3d2feee92f83c4c8cba6476b3c2c39ba35dc97\", \"extensions_browsertests\": \"c15475ddfe31e1e6389d3453c0c247a7d10c6344\", \"content_unittests\": \"87f24b1f0316772748849b2e11cb06ee8d3c7f0e\", \"ozone_gl_unittests\": \"2f8e1f15613eb2974c482bf6ca878ea04e753bf7\", \"libjingle_xmpp_unittests\": \"105bada07dc013f12e6be7b9afe0485c1e4699d2\", \"remoting_unittests\": \"fa5e923f0221ca98e3392a839b4e67ecfc5a665b\", \"filesystem_service_unittests\": \"2c9db6760c19195d8697e2c9de5191c4568f8eac\", \"gfx_unittests\": \"4a48bd99fb46c92bf71645d059a1f3a5a200ae60\", \"latency_unittests\": \"4c00a47f0f5dd3f6ad6a2e2f16842da8f72de1a0\", \"boringssl_crypto_tests\": \"d307ffbb933f9d2e0c545729a2b389426e1a2d04\", \"media_unittests\": \"6233b40fcced322fb86b2a8b836caddf51ecee5b\", \"chromedriver_unittests\": \"26b833afbb38b268fe53fa0a982882fbfa2e8868\", \"traffic_annotation_auditor_unittests\": \"5b588b6bd65af2ddfb93b311e79824407f3c9cf1\", \"chromeos_unittests\": \"a733de57cc2f09de6c6efd39fb4af61da70438df\", \"media_service_unittests\": \"3a0e6498a6af68a5c6e782690859f0c7336d529d\", \"compositor_unittests\": \"fe3fe3802a8ff4518efbead2a88b0e3167a6c7fb\", \"capture_unittests\": \"d525049c087c22195c09f2e0e2f1b65d8515ba8b\", \"ui_chromeos_unittests\": \"343a792923fb35751bfd157bfe7d1ae2a11e4e80\", \"service_manager_unittests\": \"52fe6a2f4ed477af7e4b702289af37f7b634fa76\", \"media_blink_unittests\": \"90cc9ab6cad3de6972089cd1218e939d7e6579df\", \"components_unittests\": \"04a04d24ad38d356567d33cea66fd82443d44bec\", \"snapshot_unittests\": \"b6f360ec455316ed0e5ea97973578b6f45de7c33\", \"accessibility_unittests\": \"fc03bdcde6932f9c030e9971847ebf68455aa135\", \"aura_unittests\": \"a85af2993b88e79a413e0c0589dce6f2a709a4e3\", \"services_unittests\": \"38c635007e97086d1983c518831f3b2a375d9912\", \"ozone_unittests\": \"f445e49034a1e7585723f810b4d0c1f6801657fe\", \"events_unittests\": \"b40bed1c934d7ae2d5f3e1334d8a89428e6ea012\", \"ui_touch_selection_unittests\": \"ff0ff3f49f0bf93dd9a823e6bd0bedc987556e59\", \"cacheinvalidation_unittests\": \"5b031a3c64aee8ee22bdb16efc7e51101542bd7f\", \"viz_unittests\": \"ace41c66e8af751d56f373c6714904f30b77a652\", \"browser_tests\": \"26f9475418614f87a8877408856b0ef9d2d52808\", \"net_unittests\": \"7eef49e2b9909f0c190f93b6b1b197b8bd06cd10\", \"midi_unittests\": \"ec45cf9ec17fe13ce1ee1d82c04733032655a7b0\", \"dbus_unittests\": \"8579f32acf6d28e6961c3dd6462e3920f8fb2dc4\", \"gin_unittests\": \"7a104a5ac9ef9940461448c73979f13a23a1a36a\", \"exo_unittests\": \"dc29c895fe3cf9556320bb102c97e7da12c87c61\", \"native_theme_unittests\": \"bec166df51bc434af2eeb40dfabd46f41d446857\", \"ozone_x11_unittests\": \"2a744b5857b33730e02d3ff872e4a05c3013dd42\", \"interactive_ui_tests\": \"3e83fdbc6083723d3bf4690d10afa240041f415f\", \"mojo_unittests\": \"0b60e4592bfa3a98c0a97bc2033ca0fce67aca9c\", \"url_unittests\": \"1453f3260e521ac8823011131bb2c36c007b7dc7\", \"google_apis_unittests\": \"af619b80fc371fd5777c3667180334cca062ee4c\", \"blink_heap_unittests\": \"81ff97c4926f993a1ffb70edc2fe554ac6c8f852\", \"base_unittests\": \"3c2f332f6f1b008cc18c5dc0bb385478e95908cd\", \"sync_integration_tests\": \"177cbae88721f9643a13eab3366a3b086bdfd83a\", \"sql_unittests\": \"4885adeef38c3a95b0ddeed8c1bb4c520ee0b7fd\", \"nacl_helper_nonsfi_unittests\": \"5a10d9ae58d14b850f042d3bd6f29407399a3cab\", \"keyboard_unittests\": \"ad42bf79e304dc96ab5ea9d0a5db7b1c455d2071\", \"blink_fuzzer_unittests\": \"f594cc742f52f7f75fd823eb088cf9183e1cc80e\", \"app_list_unittests\": \"9b5675a77c4307ebd660d958ced8f329d63a6a2c\", \"ipc_tests\": \"0c75d17b150e2b64dcdc9e42b4890536e5b5d4dc\", \"blink_platform_unittests\": \"b8aebd0a857c314fb8547af04099fb43f2eb8db5\", \"components_browsertests\": \"45a34312ba9878619a627a1f3dbb3c369920ff27\", \"display_unittests\": \"eac4da1c051fe96281a5d2408578ba21d8318329\", \"wtf_unittests\": \"d1bc7c204b6c6a2858b61f8ed22f74965c292755\", \"pdf_unittests\": \"a9794f8262c12baf08b5811176cacbdda62ea864\", \"skia_unittests\": \"bb7c487324cf4babd530a5aade0e65df780eabf2\", \"crypto_unittests\": \"c80ba6c63bc0b5b928f3650e4d410c98c7e8af02\", \"nacl_loader_unittests\": \"add5c0a36d0012ca104c7c4f3a55931a44c6d570\", \"unit_tests\": \"cff2154d1db3d09e36fa7bfe0bed8880137441c0\", \"sandbox_linux_unittests\": \"44010ede354f339b0c73da1309d6b6d8d8e6beb8\", \"content_browsertests\": \"fc17165a5ecfb7a83eed0141e3672554afda6b9e\", \"printing_unittests\": \"b7ee8bf7557035859282d4b361d0a4557d3232b8\", \"device_unittests\": \"77179174b325ba2ce2a8ed837b657f8887b461e8\"}, \"buildnumber\": 11209, \"path_config\": \"generic\", \"revision\": \"63182a5be6cf9502bf84c54a4a63ac4d8f448c52\", \"bot_id\": \"swarm1567-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551816917329761",
"status": "COMPLETED",
"status_changed_ts": "1551822764504853",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11209",
"builder:linux-chromeos-dbg",
"buildset:commit/git/63182a5be6cf9502bf84c54a4a63ac4d8f448c52",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63182a5be6cf9502bf84c54a4a63ac4d8f448c52",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085225843204294896",
"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/8919795780982080720/+/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:43684ff568c98310",
"user_agent:luci-scheduler"
],
"updated_ts": "1551822764507246",
"url": "https://ci.chromium.org/b/8919795780982080720",
"utcnow_ts": "1551822765193984"
}
}
[21:52:45.203] Invocation finished in 1h38m8.410042388s with status FAILED