[18:00:28.401] New invocation is queued and will start shortly
[18:00:29.497] Starting the invocation (attempt 1)
[18:00:29.539] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:00:29.539] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:00:29.539] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9081338613018673520",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"89aadba3d34c31fefcb8877173536b3f3e19d555\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9081338613018673520",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/89aadba3d34c31fefcb8877173536b3f3e19d555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89aadba3d34c31fefcb8877173536b3f3e19d555",
"gitiles_ref:refs/heads/master"
]
}
[18:00:30.844] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1555524029678097",
"id": "8915908551014225136",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"89aadba3d34c31fefcb8877173536b3f3e19d555\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555524030521897",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11920",
"builder:linux-chromeos-dbg",
"buildset:commit/git/89aadba3d34c31fefcb8877173536b3f3e19d555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89aadba3d34c31fefcb8877173536b3f3e19d555",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081338613018673520",
"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/8915908551014225136/+/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": "1555524030521988",
"url": "https://ci.chromium.org/b/8915908551014225136",
"utcnow_ts": "1555524030835641"
}
}
[18:00:30.844] Task URL: https://ci.chromium.org/b/8915908551014225136
[18:00:30.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:2:0) after 1m0s
[18:01:30.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:2:0)
[18:01:30.851] Timer tick, asking Buildbucket for the build status
[18:01:31.037] Build 8915908551014225136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:01:31.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:3:0) after 1m0s
[18:02:31.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:3:0)
[18:02:31.051] Timer tick, asking Buildbucket for the build status
[18:02:31.479] Build 8915908551014225136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:02:31.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:4:0) after 1m0s
[18:03:31.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:4:0)
[18:03:31.474] Timer tick, asking Buildbucket for the build status
[18:03:31.617] Build 8915908551014225136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:03:31.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:5:0) after 1m0s
[18:04:31.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:5:0)
[18:04:31.623] Timer tick, asking Buildbucket for the build status
[18:04:31.746] Build 8915908551014225136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:04:31.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:6:0) after 1m0s
[18:05:31.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:6:0)
[18:05:31.762] Timer tick, asking Buildbucket for the build status
[18:05:32.447] Build 8915908551014225136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:05:32.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:7:0) after 1m0s
[18:06:32.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:7:0)
[18:06:32.469] Timer tick, asking Buildbucket for the build status
[18:06:32.653] Build 8915908551014225136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:06:32.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:8:0) after 1m0s
[18:07:32.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:8:0)
[18:07:32.636] Timer tick, asking Buildbucket for the build status
[18:07:33.009] Build 8915908551014225136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:07:33.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:9:0) after 1m0s
[18:07:44.995] Received PubSub notification, asking Buildbucket for the build status
[18:07:45.143] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:33.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:9:0)
[18:08:33.005] Timer tick, asking Buildbucket for the build status
[18:08:33.268] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:33.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:11:0) after 1m0s
[18:09:33.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:11:0)
[18:09:33.242] Timer tick, asking Buildbucket for the build status
[18:09:33.357] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:33.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:12:0) after 1m0s
[18:10:33.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:12:0)
[18:10:33.377] Timer tick, asking Buildbucket for the build status
[18:10:34.071] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:34.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:13:0) after 1m0s
[18:11:34.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:13:0)
[18:11:34.057] Timer tick, asking Buildbucket for the build status
[18:11:34.175] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:34.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:14:0) after 1m0s
[18:12:34.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:14:0)
[18:12:34.204] Timer tick, asking Buildbucket for the build status
[18:12:34.370] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:34.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:15:0) after 1m0s
[18:13:34.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:15:0)
[18:13:34.392] Timer tick, asking Buildbucket for the build status
[18:13:34.540] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:34.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:16:0) after 1m0s
[18:14:34.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:16:0)
[18:14:34.560] Timer tick, asking Buildbucket for the build status
[18:14:35.408] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:35.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:17:0) after 1m0s
[18:15:35.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:17:0)
[18:15:35.384] Timer tick, asking Buildbucket for the build status
[18:15:35.532] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:35.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:18:0) after 1m0s
[18:16:35.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:18:0)
[18:16:35.537] Timer tick, asking Buildbucket for the build status
[18:16:35.673] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:35.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:19:0) after 1m0s
[18:17:35.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:19:0)
[18:17:35.682] Timer tick, asking Buildbucket for the build status
[18:17:35.786] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:35.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:20:0) after 1m0s
[18:18:35.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:20:0)
[18:18:35.805] Timer tick, asking Buildbucket for the build status
[18:18:36.324] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:36.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:21:0) after 1m0s
[18:19:36.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:21:0)
[18:19:36.308] Timer tick, asking Buildbucket for the build status
[18:19:36.692] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:36.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:22:0) after 1m0s
[18:20:36.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:22:0)
[18:20:36.729] Timer tick, asking Buildbucket for the build status
[18:20:36.846] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:36.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:23:0) after 1m0s
[18:21:36.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:23:0)
[18:21:36.852] Timer tick, asking Buildbucket for the build status
[18:21:37.383] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:37.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:24:0) after 1m0s
[18:22:37.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:24:0)
[18:22:37.386] Timer tick, asking Buildbucket for the build status
[18:22:37.574] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:37.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:25:0) after 1m0s
[18:23:37.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:25:0)
[18:23:37.572] Timer tick, asking Buildbucket for the build status
[18:23:37.923] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:37.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:26:0) after 1m0s
[18:24:37.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:26:0)
[18:24:37.921] Timer tick, asking Buildbucket for the build status
[18:24:38.011] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:38.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:27:0) after 1m0s
[18:25:38.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:27:0)
[18:25:38.009] Timer tick, asking Buildbucket for the build status
[18:25:38.120] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:38.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:28:0) after 1m0s
[18:26:38.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:28:0)
[18:26:38.123] Timer tick, asking Buildbucket for the build status
[18:26:38.243] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:38.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:29:0) after 1m0s
[18:27:38.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:29:0)
[18:27:38.263] Timer tick, asking Buildbucket for the build status
[18:27:38.383] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:38.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:30:0) after 1m0s
[18:28:38.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:30:0)
[18:28:38.405] Timer tick, asking Buildbucket for the build status
[18:28:38.494] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:38.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:31:0) after 1m0s
[18:29:38.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:31:0)
[18:29:38.601] Timer tick, asking Buildbucket for the build status
[18:29:38.723] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:38.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:32:0) after 1m0s
[18:30:38.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:32:0)
[18:30:38.751] Timer tick, asking Buildbucket for the build status
[18:30:39.023] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:39.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:33:0) after 1m0s
[18:31:39.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:33:0)
[18:31:39.058] Timer tick, asking Buildbucket for the build status
[18:31:39.372] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:39.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:34:0) after 1m0s
[18:32:39.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:34:0)
[18:32:39.387] Timer tick, asking Buildbucket for the build status
[18:32:40.276] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:40.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:35:0) after 1m0s
[18:33:40.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:35:0)
[18:33:40.296] Timer tick, asking Buildbucket for the build status
[18:33:40.498] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:40.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:36:0) after 1m0s
[18:34:40.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:36:0)
[18:34:40.485] Timer tick, asking Buildbucket for the build status
[18:34:40.967] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:40.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:37:0) after 1m0s
[18:35:41.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:37:0)
[18:35:41.054] Timer tick, asking Buildbucket for the build status
[18:35:41.160] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:41.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:38:0) after 1m0s
[18:36:41.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:38:0)
[18:36:41.223] Timer tick, asking Buildbucket for the build status
[18:36:41.587] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:41.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:39:0) after 1m0s
[18:37:41.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:39:0)
[18:37:41.609] Timer tick, asking Buildbucket for the build status
[18:37:41.909] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:41.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:40:0) after 1m0s
[18:38:41.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:40:0)
[18:38:41.911] Timer tick, asking Buildbucket for the build status
[18:38:42.043] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:42.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:41:0) after 1m0s
[18:39:42.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:41:0)
[18:39:42.028] Timer tick, asking Buildbucket for the build status
[18:39:42.542] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:42.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:42:0) after 1m0s
[18:40:42.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:42:0)
[18:40:42.566] Timer tick, asking Buildbucket for the build status
[18:40:42.680] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:42.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:43:0) after 1m0s
[18:41:42.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:43:0)
[18:41:42.730] Timer tick, asking Buildbucket for the build status
[18:41:42.856] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:42.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:44:0) after 1m0s
[18:42:42.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:44:0)
[18:42:42.875] Timer tick, asking Buildbucket for the build status
[18:42:42.981] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:42.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:45:0) after 1m0s
[18:43:42.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:45:0)
[18:43:42.974] Timer tick, asking Buildbucket for the build status
[18:43:43.083] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:43.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:46:0) after 1m0s
[18:44:43.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:46:0)
[18:44:43.115] Timer tick, asking Buildbucket for the build status
[18:44:43.867] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:43.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:47:0) after 1m0s
[18:45:43.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:47:0)
[18:45:43.876] Timer tick, asking Buildbucket for the build status
[18:45:44.984] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:44.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:48:0) after 1m0s
[18:46:44.982] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:48:0)
[18:46:44.982] Timer tick, asking Buildbucket for the build status
[18:46:45.167] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:45.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:49:0) after 1m0s
[18:47:45.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:49:0)
[18:47:45.186] Timer tick, asking Buildbucket for the build status
[18:47:45.384] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:45.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:50:0) after 1m0s
[18:48:45.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:50:0)
[18:48:45.405] Timer tick, asking Buildbucket for the build status
[18:48:46.797] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:46.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:51:0) after 1m0s
[18:49:46.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:51:0)
[18:49:46.818] Timer tick, asking Buildbucket for the build status
[18:49:47.560] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:47.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:52:0) after 1m0s
[18:50:47.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:52:0)
[18:50:47.573] Timer tick, asking Buildbucket for the build status
[18:50:47.711] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:47.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:53:0) after 1m0s
[18:51:47.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:53:0)
[18:51:47.735] Timer tick, asking Buildbucket for the build status
[18:51:48.000] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:48.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:54:0) after 1m0s
[18:52:48.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:54:0)
[18:52:48.055] Timer tick, asking Buildbucket for the build status
[18:52:49.724] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:49.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:55:0) after 1m0s
[18:53:49.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:55:0)
[18:53:49.709] Timer tick, asking Buildbucket for the build status
[18:53:50.097] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:50.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:56:0) after 1m0s
[18:54:50.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:56:0)
[18:54:50.120] Timer tick, asking Buildbucket for the build status
[18:54:50.331] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:50.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:57:0) after 1m0s
[18:55:50.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:57:0)
[18:55:50.358] Timer tick, asking Buildbucket for the build status
[18:55:51.728] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:51.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:58:0) after 1m0s
[18:56:51.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:58:0)
[18:56:51.746] Timer tick, asking Buildbucket for the build status
[18:56:52.271] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:52.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:59:0) after 1m0s
[18:57:52.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:59:0)
[18:57:52.270] Timer tick, asking Buildbucket for the build status
[18:57:53.272] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:53.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:60:0) after 1m0s
[18:58:53.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:60:0)
[18:58:53.267] Timer tick, asking Buildbucket for the build status
[18:58:53.495] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:53.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:61:0) after 1m0s
[18:59:53.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:61:0)
[18:59:53.484] Timer tick, asking Buildbucket for the build status
[18:59:53.632] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:53.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:62:0) after 1m0s
[19:00:53.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:62:0)
[19:00:53.619] Timer tick, asking Buildbucket for the build status
[19:00:53.786] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:53.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:63:0) after 1m0s
[19:01:53.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:63:0)
[19:01:53.814] Timer tick, asking Buildbucket for the build status
[19:01:54.292] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:54.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:64:0) after 1m0s
[19:02:54.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:64:0)
[19:02:54.318] Timer tick, asking Buildbucket for the build status
[19:02:54.648] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:54.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:65:0) after 1m0s
[19:03:54.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:65:0)
[19:03:54.693] Timer tick, asking Buildbucket for the build status
[19:03:54.911] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:54.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:66:0) after 1m0s
[19:04:54.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:66:0)
[19:04:54.922] Timer tick, asking Buildbucket for the build status
[19:04:55.498] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:55.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:67:0) after 1m0s
[19:05:55.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:67:0)
[19:05:55.514] Timer tick, asking Buildbucket for the build status
[19:05:56.061] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:56.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:68:0) after 1m0s
[19:06:56.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:68:0)
[19:06:56.092] Timer tick, asking Buildbucket for the build status
[19:06:56.214] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:56.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:69:0) after 1m0s
[19:07:56.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:69:0)
[19:07:56.206] Timer tick, asking Buildbucket for the build status
[19:07:56.393] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:56.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:70:0) after 1m0s
[19:08:56.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:70:0)
[19:08:56.418] Timer tick, asking Buildbucket for the build status
[19:08:56.526] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:56.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:71:0) after 1m0s
[19:09:56.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:71:0)
[19:09:56.510] Timer tick, asking Buildbucket for the build status
[19:09:56.665] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:56.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:72:0) after 1m0s
[19:10:56.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:72:0)
[19:10:56.662] Timer tick, asking Buildbucket for the build status
[19:10:57.083] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:57.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:73:0) after 1m0s
[19:11:57.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:73:0)
[19:11:57.104] Timer tick, asking Buildbucket for the build status
[19:11:57.471] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:57.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:74:0) after 1m0s
[19:12:57.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:74:0)
[19:12:57.510] Timer tick, asking Buildbucket for the build status
[19:12:57.674] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:57.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:75:0) after 1m0s
[19:13:57.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:75:0)
[19:13:57.698] Timer tick, asking Buildbucket for the build status
[19:13:57.891] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:57.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:76:0) after 1m0s
[19:14:57.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:76:0)
[19:14:57.912] Timer tick, asking Buildbucket for the build status
[19:14:58.005] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:58.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:77:0) after 1m0s
[19:15:58.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:77:0)
[19:15:58.028] Timer tick, asking Buildbucket for the build status
[19:15:58.147] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:58.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:78:0) after 1m0s
[19:16:58.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:78:0)
[19:16:58.169] Timer tick, asking Buildbucket for the build status
[19:16:58.806] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:58.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:79:0) after 1m0s
[19:17:58.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:79:0)
[19:17:58.801] Timer tick, asking Buildbucket for the build status
[19:17:58.938] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:58.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:80:0) after 1m0s
[19:18:58.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:80:0)
[19:18:58.916] Timer tick, asking Buildbucket for the build status
[19:18:59.207] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:59.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:81:0) after 1m0s
[19:19:59.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:81:0)
[19:19:59.223] Timer tick, asking Buildbucket for the build status
[19:19:59.333] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:59.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:82:0) after 1m0s
[19:20:59.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:82:0)
[19:20:59.352] Timer tick, asking Buildbucket for the build status
[19:20:59.501] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:59.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:83:0) after 1m0s
[19:21:59.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:83:0)
[19:21:59.527] Timer tick, asking Buildbucket for the build status
[19:21:59.700] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:59.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:84:0) after 1m0s
[19:22:59.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:84:0)
[19:22:59.726] Timer tick, asking Buildbucket for the build status
[19:22:59.981] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:59.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:85:0) after 1m0s
[19:23:59.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:85:0)
[19:23:59.963] Timer tick, asking Buildbucket for the build status
[19:24:00.197] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:00.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:86:0) after 1m0s
[19:25:00.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:86:0)
[19:25:00.204] Timer tick, asking Buildbucket for the build status
[19:25:00.395] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:00.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:87:0) after 1m0s
[19:26:00.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:87:0)
[19:26:00.426] Timer tick, asking Buildbucket for the build status
[19:26:01.081] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:01.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:88:0) after 1m0s
[19:27:01.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:88:0)
[19:27:01.132] Timer tick, asking Buildbucket for the build status
[19:27:02.446] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:02.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:89:0) after 1m0s
[19:28:02.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:89:0)
[19:28:02.440] Timer tick, asking Buildbucket for the build status
[19:28:03.173] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:03.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:90:0) after 1m0s
[19:29:03.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:90:0)
[19:29:03.158] Timer tick, asking Buildbucket for the build status
[19:29:03.392] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:03.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:91:0) after 1m0s
[19:30:03.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:91:0)
[19:30:03.418] Timer tick, asking Buildbucket for the build status
[19:30:03.674] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:03.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:92:0) after 1m0s
[19:31:03.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:92:0)
[19:31:03.696] Timer tick, asking Buildbucket for the build status
[19:31:03.840] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:03.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:93:0) after 1m0s
[19:32:03.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:93:0)
[19:32:03.860] Timer tick, asking Buildbucket for the build status
[19:32:04.444] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:04.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:94:0) after 1m0s
[19:33:04.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:94:0)
[19:33:04.466] Timer tick, asking Buildbucket for the build status
[19:33:05.130] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:05.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:95:0) after 1m0s
[19:34:05.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:95:0)
[19:34:05.189] Timer tick, asking Buildbucket for the build status
[19:34:05.832] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:05.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:96:0) after 1m0s
[19:35:05.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:96:0)
[19:35:05.826] Timer tick, asking Buildbucket for the build status
[19:35:05.933] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:05.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:97:0) after 1m0s
[19:36:05.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:97:0)
[19:36:05.929] Timer tick, asking Buildbucket for the build status
[19:36:06.037] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:06.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:98:0) after 1m0s
[19:37:06.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:98:0)
[19:37:06.085] Timer tick, asking Buildbucket for the build status
[19:37:06.704] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:06.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:99:0) after 1m0s
[19:38:06.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:99:0)
[19:38:06.689] Timer tick, asking Buildbucket for the build status
[19:38:06.967] Build 8915908551014225136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:06.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081338613018673520:100:0) after 1m0s
[19:38:40.814] Received PubSub notification, asking Buildbucket for the build status
[19:38:41.070] Build 8915908551014225136: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:38:41.070] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1555529919890258",
"created_by": "project:chromium",
"created_ts": "1555524029678097",
"failure_reason": "BUILD_FAILURE",
"id": "8915908551014225136",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"89aadba3d34c31fefcb8877173536b3f3e19d555\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 tests failed: ['non_network_service_browser_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\": 11920, \"got_angle_revision\": \"3b2c6bfd43536cf9ceca5d1303aa9435e67a432b\", \"got_dawn_revision\": \"0c133bbe554e98c55b88f3dd3fab7b885aa5abcb\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"89aadba3d34c31fefcb8877173536b3f3e19d555\", \"got_revision_cp\": \"refs/heads/master@{#651800}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"fd3e5e3a30df06b3f5ef428dab42c3d625ef2c0b\", \"got_v8_revision_cp\": \"refs/heads/7.5.286@{#1}\", \"got_webrtc_revision\": \"6f5e84894e74e62b221a06cd4d2d7aeaa2750f41\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27655}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"89aadba3d34c31fefcb8877173536b3f3e19d555\", \"swarm_hashes\": {\"accessibility_unittests\": \"6eb0ccdb2d9cb3c515046bd40c2bce40ea57a645\", \"angle_unittests\": \"8249a7b728a62f85ffa8c5b02c911b4ac581df64\", \"app_list_unittests\": \"98d13effc7c38ec1bfff986151d318321188ff73\", \"app_shell_unittests\": \"280ee17e8abaa5476629b45fc76bf35e3170cf0a\", \"ash_unittests\": \"f12c01da563e7cb188efe828a4717f7bddf50312\", \"aura_unittests\": \"ad3adb0a3c3bc62a6f3e683c0cf01d9793ba8075\", \"base_unittests\": \"72ea9a4cea3bd7a292bdbb6f027e85ccad3a2f3e\", \"blink_common_unittests\": \"4ee54d28c2a2cc3d00d32635be38d656c8ad20e3\", \"blink_fuzzer_unittests\": \"719f9313599265347ee928a7451d9154684e9d22\", \"blink_heap_unittests\": \"889c6b31ffef3435100dc9fbe3e8067ec778b9ee\", \"blink_platform_unittests\": \"2a0d6953e2dda50e4711a4133d4965e4c50c474a\", \"blink_unittests\": \"cfc3f60a8ac92c7171179b3e1b219d1966b788f1\", \"boringssl_crypto_tests\": \"14e4a1075f3851b55b9fdba0ba53b1c92172a4ce\", \"boringssl_ssl_tests\": \"65996e349833ec916b8e17054ee881ab824f4a0f\", \"browser_tests\": \"ca4a3e8b6bf0459f4e766a745d103c85355d8d96\", \"cacheinvalidation_unittests\": \"90965e878e5d58681eedd96b8ab8d0eadeadbc99\", \"capture_unittests\": \"9b2b30258ed85630f6c812976cac4eb46ab73ec6\", \"cast_unittests\": \"b24fe926c40e53ae86d6effb22cf6a91164d6e4e\", \"cc_unittests\": \"018fc489c360d6077fee572f6514849c9831dcb2\", \"chrome_app_unittests\": \"df463dee55c75cf4010de0c0a78dee45c8c698e3\", \"chromedriver_unittests\": \"f9eb1b48b223e0d6caf51bf7ee4af902a51209f2\", \"chromeos_components_unittests\": \"68115197d6c46df43f9b0ff69ec9f7398c712b08\", \"chromeos_unittests\": \"11176324ff60a695624f87b46c1d0691d82c46ff\", \"components_browsertests\": \"67400f8cce1e9b8ace4fe99bf1f8084e564aa691\", \"components_unittests\": \"1c0bf6ff95e50325e88b52708544063ce9ecb65f\", \"compositor_unittests\": \"86659f41335124a3db9a47ccdc894dc903531134\", \"content_browsertests\": \"8a197afb17a51284351d9d417ab320e272c153cc\", \"content_unittests\": \"f0c21cd8af36c4a5a8c1980a0ddae367f186852d\", \"crypto_unittests\": \"365a11714c44d36c344a9a75368a659dd53d8d26\", \"dbus_unittests\": \"b43c4eaf4a996995790af5288f785212d5ae40b0\", \"device_unittests\": \"10c58f4993a30bf4f94c20e943b00778866b92ef\", \"display_unittests\": \"70d9d0f96ede2d916248c25ad864466189e8767c\", \"events_unittests\": \"5ce9f05414a497bbf0a206d5fa8036406d6d7698\", \"exo_unittests\": \"de436281c9863bd793749ec9b587ccaf6484410e\", \"extensions_browsertests\": \"615104a43ac2868200751c903b4cb9e6a1ec1018\", \"extensions_unittests\": \"0f0d1fbab13e705288e1d584344ccb686974f930\", \"filesystem_service_unittests\": \"78806e083f4cb20f12e6ecfe1153b2caf664f7cb\", \"gcm_unit_tests\": \"9cbdbb2ab2bc9abd60e502975bf0e4de16c9d7ff\", \"gfx_unittests\": \"a85d6595c38e9095af672da1abfb7fe5c2c82e89\", \"gin_unittests\": \"578124134727e41c6243ea9bf77d9efe06185cc0\", \"gl_unittests_ozone\": \"8fde45a868ee7c968195a736de3681bb240c584b\", \"google_apis_unittests\": \"6c72388668e42a83c948fcc123c6ce76d0341345\", \"gpu_unittests\": \"a02ef828cd5036a22a783fd5100a16c73ec8f492\", \"interactive_ui_tests\": \"c3359021e164c8bf9ee7c823ca09dc0a4f084e78\", \"ipc_tests\": \"c7016bdf6c776bb84f275df37129d90edc489cb6\", \"jingle_unittests\": \"4fbb0e6dd3041e937669b7c0d0a1fe2eca917f06\", \"keyboard_unittests\": \"e6c43b3090ba69bae3a37dcbf370fa115b510a56\", \"latency_unittests\": \"3780cc5c02b559255233678ee373983e049dcbe9\", \"leveldb_service_unittests\": \"73eba7f02329702ace72b9014414ca107f7cd4bf\", \"libjingle_xmpp_unittests\": \"c82e9bcab94ac0fe945d5df41eeefec6ab489165\", \"media_blink_unittests\": \"732d547f50cd7ec49b8abf231e6c79b7adce544b\", \"media_service_unittests\": \"9d434c5e7b39cabd86af1c386e74432d05ebb031\", \"media_unittests\": \"63acb01ff94a8d624e8ba4c8fbbf6f188f10df86\", \"message_center_unittests\": \"ea83ddd13306a057914b88e1a96d1e88ce45c46e\", \"midi_unittests\": \"6921c4bbd28baf1f80b6ccce7b293da8d11403f7\", \"mojo_core_unittests\": \"4d74936052dbe835d1b9a7108700443bc9f68c4c\", \"mojo_unittests\": \"cb5c5301f3ab139ff9470aadcb874bf2634c5b39\", \"nacl_helper_nonsfi_unittests\": \"566ea6ec5ab3c479c54a0e1c901091c4274bd38c\", \"nacl_loader_unittests\": \"a5225d50588c23d0a7a203e5f97569b30504fb56\", \"native_theme_unittests\": \"871c0f17ad139ee747b79aca0f936c55e8a796ae\", \"net_unittests\": \"2bca65e9df4824c06f1e3b56486098c5aa622cd0\", \"ozone_gl_unittests\": \"f7c15d109732330c7e18d741ee4837916d951127\", \"ozone_unittests\": \"7420ed7afd18391f796820de5832e9c8867fd246\", \"ozone_x11_unittests\": \"53ccea3400b72b24b3de30efc74689597e906eaf\", \"pdf_unittests\": \"b0acf7f3ac1cbd17a072d340d6b9d49b89a64a7e\", \"perfetto_unittests\": \"97c0f7d7339038d38848370dfaeb5436a96adce8\", \"ppapi_unittests\": \"45e5a41b06cf04b6157dbfdc7f1998c27ce77e95\", \"printing_unittests\": \"8b7fb5a737021c6116b2fecc0dcb6189c53a920f\", \"remoting_unittests\": \"937f8853b78f8342eb01c7fbca3b99fdc7315281\", \"sandbox_linux_unittests\": \"9990c578f246b6d8d7e1df3a5f4b454d06ff9cac\", \"service_manager_unittests\": \"9d66573285d52598e5ad7859d2f491adcb38db5c\", \"services_unittests\": \"a3a5669c89c485631212e0f491e77cc67aad54ea\", \"shell_dialogs_unittests\": \"5a9bae32528b36e52758efbbfc8745f9449bc04c\", \"skia_unittests\": \"683be9c3dfdf57e43233572e23ab7586df888951\", \"snapshot_unittests\": \"5e11b095a2462efec3263bac866f3e45d7e0cf00\", \"sql_unittests\": \"980ce2a1bcd12a76fed306eba11f7825d23721e7\", \"storage_unittests\": \"28ebd2e476bf3c69a49396de2b1ca8e1e3eeb488\", \"sync_integration_tests\": \"4c70f9d93138e306e2c0196a41df2bfa165fbe6a\", \"traffic_annotation_auditor_unittests\": \"abf0ddf39748da4952bc0950293325bdd1724485\", \"ui_base_unittests\": \"af5a34653386aac74861a5e8c45b0ac6cdccbf53\", \"ui_chromeos_unittests\": \"e6c5d4fa7cc5f13a6396636c890ac78a63b225a5\", \"ui_touch_selection_unittests\": \"fd63bcc4d4726ce74e700382a29bc02ba0d224c5\", \"unit_tests\": \"be22883f55dc17288c2f43e300d7f709501e1bc9\", \"url_unittests\": \"5cbe6fe0608519e6486125b0b37a96547219626e\", \"usage_time_limit_unittests\": \"72ebf985ddbba1de17bea1805b4c4127bf93d9f1\", \"views_unittests\": \"b8a6c2b499231348b6165a0f68d39fcc475f5968\", \"viz_unittests\": \"9ec6d40013d7748f4de080619864a03a61cf23d5\", \"wm_unittests\": \"b752a01735e82675e74f7f7067e97295c117ba03\", \"wtf_unittests\": \"8bc8870eda03ce70665e40fa5cc9a7d9229200a5\"}, \"swarm_hashes_refs/heads/master(at){#651800}_without_patch\": {\"accessibility_unittests\": \"6eb0ccdb2d9cb3c515046bd40c2bce40ea57a645\", \"angle_unittests\": \"8249a7b728a62f85ffa8c5b02c911b4ac581df64\", \"app_list_unittests\": \"98d13effc7c38ec1bfff986151d318321188ff73\", \"app_shell_unittests\": \"280ee17e8abaa5476629b45fc76bf35e3170cf0a\", \"ash_unittests\": \"f12c01da563e7cb188efe828a4717f7bddf50312\", \"aura_unittests\": \"ad3adb0a3c3bc62a6f3e683c0cf01d9793ba8075\", \"base_unittests\": \"72ea9a4cea3bd7a292bdbb6f027e85ccad3a2f3e\", \"blink_common_unittests\": \"4ee54d28c2a2cc3d00d32635be38d656c8ad20e3\", \"blink_fuzzer_unittests\": \"719f9313599265347ee928a7451d9154684e9d22\", \"blink_heap_unittests\": \"889c6b31ffef3435100dc9fbe3e8067ec778b9ee\", \"blink_platform_unittests\": \"2a0d6953e2dda50e4711a4133d4965e4c50c474a\", \"blink_unittests\": \"cfc3f60a8ac92c7171179b3e1b219d1966b788f1\", \"boringssl_crypto_tests\": \"14e4a1075f3851b55b9fdba0ba53b1c92172a4ce\", \"boringssl_ssl_tests\": \"65996e349833ec916b8e17054ee881ab824f4a0f\", \"browser_tests\": \"ca4a3e8b6bf0459f4e766a745d103c85355d8d96\", \"cacheinvalidation_unittests\": \"90965e878e5d58681eedd96b8ab8d0eadeadbc99\", \"capture_unittests\": \"9b2b30258ed85630f6c812976cac4eb46ab73ec6\", \"cast_unittests\": \"b24fe926c40e53ae86d6effb22cf6a91164d6e4e\", \"cc_unittests\": \"018fc489c360d6077fee572f6514849c9831dcb2\", \"chrome_app_unittests\": \"df463dee55c75cf4010de0c0a78dee45c8c698e3\", \"chromedriver_unittests\": \"f9eb1b48b223e0d6caf51bf7ee4af902a51209f2\", \"chromeos_components_unittests\": \"68115197d6c46df43f9b0ff69ec9f7398c712b08\", \"chromeos_unittests\": \"11176324ff60a695624f87b46c1d0691d82c46ff\", \"components_browsertests\": \"67400f8cce1e9b8ace4fe99bf1f8084e564aa691\", \"components_unittests\": \"1c0bf6ff95e50325e88b52708544063ce9ecb65f\", \"compositor_unittests\": \"86659f41335124a3db9a47ccdc894dc903531134\", \"content_browsertests\": \"8a197afb17a51284351d9d417ab320e272c153cc\", \"content_unittests\": \"f0c21cd8af36c4a5a8c1980a0ddae367f186852d\", \"crypto_unittests\": \"365a11714c44d36c344a9a75368a659dd53d8d26\", \"dbus_unittests\": \"b43c4eaf4a996995790af5288f785212d5ae40b0\", \"device_unittests\": \"10c58f4993a30bf4f94c20e943b00778866b92ef\", \"display_unittests\": \"70d9d0f96ede2d916248c25ad864466189e8767c\", \"events_unittests\": \"5ce9f05414a497bbf0a206d5fa8036406d6d7698\", \"exo_unittests\": \"de436281c9863bd793749ec9b587ccaf6484410e\", \"extensions_browsertests\": \"615104a43ac2868200751c903b4cb9e6a1ec1018\", \"extensions_unittests\": \"0f0d1fbab13e705288e1d584344ccb686974f930\", \"filesystem_service_unittests\": \"78806e083f4cb20f12e6ecfe1153b2caf664f7cb\", \"gcm_unit_tests\": \"9cbdbb2ab2bc9abd60e502975bf0e4de16c9d7ff\", \"gfx_unittests\": \"a85d6595c38e9095af672da1abfb7fe5c2c82e89\", \"gin_unittests\": \"578124134727e41c6243ea9bf77d9efe06185cc0\", \"gl_unittests_ozone\": \"8fde45a868ee7c968195a736de3681bb240c584b\", \"google_apis_unittests\": \"6c72388668e42a83c948fcc123c6ce76d0341345\", \"gpu_unittests\": \"a02ef828cd5036a22a783fd5100a16c73ec8f492\", \"interactive_ui_tests\": \"c3359021e164c8bf9ee7c823ca09dc0a4f084e78\", \"ipc_tests\": \"c7016bdf6c776bb84f275df37129d90edc489cb6\", \"jingle_unittests\": \"4fbb0e6dd3041e937669b7c0d0a1fe2eca917f06\", \"keyboard_unittests\": \"e6c43b3090ba69bae3a37dcbf370fa115b510a56\", \"latency_unittests\": \"3780cc5c02b559255233678ee373983e049dcbe9\", \"leveldb_service_unittests\": \"73eba7f02329702ace72b9014414ca107f7cd4bf\", \"libjingle_xmpp_unittests\": \"c82e9bcab94ac0fe945d5df41eeefec6ab489165\", \"media_blink_unittests\": \"732d547f50cd7ec49b8abf231e6c79b7adce544b\", \"media_service_unittests\": \"9d434c5e7b39cabd86af1c386e74432d05ebb031\", \"media_unittests\": \"63acb01ff94a8d624e8ba4c8fbbf6f188f10df86\", \"message_center_unittests\": \"ea83ddd13306a057914b88e1a96d1e88ce45c46e\", \"midi_unittests\": \"6921c4bbd28baf1f80b6ccce7b293da8d11403f7\", \"mojo_core_unittests\": \"4d74936052dbe835d1b9a7108700443bc9f68c4c\", \"mojo_unittests\": \"cb5c5301f3ab139ff9470aadcb874bf2634c5b39\", \"nacl_helper_nonsfi_unittests\": \"566ea6ec5ab3c479c54a0e1c901091c4274bd38c\", \"nacl_loader_unittests\": \"a5225d50588c23d0a7a203e5f97569b30504fb56\", \"native_theme_unittests\": \"871c0f17ad139ee747b79aca0f936c55e8a796ae\", \"net_unittests\": \"2bca65e9df4824c06f1e3b56486098c5aa622cd0\", \"ozone_gl_unittests\": \"f7c15d109732330c7e18d741ee4837916d951127\", \"ozone_unittests\": \"7420ed7afd18391f796820de5832e9c8867fd246\", \"ozone_x11_unittests\": \"53ccea3400b72b24b3de30efc74689597e906eaf\", \"pdf_unittests\": \"b0acf7f3ac1cbd17a072d340d6b9d49b89a64a7e\", \"perfetto_unittests\": \"97c0f7d7339038d38848370dfaeb5436a96adce8\", \"ppapi_unittests\": \"45e5a41b06cf04b6157dbfdc7f1998c27ce77e95\", \"printing_unittests\": \"8b7fb5a737021c6116b2fecc0dcb6189c53a920f\", \"remoting_unittests\": \"937f8853b78f8342eb01c7fbca3b99fdc7315281\", \"sandbox_linux_unittests\": \"9990c578f246b6d8d7e1df3a5f4b454d06ff9cac\", \"service_manager_unittests\": \"9d66573285d52598e5ad7859d2f491adcb38db5c\", \"services_unittests\": \"a3a5669c89c485631212e0f491e77cc67aad54ea\", \"shell_dialogs_unittests\": \"5a9bae32528b36e52758efbbfc8745f9449bc04c\", \"skia_unittests\": \"683be9c3dfdf57e43233572e23ab7586df888951\", \"snapshot_unittests\": \"5e11b095a2462efec3263bac866f3e45d7e0cf00\", \"sql_unittests\": \"980ce2a1bcd12a76fed306eba11f7825d23721e7\", \"storage_unittests\": \"28ebd2e476bf3c69a49396de2b1ca8e1e3eeb488\", \"sync_integration_tests\": \"4c70f9d93138e306e2c0196a41df2bfa165fbe6a\", \"traffic_annotation_auditor_unittests\": \"abf0ddf39748da4952bc0950293325bdd1724485\", \"ui_base_unittests\": \"af5a34653386aac74861a5e8c45b0ac6cdccbf53\", \"ui_chromeos_unittests\": \"e6c5d4fa7cc5f13a6396636c890ac78a63b225a5\", \"ui_touch_selection_unittests\": \"fd63bcc4d4726ce74e700382a29bc02ba0d224c5\", \"unit_tests\": \"be22883f55dc17288c2f43e300d7f709501e1bc9\", \"url_unittests\": \"5cbe6fe0608519e6486125b0b37a96547219626e\", \"usage_time_limit_unittests\": \"72ebf985ddbba1de17bea1805b4c4127bf93d9f1\", \"views_unittests\": \"b8a6c2b499231348b6165a0f68d39fcc475f5968\", \"viz_unittests\": \"9ec6d40013d7748f4de080619864a03a61cf23d5\", \"wm_unittests\": \"b752a01735e82675e74f7f7067e97295c117ba03\", \"wtf_unittests\": \"8bc8870eda03ce70665e40fa5cc9a7d9229200a5\"}}, \"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\": [\"4260-fb32526\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 tests failed: ['non_network_service_browser_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1555524463246267",
"status": "COMPLETED",
"status_changed_ts": "1555524464610804",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11920",
"builder:linux-chromeos-dbg",
"buildset:commit/git/89aadba3d34c31fefcb8877173536b3f3e19d555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89aadba3d34c31fefcb8877173536b3f3e19d555",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081338613018673520",
"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/8915908551014225136/+/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:44454699f3a19a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555529920300678",
"url": "https://ci.chromium.org/b/8915908551014225136",
"utcnow_ts": "1555529921058860"
}
}
[19:38:41.070] Invocation finished in 1h38m12.687453529s with status FAILED