[11:13:58.573] New invocation is queued and will start shortly
[11:13:59.653] Starting the invocation (attempt 1)
[11:13:59.702] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:13:59.702] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:13:59.702] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078283890748050208",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5e4ae9cbd14308fc107017367a815976168c785c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9078283890748050208",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/5e4ae9cbd14308fc107017367a815976168c785c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e4ae9cbd14308fc107017367a815976168c785c",
"gitiles_ref:refs/heads/master"
]
}
[11:14:00.783] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558437239898776",
"id": "8912853828695155520",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5e4ae9cbd14308fc107017367a815976168c785c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558437240655617",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12500",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5e4ae9cbd14308fc107017367a815976168c785c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e4ae9cbd14308fc107017367a815976168c785c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078283890748050208",
"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/8912853828695155520/+/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": "1558437240655710",
"url": "https://ci.chromium.org/b/8912853828695155520",
"utcnow_ts": "1558437240772091"
}
}
[11:14:00.783] Task URL: https://ci.chromium.org/b/8912853828695155520
[11:14:00.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:2:0) after 1m0s
[11:15:00.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:2:0)
[11:15:00.784] Timer tick, asking Buildbucket for the build status
[11:15:01.401] Build 8912853828695155520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:15:01.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:3:0) after 1m0s
[11:15:36.326] Received PubSub notification, asking Buildbucket for the build status
[11:15:36.429] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:01.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:3:0)
[11:16:01.424] Timer tick, asking Buildbucket for the build status
[11:16:02.569] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:02.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:5:0) after 1m0s
[11:17:02.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:5:0)
[11:17:02.630] Timer tick, asking Buildbucket for the build status
[11:17:06.651] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:06.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:6:0) after 1m0s
[11:18:06.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:6:0)
[11:18:06.672] Timer tick, asking Buildbucket for the build status
[11:18:07.527] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:07.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:7:0) after 1m0s
[11:19:07.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:7:0)
[11:19:07.547] Timer tick, asking Buildbucket for the build status
[11:19:07.741] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:07.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:8:0) after 1m0s
[11:20:07.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:8:0)
[11:20:07.761] Timer tick, asking Buildbucket for the build status
[11:20:08.365] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:08.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:9:0) after 1m0s
[11:21:08.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:9:0)
[11:21:08.525] Timer tick, asking Buildbucket for the build status
[11:21:10.195] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:10.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:10:0) after 1m0s
[11:22:10.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:10:0)
[11:22:10.275] Timer tick, asking Buildbucket for the build status
[11:22:10.665] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:10.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:11:0) after 1m0s
[11:23:10.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:11:0)
[11:23:10.710] Timer tick, asking Buildbucket for the build status
[11:23:11.901] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:11.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:12:0) after 1m0s
[11:24:11.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:12:0)
[11:24:11.928] Timer tick, asking Buildbucket for the build status
[11:24:13.202] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:13.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:13:0) after 1m0s
[11:25:13.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:13:0)
[11:25:13.183] Timer tick, asking Buildbucket for the build status
[11:25:13.400] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:13.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:14:0) after 1m0s
[11:26:13.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:14:0)
[11:26:13.403] Timer tick, asking Buildbucket for the build status
[11:26:14.296] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:14.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:15:0) after 1m0s
[11:27:14.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:15:0)
[11:27:14.330] Timer tick, asking Buildbucket for the build status
[11:27:14.591] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:14.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:16:0) after 1m0s
[11:28:14.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:16:0)
[11:28:14.603] Timer tick, asking Buildbucket for the build status
[11:28:14.727] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:14.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:17:0) after 1m0s
[11:29:14.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:17:0)
[11:29:14.733] Timer tick, asking Buildbucket for the build status
[11:29:15.387] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:15.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:18:0) after 1m0s
[11:30:15.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:18:0)
[11:30:15.415] Timer tick, asking Buildbucket for the build status
[11:30:15.830] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:15.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:19:0) after 1m0s
[11:31:15.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:19:0)
[11:31:15.921] Timer tick, asking Buildbucket for the build status
[11:31:16.149] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:16.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:20:0) after 1m0s
[11:32:16.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:20:0)
[11:32:16.711] Timer tick, asking Buildbucket for the build status
[11:32:16.890] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:16.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:21:0) after 1m0s
[11:33:16.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:21:0)
[11:33:16.918] Timer tick, asking Buildbucket for the build status
[11:33:18.854] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:18.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:22:0) after 1m0s
[11:34:18.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:22:0)
[11:34:18.830] Timer tick, asking Buildbucket for the build status
[11:34:19.018] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:19.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:23:0) after 1m0s
[11:35:19.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:23:0)
[11:35:19.108] Timer tick, asking Buildbucket for the build status
[11:35:19.201] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:19.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:24:0) after 1m0s
[11:36:19.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:24:0)
[11:36:19.211] Timer tick, asking Buildbucket for the build status
[11:36:19.883] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:19.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:25:0) after 1m0s
[11:37:19.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:25:0)
[11:37:19.907] Timer tick, asking Buildbucket for the build status
[11:37:20.068] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:20.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:26:0) after 1m0s
[11:38:20.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:26:0)
[11:38:20.118] Timer tick, asking Buildbucket for the build status
[11:38:20.903] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:20.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:27:0) after 1m0s
[11:39:21.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:27:0)
[11:39:21.866] Timer tick, asking Buildbucket for the build status
[11:39:22.366] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:22.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:28:0) after 1m0s
[11:40:22.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:28:0)
[11:40:22.367] Timer tick, asking Buildbucket for the build status
[11:40:22.496] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:22.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:29:0) after 1m0s
[11:41:22.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:29:0)
[11:41:22.524] Timer tick, asking Buildbucket for the build status
[11:41:24.472] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:24.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:30:0) after 1m0s
[11:42:25.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:30:0)
[11:42:25.121] Timer tick, asking Buildbucket for the build status
[11:42:25.238] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:25.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:31:0) after 1m0s
[11:43:25.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:31:0)
[11:43:25.295] Timer tick, asking Buildbucket for the build status
[11:43:25.524] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:25.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:32:0) after 1m0s
[11:44:25.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:32:0)
[11:44:25.538] Timer tick, asking Buildbucket for the build status
[11:44:26.921] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:26.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:33:0) after 1m0s
[11:45:26.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:33:0)
[11:45:26.904] Timer tick, asking Buildbucket for the build status
[11:45:27.107] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:27.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:34:0) after 1m0s
[11:46:27.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:34:0)
[11:46:27.089] Timer tick, asking Buildbucket for the build status
[11:46:27.329] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:27.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:35:0) after 1m0s
[11:47:27.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:35:0)
[11:47:27.321] Timer tick, asking Buildbucket for the build status
[11:47:27.451] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:27.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:36:0) after 1m0s
[11:48:27.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:36:0)
[11:48:27.477] Timer tick, asking Buildbucket for the build status
[11:48:28.077] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:28.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:37:0) after 1m0s
[11:49:28.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:37:0)
[11:49:28.145] Timer tick, asking Buildbucket for the build status
[11:49:28.980] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:28.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:38:0) after 1m0s
[11:50:28.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:38:0)
[11:50:28.988] Timer tick, asking Buildbucket for the build status
[11:50:29.167] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:29.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:39:0) after 1m0s
[11:51:29.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:39:0)
[11:51:29.243] Timer tick, asking Buildbucket for the build status
[11:51:32.313] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:32.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:40:0) after 1m0s
[11:52:32.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:40:0)
[11:52:32.308] Timer tick, asking Buildbucket for the build status
[11:52:33.052] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:33.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:41:0) after 1m0s
[11:53:33.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:41:0)
[11:53:33.064] Timer tick, asking Buildbucket for the build status
[11:53:33.402] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:33.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:42:0) after 1m0s
[11:54:33.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:42:0)
[11:54:33.401] Timer tick, asking Buildbucket for the build status
[11:54:33.716] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:33.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:43:0) after 1m0s
[11:55:33.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:43:0)
[11:55:33.730] Timer tick, asking Buildbucket for the build status
[11:55:35.436] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:35.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:44:0) after 1m0s
[11:56:35.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:44:0)
[11:56:35.451] Timer tick, asking Buildbucket for the build status
[11:56:37.475] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:37.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:45:0) after 1m0s
[11:57:37.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:45:0)
[11:57:37.503] Timer tick, asking Buildbucket for the build status
[11:57:37.896] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:37.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:46:0) after 1m0s
[11:58:37.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:46:0)
[11:58:37.917] Timer tick, asking Buildbucket for the build status
[11:58:38.099] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:38.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:47:0) after 1m0s
[11:59:38.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:47:0)
[11:59:38.131] Timer tick, asking Buildbucket for the build status
[11:59:40.242] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:40.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:48:0) after 1m0s
[12:00:40.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:48:0)
[12:00:40.273] Timer tick, asking Buildbucket for the build status
[12:00:40.751] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:40.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:49:0) after 1m0s
[12:01:43.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:49:0)
[12:01:43.902] Timer tick, asking Buildbucket for the build status
[12:01:47.258] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:47.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:50:0) after 1m0s
[12:02:47.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:50:0)
[12:02:47.271] Timer tick, asking Buildbucket for the build status
[12:02:49.603] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:49.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:51:0) after 1m0s
[12:03:49.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:51:0)
[12:03:49.616] Timer tick, asking Buildbucket for the build status
[12:03:49.888] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:49.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:52:0) after 1m0s
[12:04:49.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:52:0)
[12:04:49.909] Timer tick, asking Buildbucket for the build status
[12:04:50.282] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:50.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:53:0) after 1m0s
[12:05:50.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:53:0)
[12:05:50.310] Timer tick, asking Buildbucket for the build status
[12:05:51.554] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:51.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:54:0) after 1m0s
[12:06:51.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:54:0)
[12:06:51.548] Timer tick, asking Buildbucket for the build status
[12:06:53.681] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:53.681] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:55:0) after 1m0s
[12:07:53.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:55:0)
[12:07:53.692] Timer tick, asking Buildbucket for the build status
[12:07:54.756] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:54.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:56:0) after 1m0s
[12:08:54.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:56:0)
[12:08:54.871] Timer tick, asking Buildbucket for the build status
[12:08:55.427] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:55.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:57:0) after 1m0s
[12:09:55.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:57:0)
[12:09:55.446] Timer tick, asking Buildbucket for the build status
[12:09:55.595] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:55.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:58:0) after 1m0s
[12:10:55.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:58:0)
[12:10:55.606] Timer tick, asking Buildbucket for the build status
[12:10:56.332] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:56.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:59:0) after 1m0s
[12:11:56.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:59:0)
[12:11:56.316] Timer tick, asking Buildbucket for the build status
[12:11:56.609] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:56.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:60:0) after 1m0s
[12:12:56.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:60:0)
[12:12:56.654] Timer tick, asking Buildbucket for the build status
[12:12:56.886] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:56.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:61:0) after 1m0s
[12:13:56.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:61:0)
[12:13:56.913] Timer tick, asking Buildbucket for the build status
[12:14:02.656] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:02.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:62:0) after 1m0s
[12:15:10.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:62:0)
[12:15:10.832] Timer tick, asking Buildbucket for the build status
[12:15:12.025] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:12.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:63:0) after 1m0s
[12:16:12.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:63:0)
[12:16:12.059] Timer tick, asking Buildbucket for the build status
[12:16:12.168] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:12.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:64:0) after 1m0s
[12:17:12.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:64:0)
[12:17:12.198] Timer tick, asking Buildbucket for the build status
[12:17:12.997] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:12.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:65:0) after 1m0s
[12:18:13.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:65:0)
[12:18:13.037] Timer tick, asking Buildbucket for the build status
[12:18:13.672] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:13.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:66:0) after 1m0s
[12:19:13.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:66:0)
[12:19:13.654] Timer tick, asking Buildbucket for the build status
[12:19:14.304] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:14.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:67:0) after 1m0s
[12:20:14.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:67:0)
[12:20:14.302] Timer tick, asking Buildbucket for the build status
[12:20:14.888] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:14.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:68:0) after 1m0s
[12:21:14.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:68:0)
[12:21:14.922] Timer tick, asking Buildbucket for the build status
[12:21:17.216] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:17.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:69:0) after 1m0s
[12:22:17.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:69:0)
[12:22:17.234] Timer tick, asking Buildbucket for the build status
[12:22:17.978] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:17.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:70:0) after 1m0s
[12:23:18.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:70:0)
[12:23:18.051] Timer tick, asking Buildbucket for the build status
[12:23:18.357] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:18.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:71:0) after 1m0s
[12:24:18.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:71:0)
[12:24:18.414] Timer tick, asking Buildbucket for the build status
[12:24:18.553] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:18.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:72:0) after 1m0s
[12:25:18.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:72:0)
[12:25:18.574] Timer tick, asking Buildbucket for the build status
[12:25:19.264] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:19.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:73:0) after 1m0s
[12:26:19.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:73:0)
[12:26:19.282] Timer tick, asking Buildbucket for the build status
[12:26:19.764] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:19.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:74:0) after 1m0s
[12:27:19.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:74:0)
[12:27:19.804] Timer tick, asking Buildbucket for the build status
[12:27:19.991] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:19.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:75:0) after 1m0s
[12:28:19.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:75:0)
[12:28:19.979] Timer tick, asking Buildbucket for the build status
[12:28:20.259] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:20.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:76:0) after 1m0s
[12:29:20.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:76:0)
[12:29:20.281] Timer tick, asking Buildbucket for the build status
[12:29:20.481] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:20.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:77:0) after 1m0s
[12:30:20.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:77:0)
[12:30:20.476] Timer tick, asking Buildbucket for the build status
[12:30:20.915] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:20.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:78:0) after 1m0s
[12:31:20.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:78:0)
[12:31:20.957] Timer tick, asking Buildbucket for the build status
[12:31:21.686] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:21.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:79:0) after 1m0s
[12:32:21.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:79:0)
[12:32:21.709] Timer tick, asking Buildbucket for the build status
[12:32:22.170] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:22.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:80:0) after 1m0s
[12:33:22.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:80:0)
[12:33:22.222] Timer tick, asking Buildbucket for the build status
[12:33:22.416] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:22.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:81:0) after 1m0s
[12:34:22.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:81:0)
[12:34:22.466] Timer tick, asking Buildbucket for the build status
[12:34:22.966] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:22.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:82:0) after 1m0s
[12:35:22.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:82:0)
[12:35:22.984] Timer tick, asking Buildbucket for the build status
[12:35:23.409] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:23.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:83:0) after 1m0s
[12:36:23.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:83:0)
[12:36:23.446] Timer tick, asking Buildbucket for the build status
[12:36:24.073] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:24.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:84:0) after 1m0s
[12:37:24.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:84:0)
[12:37:24.117] Timer tick, asking Buildbucket for the build status
[12:37:24.864] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:24.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:85:0) after 1m0s
[12:38:24.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:85:0)
[12:38:24.893] Timer tick, asking Buildbucket for the build status
[12:38:26.519] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:26.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:86:0) after 1m0s
[12:39:26.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:86:0)
[12:39:26.500] Timer tick, asking Buildbucket for the build status
[12:39:27.839] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:27.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:87:0) after 1m0s
[12:40:27.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:87:0)
[12:40:27.868] Timer tick, asking Buildbucket for the build status
[12:40:28.103] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:88:0) after 1m0s
[12:41:28.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:88:0)
[12:41:28.148] Timer tick, asking Buildbucket for the build status
[12:41:30.492] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:30.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:89:0) after 1m0s
[12:42:30.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:89:0)
[12:42:30.533] Timer tick, asking Buildbucket for the build status
[12:42:34.078] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:34.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:90:0) after 1m0s
[12:43:34.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:90:0)
[12:43:34.111] Timer tick, asking Buildbucket for the build status
[12:43:34.572] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:34.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:91:0) after 1m0s
[12:44:34.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:91:0)
[12:44:34.550] Timer tick, asking Buildbucket for the build status
[12:44:34.663] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:34.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:92:0) after 1m0s
[12:45:34.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:92:0)
[12:45:34.654] Timer tick, asking Buildbucket for the build status
[12:45:34.799] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:34.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:93:0) after 1m0s
[12:46:34.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:93:0)
[12:46:34.839] Timer tick, asking Buildbucket for the build status
[12:46:36.873] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:36.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:94:0) after 1m0s
[12:47:36.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:94:0)
[12:47:36.970] Timer tick, asking Buildbucket for the build status
[12:47:38.145] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:38.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:95:0) after 1m0s
[12:48:38.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:95:0)
[12:48:38.155] Timer tick, asking Buildbucket for the build status
[12:48:38.327] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:38.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:96:0) after 1m0s
[12:49:38.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:96:0)
[12:49:38.396] Timer tick, asking Buildbucket for the build status
[12:49:38.868] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:38.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:97:0) after 1m0s
[12:50:38.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:97:0)
[12:50:38.894] Timer tick, asking Buildbucket for the build status
[12:50:39.016] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:39.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:98:0) after 1m0s
[12:51:39.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:98:0)
[12:51:39.047] Timer tick, asking Buildbucket for the build status
[12:51:39.652] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:39.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:99:0) after 1m0s
[12:52:39.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:99:0)
[12:52:39.692] Timer tick, asking Buildbucket for the build status
[12:52:42.135] Build 8912853828695155520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:42.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078283890748050208:100:0) after 1m0s
[12:52:49.068] Received PubSub notification, asking Buildbucket for the build status
[12:52:50.832] Build 8912853828695155520: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:52:50.832] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558443168042315",
"created_by": "project:chromium",
"created_ts": "1558437239898776",
"failure_reason": "BUILD_FAILURE",
"id": "8912853828695155520",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5e4ae9cbd14308fc107017367a815976168c785c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 tests failed: ['non_network_service_browser_tests', 'browser_tests', 'viz_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\": 12500, \"got_angle_revision\": \"dc4f4a5b1582354826eede8e5b9c072b7b7697ee\", \"got_dawn_revision\": \"2a7b631482d8cb167d5651be130b08549b4dedc8\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"5e4ae9cbd14308fc107017367a815976168c785c\", \"got_revision_cp\": \"refs/heads/master@{#661690}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"69fc6ea24de35d2e4d31c16810a09e377ed0a916\", \"got_v8_revision_cp\": \"refs/heads/7.6.229@{#1}\", \"got_webrtc_revision\": \"e8602067db5dfbf3c2d05d22b8b8a9e1325229d2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27999}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5e4ae9cbd14308fc107017367a815976168c785c\", \"swarm_hashes\": {\"accessibility_unittests\": \"4e2a8336c66c484d83dba2e5bb5794940f5c905c\", \"angle_unittests\": \"4bdda2b082c86a2441fd1c242b3ff7f0c5ac300b\", \"app_list_unittests\": \"174dba7e0bc5bc0be3b8095353ff0bf1d8890e8e\", \"app_shell_unittests\": \"4c131e6c15c88cde0d5cd911d103eed915a02212\", \"ash_unittests\": \"09ae58c38ef528a4f9d15a6396760a213791f843\", \"aura_unittests\": \"b2b3008f39f036773a4712b1eb42b2848c2b8b40\", \"base_unittests\": \"5c6edccc757bc139ef074f58ac34626bdf494c02\", \"base_util_unittests\": \"c25cf049f49165b5d508f65ac5728434d1de7210\", \"blink_common_unittests\": \"138fe1793d0d9cb403a455b9c3e656091fb8b660\", \"blink_fuzzer_unittests\": \"37d030c02aca72f783af682910f0c653457153b7\", \"blink_heap_unittests\": \"303f488e588c855cbeca1b557a34e9f582df21df\", \"blink_platform_unittests\": \"ce4cb29426fe5101ca4918eb4b21e76a25a4c009\", \"blink_unittests\": \"e485b81f87eaf1d170bb95020346c25278240b26\", \"boringssl_crypto_tests\": \"f9cc299cdf000b7331641163508d04d263415e0a\", \"boringssl_ssl_tests\": \"a402d75a7619d8909b41c96523c0a644d9cb37b9\", \"browser_tests\": \"6c05bcdeac6d7cdce58e9160ad06caab14189bb5\", \"cacheinvalidation_unittests\": \"2eb1dcfc10b61e68844471c5861dcd70149519cc\", \"capture_unittests\": \"58641f1db7a3e1bc7f5b26c1b8acca3381c8d764\", \"cast_unittests\": \"70326253810d155c4837e22235e47c6731539c6c\", \"cc_unittests\": \"28362511b712411dc06e22faa539971c64dabd7d\", \"chrome_app_unittests\": \"3bbac457bd03db9ee8cce0455d2581ec499afbaf\", \"chromedriver_unittests\": \"a5fc75a7952b9a5e6be4ae10626dd5f98ca1a780\", \"chromeos_components_unittests\": \"8c771bfce96f3cbbd15218b68fc37da3561244c4\", \"chromeos_unittests\": \"8756dc4e98013f829ff9898ba2b996568fe146a2\", \"components_browsertests\": \"c377a2d9aa4335d6d4dad04c67a467fc8dd25ddb\", \"components_unittests\": \"5f024fdb25843c1ee20b17f2cdc872899a507777\", \"compositor_unittests\": \"8f572db169433ca4269f64deaa9c3a4498345a36\", \"content_browsertests\": \"f9ec8fc6b7aeef6739f248f6729a684ea8ae4811\", \"content_unittests\": \"0c3abb45352f867722fb45d6217fc58fa9639ff4\", \"crypto_unittests\": \"f320a814b67a7e7f3b8f3a8a8de4f39bb9c20952\", \"dbus_unittests\": \"6a6550e729cbdb98cfe48f3aa9c91e65892a7f64\", \"device_unittests\": \"9fda01453b2d9a79127f19b708a1eea7a63d2332\", \"display_unittests\": \"0b4e192893e18a16ac34906031fa18f6faf0a798\", \"events_unittests\": \"e3b64f11ad40bc0756a8f40cbc7c392f132883c3\", \"exo_unittests\": \"309c3dabcc76eccb201417c9f46a8cd09e26fae3\", \"extensions_browsertests\": \"01a0267bceec21535c16fe6901d28de411b0be5c\", \"extensions_unittests\": \"9e7a30f3305f523dbaec7b2e8c8ba24a91de4f57\", \"filesystem_service_unittests\": \"637e6b631ed39ef01904fb725131b0d568804c2f\", \"gcm_unit_tests\": \"ea9907d5245dbb11b408b136ddcacda7b39bc30d\", \"gfx_unittests\": \"6699edfd4fc233ad471c5e878addf2eda7a34be1\", \"gin_unittests\": \"128744f30e79a1325ca0282ac8eeec49e11d0749\", \"gl_unittests_ozone\": \"e4fdad88dfe5e80d1938290a1ef1a6616b8e72e5\", \"google_apis_unittests\": \"1d21b5ed771dfac6301444a5eaa0248b22ad6868\", \"gpu_unittests\": \"2c12554867d63a18701c0fb788bba74e45f6ff9b\", \"interactive_ui_tests\": \"5aa859b83084b22abc5349335e1ab6bf41c96e1d\", \"ipc_tests\": \"362cffac248147219ddc8a98fd9118cf9c28ca69\", \"jingle_unittests\": \"768813413630249bd6ba3379d73f5f64c3a28834\", \"keyboard_unittests\": \"0524c5c0889303509f58198e6198be9bff704725\", \"latency_unittests\": \"f034908d9d83dca2a0cad0edc48f6259d569102d\", \"leveldb_service_unittests\": \"8fa655465e0bf1a974b902f313188814b8821e0d\", \"libjingle_xmpp_unittests\": \"204bb67c306a2d5d3e49c17252bd298cc593997e\", \"media_blink_unittests\": \"3144817de3adba96496cb918da9458da14077685\", \"media_service_unittests\": \"d344828063c1ce199f624638b675980c620f0cc3\", \"media_unittests\": \"b81ed09611cceea866f93f665cbd3d46347fe917\", \"message_center_unittests\": \"2b2bc78211e8ed0e5c2bf28b262b54e795d82b70\", \"midi_unittests\": \"51a6d2814ce5dc54a85953ba589ac3783c913286\", \"mojo_core_unittests\": \"fa969417ebaf90e4eec686ca827563f54c122eb9\", \"mojo_unittests\": \"191513f1cd5bfc07afc7160ed5c6c194e127bba5\", \"nacl_helper_nonsfi_unittests\": \"54b49f49529b8c87d8421aa241f3b0d281205da3\", \"nacl_loader_unittests\": \"a5d3665b2b02e6c53b7840403f2e76d9259034f4\", \"native_theme_unittests\": \"bbe56069dd2c54246eb9135da0c437e39bccfbe3\", \"net_unittests\": \"cc516d76c576651e12565fbe10ffcdac19d78b6e\", \"ozone_gl_unittests\": \"d2c07f674e77c885db8f4f380b0cf24f9d57672e\", \"ozone_unittests\": \"2e3046e13e8c0c637513a28d493bb9bbd7b215b5\", \"ozone_x11_unittests\": \"5aa0fabebf24f66efc0a5b98515b5923a00303e3\", \"pdf_unittests\": \"5e06ffa633be4b875f408e407200e1a0769c30e9\", \"perfetto_unittests\": \"cbc0e8eb568b72d9cb62a031e1d6beb9b20a1922\", \"ppapi_unittests\": \"69b634e96135d7e8c0e40281263c489595d54604\", \"printing_unittests\": \"eda6c823820513139c48888989f4f8bb039ffd1f\", \"remoting_unittests\": \"ea0f2695994e748076c8cc14cf7011161ebe0bca\", \"sandbox_linux_unittests\": \"910d9324944a625638df9760d8d2cdd937cd22ed\", \"service_manager_unittests\": \"eb2a4e44f3349d0fbdc9415a94c8772caad7d9a9\", \"services_unittests\": \"93ce9d1b93d95d16db6793274cc29023dc0a46c7\", \"shell_dialogs_unittests\": \"29244094490c9754322886c7b275092752342920\", \"skia_unittests\": \"b1000d7d7d30421d53e01ec84d8657d22901dfbc\", \"snapshot_unittests\": \"4bbe6514016ad5a9db88c803cfd2a19427426b91\", \"sql_unittests\": \"e52ee772ffe46fa5d5d2fa70d614cf647ab3315e\", \"storage_unittests\": \"834ff56608f9907699f83b743304bfe42f30c396\", \"sync_integration_tests\": \"9073184ad9a1175b743088556fc76b98c20e66ff\", \"traffic_annotation_auditor_unittests\": \"05ed96f1e7f3e70b4404b7bdebb70cd9df12c16e\", \"ui_base_unittests\": \"bb63d686ba7888895ae7de813309776a8a9f80c3\", \"ui_chromeos_unittests\": \"9474744cd2e1753d7a467ae3e9b9ad27ddfc551c\", \"ui_touch_selection_unittests\": \"4686bfa9f0af3e42c9f12772d8d5a38569a017f1\", \"unit_tests\": \"d8df23f2d50ce782982834a0354193bc32cb237f\", \"url_unittests\": \"bc5c1be4b7fa4401ebf534e2656c0bc32cf7a501\", \"usage_time_limit_unittests\": \"12b931ed6fbb7a29af8385426ca91663854a2c0a\", \"views_unittests\": \"c36a7290c6856cc147bf904a7f410cbb534db88c\", \"viz_unittests\": \"2caec2a682ae9c4282dc0c6acaa20aed0330f2cd\", \"wm_unittests\": \"678d52b7c5d6de9d785c369a89b4e15b3a46ae63\", \"wtf_unittests\": \"0a635db16bba4cfda9a9ce5d2197cf8aca19d800\"}, \"swarm_hashes_refs/heads/master(at){#661690}_without_patch\": {\"accessibility_unittests\": \"4e2a8336c66c484d83dba2e5bb5794940f5c905c\", \"angle_unittests\": \"4bdda2b082c86a2441fd1c242b3ff7f0c5ac300b\", \"app_list_unittests\": \"174dba7e0bc5bc0be3b8095353ff0bf1d8890e8e\", \"app_shell_unittests\": \"4c131e6c15c88cde0d5cd911d103eed915a02212\", \"ash_unittests\": \"09ae58c38ef528a4f9d15a6396760a213791f843\", \"aura_unittests\": \"b2b3008f39f036773a4712b1eb42b2848c2b8b40\", \"base_unittests\": \"5c6edccc757bc139ef074f58ac34626bdf494c02\", \"base_util_unittests\": \"c25cf049f49165b5d508f65ac5728434d1de7210\", \"blink_common_unittests\": \"138fe1793d0d9cb403a455b9c3e656091fb8b660\", \"blink_fuzzer_unittests\": \"37d030c02aca72f783af682910f0c653457153b7\", \"blink_heap_unittests\": \"303f488e588c855cbeca1b557a34e9f582df21df\", \"blink_platform_unittests\": \"ce4cb29426fe5101ca4918eb4b21e76a25a4c009\", \"blink_unittests\": \"e485b81f87eaf1d170bb95020346c25278240b26\", \"boringssl_crypto_tests\": \"f9cc299cdf000b7331641163508d04d263415e0a\", \"boringssl_ssl_tests\": \"a402d75a7619d8909b41c96523c0a644d9cb37b9\", \"browser_tests\": \"6c05bcdeac6d7cdce58e9160ad06caab14189bb5\", \"cacheinvalidation_unittests\": \"2eb1dcfc10b61e68844471c5861dcd70149519cc\", \"capture_unittests\": \"58641f1db7a3e1bc7f5b26c1b8acca3381c8d764\", \"cast_unittests\": \"70326253810d155c4837e22235e47c6731539c6c\", \"cc_unittests\": \"28362511b712411dc06e22faa539971c64dabd7d\", \"chrome_app_unittests\": \"3bbac457bd03db9ee8cce0455d2581ec499afbaf\", \"chromedriver_unittests\": \"a5fc75a7952b9a5e6be4ae10626dd5f98ca1a780\", \"chromeos_components_unittests\": \"8c771bfce96f3cbbd15218b68fc37da3561244c4\", \"chromeos_unittests\": \"8756dc4e98013f829ff9898ba2b996568fe146a2\", \"components_browsertests\": \"c377a2d9aa4335d6d4dad04c67a467fc8dd25ddb\", \"components_unittests\": \"5f024fdb25843c1ee20b17f2cdc872899a507777\", \"compositor_unittests\": \"8f572db169433ca4269f64deaa9c3a4498345a36\", \"content_browsertests\": \"f9ec8fc6b7aeef6739f248f6729a684ea8ae4811\", \"content_unittests\": \"0c3abb45352f867722fb45d6217fc58fa9639ff4\", \"crypto_unittests\": \"f320a814b67a7e7f3b8f3a8a8de4f39bb9c20952\", \"dbus_unittests\": \"6a6550e729cbdb98cfe48f3aa9c91e65892a7f64\", \"device_unittests\": \"9fda01453b2d9a79127f19b708a1eea7a63d2332\", \"display_unittests\": \"0b4e192893e18a16ac34906031fa18f6faf0a798\", \"events_unittests\": \"e3b64f11ad40bc0756a8f40cbc7c392f132883c3\", \"exo_unittests\": \"309c3dabcc76eccb201417c9f46a8cd09e26fae3\", \"extensions_browsertests\": \"01a0267bceec21535c16fe6901d28de411b0be5c\", \"extensions_unittests\": \"9e7a30f3305f523dbaec7b2e8c8ba24a91de4f57\", \"filesystem_service_unittests\": \"637e6b631ed39ef01904fb725131b0d568804c2f\", \"gcm_unit_tests\": \"ea9907d5245dbb11b408b136ddcacda7b39bc30d\", \"gfx_unittests\": \"6699edfd4fc233ad471c5e878addf2eda7a34be1\", \"gin_unittests\": \"128744f30e79a1325ca0282ac8eeec49e11d0749\", \"gl_unittests_ozone\": \"e4fdad88dfe5e80d1938290a1ef1a6616b8e72e5\", \"google_apis_unittests\": \"1d21b5ed771dfac6301444a5eaa0248b22ad6868\", \"gpu_unittests\": \"2c12554867d63a18701c0fb788bba74e45f6ff9b\", \"interactive_ui_tests\": \"5aa859b83084b22abc5349335e1ab6bf41c96e1d\", \"ipc_tests\": \"362cffac248147219ddc8a98fd9118cf9c28ca69\", \"jingle_unittests\": \"768813413630249bd6ba3379d73f5f64c3a28834\", \"keyboard_unittests\": \"0524c5c0889303509f58198e6198be9bff704725\", \"latency_unittests\": \"f034908d9d83dca2a0cad0edc48f6259d569102d\", \"leveldb_service_unittests\": \"8fa655465e0bf1a974b902f313188814b8821e0d\", \"libjingle_xmpp_unittests\": \"204bb67c306a2d5d3e49c17252bd298cc593997e\", \"media_blink_unittests\": \"3144817de3adba96496cb918da9458da14077685\", \"media_service_unittests\": \"d344828063c1ce199f624638b675980c620f0cc3\", \"media_unittests\": \"b81ed09611cceea866f93f665cbd3d46347fe917\", \"message_center_unittests\": \"2b2bc78211e8ed0e5c2bf28b262b54e795d82b70\", \"midi_unittests\": \"51a6d2814ce5dc54a85953ba589ac3783c913286\", \"mojo_core_unittests\": \"fa969417ebaf90e4eec686ca827563f54c122eb9\", \"mojo_unittests\": \"191513f1cd5bfc07afc7160ed5c6c194e127bba5\", \"nacl_helper_nonsfi_unittests\": \"54b49f49529b8c87d8421aa241f3b0d281205da3\", \"nacl_loader_unittests\": \"a5d3665b2b02e6c53b7840403f2e76d9259034f4\", \"native_theme_unittests\": \"bbe56069dd2c54246eb9135da0c437e39bccfbe3\", \"net_unittests\": \"cc516d76c576651e12565fbe10ffcdac19d78b6e\", \"ozone_gl_unittests\": \"d2c07f674e77c885db8f4f380b0cf24f9d57672e\", \"ozone_unittests\": \"2e3046e13e8c0c637513a28d493bb9bbd7b215b5\", \"ozone_x11_unittests\": \"5aa0fabebf24f66efc0a5b98515b5923a00303e3\", \"pdf_unittests\": \"5e06ffa633be4b875f408e407200e1a0769c30e9\", \"perfetto_unittests\": \"cbc0e8eb568b72d9cb62a031e1d6beb9b20a1922\", \"ppapi_unittests\": \"69b634e96135d7e8c0e40281263c489595d54604\", \"printing_unittests\": \"eda6c823820513139c48888989f4f8bb039ffd1f\", \"remoting_unittests\": \"ea0f2695994e748076c8cc14cf7011161ebe0bca\", \"sandbox_linux_unittests\": \"910d9324944a625638df9760d8d2cdd937cd22ed\", \"service_manager_unittests\": \"eb2a4e44f3349d0fbdc9415a94c8772caad7d9a9\", \"services_unittests\": \"93ce9d1b93d95d16db6793274cc29023dc0a46c7\", \"shell_dialogs_unittests\": \"29244094490c9754322886c7b275092752342920\", \"skia_unittests\": \"b1000d7d7d30421d53e01ec84d8657d22901dfbc\", \"snapshot_unittests\": \"4bbe6514016ad5a9db88c803cfd2a19427426b91\", \"sql_unittests\": \"e52ee772ffe46fa5d5d2fa70d614cf647ab3315e\", \"storage_unittests\": \"834ff56608f9907699f83b743304bfe42f30c396\", \"sync_integration_tests\": \"9073184ad9a1175b743088556fc76b98c20e66ff\", \"traffic_annotation_auditor_unittests\": \"05ed96f1e7f3e70b4404b7bdebb70cd9df12c16e\", \"ui_base_unittests\": \"bb63d686ba7888895ae7de813309776a8a9f80c3\", \"ui_chromeos_unittests\": \"9474744cd2e1753d7a467ae3e9b9ad27ddfc551c\", \"ui_touch_selection_unittests\": \"4686bfa9f0af3e42c9f12772d8d5a38569a017f1\", \"unit_tests\": \"d8df23f2d50ce782982834a0354193bc32cb237f\", \"url_unittests\": \"bc5c1be4b7fa4401ebf534e2656c0bc32cf7a501\", \"usage_time_limit_unittests\": \"12b931ed6fbb7a29af8385426ca91663854a2c0a\", \"views_unittests\": \"c36a7290c6856cc147bf904a7f410cbb534db88c\", \"viz_unittests\": \"2caec2a682ae9c4282dc0c6acaa20aed0330f2cd\", \"wm_unittests\": \"678d52b7c5d6de9d785c369a89b4e15b3a46ae63\", \"wtf_unittests\": \"0a635db16bba4cfda9a9ce5d2197cf8aca19d800\"}}, \"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\"], \"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\": [\"4302-e8210c4\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"3 tests failed: ['non_network_service_browser_tests', 'browser_tests', 'viz_browser_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558437334879019",
"status": "COMPLETED",
"status_changed_ts": "1558437336061186",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12500",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5e4ae9cbd14308fc107017367a815976168c785c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e4ae9cbd14308fc107017367a815976168c785c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078283890748050208",
"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/8912853828695155520/+/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:44f2eaa8b5ffe010",
"user_agent:luci-scheduler"
],
"updated_ts": "1558443168538318",
"url": "https://ci.chromium.org/b/8912853828695155520",
"utcnow_ts": "1558443170750804"
}
}
[12:52:50.833] Invocation finished in 1h38m52.276566521s with status FAILED