[09:13:56.132] New invocation is queued and will start shortly
[09:13:57.230] Starting the invocation (attempt 1)
[09:13:57.300] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:13:57.300] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:13:57.300] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082821291389944288",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"07e4b034349122513504f7dbeb3e721b02d43257\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9082821291389944288",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/07e4b034349122513504f7dbeb3e721b02d43257",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/07e4b034349122513504f7dbeb3e721b02d43257",
"gitiles_ref:refs/heads/master"
]
}
[09:13:58.430] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554110037751426",
"id": "8917391229013007488",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"07e4b034349122513504f7dbeb3e721b02d43257\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554110038295490",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11640",
"builder:linux-chromeos-dbg",
"buildset:commit/git/07e4b034349122513504f7dbeb3e721b02d43257",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/07e4b034349122513504f7dbeb3e721b02d43257",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082821291389944288",
"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/8917391229013007488/+/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": "1554110038295627",
"url": "https://ci.chromium.org/b/8917391229013007488",
"utcnow_ts": "1554110038420568"
}
}
[09:13:58.430] Task URL: https://ci.chromium.org/b/8917391229013007488
[09:13:58.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:2:0) after 1m0s
[09:14:58.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:2:0)
[09:14:58.429] Timer tick, asking Buildbucket for the build status
[09:14:58.639] Build 8917391229013007488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:58.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:3:0) after 1m0s
[09:15:58.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:3:0)
[09:15:58.672] Timer tick, asking Buildbucket for the build status
[09:15:58.902] Build 8917391229013007488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:58.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:4:0) after 1m0s
[09:16:58.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:4:0)
[09:16:58.923] Timer tick, asking Buildbucket for the build status
[09:16:59.149] Build 8917391229013007488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:59.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:5:0) after 1m0s
[09:17:59.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:5:0)
[09:17:59.162] Timer tick, asking Buildbucket for the build status
[09:17:59.799] Build 8917391229013007488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:59.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:6:0) after 1m0s
[09:18:59.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:6:0)
[09:18:59.783] Timer tick, asking Buildbucket for the build status
[09:19:00.576] Build 8917391229013007488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:00.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:7:0) after 1m0s
[09:19:19.174] Received PubSub notification, asking Buildbucket for the build status
[09:19:19.342] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:00.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:7:0)
[09:20:00.601] Timer tick, asking Buildbucket for the build status
[09:20:00.799] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:00.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:9:0) after 1m0s
[09:21:00.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:9:0)
[09:21:00.792] Timer tick, asking Buildbucket for the build status
[09:21:01.119] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:01.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:10:0) after 1m0s
[09:22:01.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:10:0)
[09:22:01.115] Timer tick, asking Buildbucket for the build status
[09:22:01.536] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:01.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:11:0) after 1m0s
[09:23:01.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:11:0)
[09:23:01.515] Timer tick, asking Buildbucket for the build status
[09:23:01.855] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:01.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:12:0) after 1m0s
[09:24:01.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:12:0)
[09:24:01.896] Timer tick, asking Buildbucket for the build status
[09:24:02.152] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:02.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:13:0) after 1m0s
[09:25:02.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:13:0)
[09:25:02.193] Timer tick, asking Buildbucket for the build status
[09:25:02.413] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:02.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:14:0) after 1m0s
[09:26:02.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:14:0)
[09:26:02.441] Timer tick, asking Buildbucket for the build status
[09:26:03.478] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:03.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:15:0) after 1m0s
[09:27:03.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:15:0)
[09:27:03.482] Timer tick, asking Buildbucket for the build status
[09:27:03.950] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:03.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:16:0) after 1m0s
[09:28:03.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:16:0)
[09:28:03.967] Timer tick, asking Buildbucket for the build status
[09:28:04.142] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:04.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:17:0) after 1m0s
[09:29:04.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:17:0)
[09:29:04.164] Timer tick, asking Buildbucket for the build status
[09:29:04.270] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:04.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:18:0) after 1m0s
[09:30:04.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:18:0)
[09:30:04.290] Timer tick, asking Buildbucket for the build status
[09:30:04.439] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:04.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:19:0) after 1m0s
[09:31:04.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:19:0)
[09:31:04.440] Timer tick, asking Buildbucket for the build status
[09:31:04.570] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:04.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:20:0) after 1m0s
[09:32:04.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:20:0)
[09:32:04.587] Timer tick, asking Buildbucket for the build status
[09:32:04.702] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:04.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:21:0) after 1m0s
[09:33:04.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:21:0)
[09:33:04.698] Timer tick, asking Buildbucket for the build status
[09:33:04.899] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:04.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:22:0) after 1m0s
[09:34:04.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:22:0)
[09:34:04.972] Timer tick, asking Buildbucket for the build status
[09:34:05.112] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:05.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:23:0) after 1m0s
[09:35:05.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:23:0)
[09:35:05.096] Timer tick, asking Buildbucket for the build status
[09:35:05.585] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:05.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:24:0) after 1m0s
[09:36:05.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:24:0)
[09:36:05.604] Timer tick, asking Buildbucket for the build status
[09:36:06.467] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:06.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:25:0) after 1m0s
[09:37:06.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:25:0)
[09:37:06.485] Timer tick, asking Buildbucket for the build status
[09:37:06.686] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:06.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:26:0) after 1m0s
[09:38:06.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:26:0)
[09:38:06.734] Timer tick, asking Buildbucket for the build status
[09:38:07.214] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:07.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:27:0) after 1m0s
[09:39:07.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:27:0)
[09:39:07.234] Timer tick, asking Buildbucket for the build status
[09:39:07.408] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:07.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:28:0) after 1m0s
[09:40:07.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:28:0)
[09:40:07.395] Timer tick, asking Buildbucket for the build status
[09:40:07.589] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:07.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:29:0) after 1m0s
[09:41:07.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:29:0)
[09:41:07.573] Timer tick, asking Buildbucket for the build status
[09:41:08.369] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:08.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:30:0) after 1m0s
[09:42:08.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:30:0)
[09:42:08.388] Timer tick, asking Buildbucket for the build status
[09:42:08.552] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:08.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:31:0) after 1m0s
[09:43:08.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:31:0)
[09:43:08.534] Timer tick, asking Buildbucket for the build status
[09:43:08.898] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:08.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:32:0) after 1m0s
[09:44:08.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:32:0)
[09:44:08.920] Timer tick, asking Buildbucket for the build status
[09:44:09.412] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:09.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:33:0) after 1m0s
[09:45:09.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:33:0)
[09:45:09.426] Timer tick, asking Buildbucket for the build status
[09:45:10.008] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:10.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:34:0) after 1m0s
[09:46:10.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:34:0)
[09:46:10.029] Timer tick, asking Buildbucket for the build status
[09:46:10.218] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:10.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:35:0) after 1m0s
[09:47:10.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:35:0)
[09:47:10.237] Timer tick, asking Buildbucket for the build status
[09:47:10.511] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:10.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:36:0) after 1m0s
[09:48:10.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:36:0)
[09:48:10.529] Timer tick, asking Buildbucket for the build status
[09:48:11.065] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:11.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:37:0) after 1m0s
[09:49:11.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:37:0)
[09:49:11.073] Timer tick, asking Buildbucket for the build status
[09:49:11.515] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:11.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:38:0) after 1m0s
[09:50:11.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:38:0)
[09:50:11.553] Timer tick, asking Buildbucket for the build status
[09:50:11.866] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:11.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:39:0) after 1m0s
[09:51:11.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:39:0)
[09:51:11.876] Timer tick, asking Buildbucket for the build status
[09:51:12.116] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:12.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:40:0) after 1m0s
[09:52:12.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:40:0)
[09:52:12.136] Timer tick, asking Buildbucket for the build status
[09:52:12.335] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:12.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:41:0) after 1m0s
[09:53:12.356] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:41:0)
[09:53:12.356] Timer tick, asking Buildbucket for the build status
[09:53:12.644] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:12.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:42:0) after 1m0s
[09:54:12.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:42:0)
[09:54:12.661] Timer tick, asking Buildbucket for the build status
[09:54:13.686] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:13.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:43:0) after 1m0s
[09:55:13.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:43:0)
[09:55:13.703] Timer tick, asking Buildbucket for the build status
[09:55:14.014] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:14.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:44:0) after 1m0s
[09:56:14.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:44:0)
[09:56:14.014] Timer tick, asking Buildbucket for the build status
[09:56:14.313] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:14.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:45:0) after 1m0s
[09:57:14.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:45:0)
[09:57:14.349] Timer tick, asking Buildbucket for the build status
[09:57:14.648] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:14.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:46:0) after 1m0s
[09:58:14.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:46:0)
[09:58:14.690] Timer tick, asking Buildbucket for the build status
[09:58:15.114] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:15.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:47:0) after 1m0s
[09:59:15.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:47:0)
[09:59:15.102] Timer tick, asking Buildbucket for the build status
[09:59:15.738] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:15.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:48:0) after 1m0s
[10:00:15.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:48:0)
[10:00:15.725] Timer tick, asking Buildbucket for the build status
[10:00:16.121] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:16.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:49:0) after 1m0s
[10:01:16.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:49:0)
[10:01:16.143] Timer tick, asking Buildbucket for the build status
[10:01:16.462] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:16.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:50:0) after 1m0s
[10:02:16.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:50:0)
[10:02:16.485] Timer tick, asking Buildbucket for the build status
[10:02:16.629] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:16.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:51:0) after 1m0s
[10:03:16.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:51:0)
[10:03:16.649] Timer tick, asking Buildbucket for the build status
[10:03:16.912] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:16.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:52:0) after 1m0s
[10:04:16.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:52:0)
[10:04:16.907] Timer tick, asking Buildbucket for the build status
[10:04:17.099] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:17.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:53:0) after 1m0s
[10:05:17.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:53:0)
[10:05:17.119] Timer tick, asking Buildbucket for the build status
[10:05:17.381] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:17.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:54:0) after 1m0s
[10:06:17.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:54:0)
[10:06:17.414] Timer tick, asking Buildbucket for the build status
[10:06:17.966] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:17.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:55:0) after 1m0s
[10:07:17.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:55:0)
[10:07:17.949] Timer tick, asking Buildbucket for the build status
[10:07:18.534] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:18.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:56:0) after 1m0s
[10:08:18.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:56:0)
[10:08:18.554] Timer tick, asking Buildbucket for the build status
[10:08:18.816] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:18.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:57:0) after 1m0s
[10:09:18.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:57:0)
[10:09:18.845] Timer tick, asking Buildbucket for the build status
[10:09:19.267] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:19.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:58:0) after 1m0s
[10:10:19.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:58:0)
[10:10:19.332] Timer tick, asking Buildbucket for the build status
[10:10:19.920] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:19.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:59:0) after 1m0s
[10:11:19.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:59:0)
[10:11:19.942] Timer tick, asking Buildbucket for the build status
[10:11:20.527] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:60:0) after 1m0s
[10:12:20.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:60:0)
[10:12:20.546] Timer tick, asking Buildbucket for the build status
[10:12:20.793] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:20.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:61:0) after 1m0s
[10:13:20.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:61:0)
[10:13:20.812] Timer tick, asking Buildbucket for the build status
[10:13:21.478] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:21.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:62:0) after 1m0s
[10:14:21.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:62:0)
[10:14:21.519] Timer tick, asking Buildbucket for the build status
[10:14:21.703] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:21.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:63:0) after 1m0s
[10:15:21.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:63:0)
[10:15:21.726] Timer tick, asking Buildbucket for the build status
[10:15:22.707] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:22.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:64:0) after 1m0s
[10:16:22.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:64:0)
[10:16:22.713] Timer tick, asking Buildbucket for the build status
[10:16:23.305] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:23.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:65:0) after 1m0s
[10:17:23.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:65:0)
[10:17:23.302] Timer tick, asking Buildbucket for the build status
[10:17:25.372] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:25.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:66:0) after 1m0s
[10:18:25.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:66:0)
[10:18:25.393] Timer tick, asking Buildbucket for the build status
[10:18:26.092] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:26.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:67:0) after 1m0s
[10:19:26.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:67:0)
[10:19:26.122] Timer tick, asking Buildbucket for the build status
[10:19:26.287] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:26.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:68:0) after 1m0s
[10:20:26.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:68:0)
[10:20:26.325] Timer tick, asking Buildbucket for the build status
[10:20:27.904] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:27.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:69:0) after 1m0s
[10:21:27.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:69:0)
[10:21:27.926] Timer tick, asking Buildbucket for the build status
[10:21:28.560] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:28.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:70:0) after 1m0s
[10:22:28.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:70:0)
[10:22:28.587] Timer tick, asking Buildbucket for the build status
[10:22:28.731] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:28.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:71:0) after 1m0s
[10:23:28.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:71:0)
[10:23:28.750] Timer tick, asking Buildbucket for the build status
[10:23:28.947] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:28.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:72:0) after 1m0s
[10:24:28.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:72:0)
[10:24:28.968] Timer tick, asking Buildbucket for the build status
[10:24:29.277] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:29.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:73:0) after 1m0s
[10:25:29.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:73:0)
[10:25:29.305] Timer tick, asking Buildbucket for the build status
[10:25:29.876] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:29.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:74:0) after 1m0s
[10:26:29.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:74:0)
[10:26:29.896] Timer tick, asking Buildbucket for the build status
[10:26:30.434] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:30.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:75:0) after 1m0s
[10:27:30.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:75:0)
[10:27:30.440] Timer tick, asking Buildbucket for the build status
[10:27:30.575] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:30.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:76:0) after 1m0s
[10:28:30.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:76:0)
[10:28:30.546] Timer tick, asking Buildbucket for the build status
[10:28:30.869] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:30.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:77:0) after 1m0s
[10:29:30.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:77:0)
[10:29:30.887] Timer tick, asking Buildbucket for the build status
[10:29:32.122] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:32.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:78:0) after 1m0s
[10:30:32.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:78:0)
[10:30:32.139] Timer tick, asking Buildbucket for the build status
[10:30:32.379] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:32.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:79:0) after 1m0s
[10:31:32.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:79:0)
[10:31:32.404] Timer tick, asking Buildbucket for the build status
[10:31:33.192] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:33.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:80:0) after 1m0s
[10:32:33.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:80:0)
[10:32:33.210] Timer tick, asking Buildbucket for the build status
[10:32:33.545] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:33.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:81:0) after 1m0s
[10:33:33.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:81:0)
[10:33:33.579] Timer tick, asking Buildbucket for the build status
[10:33:33.871] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:33.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:82:0) after 1m0s
[10:34:33.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:82:0)
[10:34:33.892] Timer tick, asking Buildbucket for the build status
[10:34:34.064] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:34.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:83:0) after 1m0s
[10:35:34.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:83:0)
[10:35:34.112] Timer tick, asking Buildbucket for the build status
[10:35:35.174] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:35.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:84:0) after 1m0s
[10:36:35.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:84:0)
[10:36:35.281] Timer tick, asking Buildbucket for the build status
[10:36:35.394] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:35.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:85:0) after 1m0s
[10:37:35.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:85:0)
[10:37:35.384] Timer tick, asking Buildbucket for the build status
[10:37:35.571] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:35.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:86:0) after 1m0s
[10:38:35.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:86:0)
[10:38:35.593] Timer tick, asking Buildbucket for the build status
[10:38:35.715] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:35.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:87:0) after 1m0s
[10:39:35.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:87:0)
[10:39:35.732] Timer tick, asking Buildbucket for the build status
[10:39:36.304] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:36.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:88:0) after 1m0s
[10:40:36.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:88:0)
[10:40:36.324] Timer tick, asking Buildbucket for the build status
[10:40:37.085] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:37.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:89:0) after 1m0s
[10:41:37.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:89:0)
[10:41:37.116] Timer tick, asking Buildbucket for the build status
[10:41:37.656] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:37.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:90:0) after 1m0s
[10:42:37.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:90:0)
[10:42:37.679] Timer tick, asking Buildbucket for the build status
[10:42:38.271] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:38.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:91:0) after 1m0s
[10:43:38.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:91:0)
[10:43:38.293] Timer tick, asking Buildbucket for the build status
[10:43:38.492] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:38.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:92:0) after 1m0s
[10:44:38.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:92:0)
[10:44:38.475] Timer tick, asking Buildbucket for the build status
[10:44:39.204] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:39.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:93:0) after 1m0s
[10:45:39.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:93:0)
[10:45:39.236] Timer tick, asking Buildbucket for the build status
[10:45:39.829] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:39.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:94:0) after 1m0s
[10:46:39.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:94:0)
[10:46:39.826] Timer tick, asking Buildbucket for the build status
[10:46:40.018] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:40.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:95:0) after 1m0s
[10:47:40.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:95:0)
[10:47:40.045] Timer tick, asking Buildbucket for the build status
[10:47:40.204] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:40.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:96:0) after 1m0s
[10:48:40.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:96:0)
[10:48:40.217] Timer tick, asking Buildbucket for the build status
[10:48:41.308] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:41.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:97:0) after 1m0s
[10:49:41.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:97:0)
[10:49:41.331] Timer tick, asking Buildbucket for the build status
[10:49:42.083] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:42.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:98:0) after 1m0s
[10:50:42.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:98:0)
[10:50:42.102] Timer tick, asking Buildbucket for the build status
[10:50:42.505] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:42.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:99:0) after 1m0s
[10:51:42.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:99:0)
[10:51:42.524] Timer tick, asking Buildbucket for the build status
[10:51:44.138] Build 8917391229013007488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:44.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082821291389944288:100:0) after 1m0s
[10:52:31.942] Received PubSub notification, asking Buildbucket for the build status
[10:52:32.598] Build 8917391229013007488: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:52:32.598] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1554115950808713",
"created_by": "project:chromium",
"created_ts": "1554110037751426",
"failure_reason": "BUILD_FAILURE",
"id": "8917391229013007488",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"07e4b034349122513504f7dbeb3e721b02d43257\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 11640, \"got_angle_revision\": \"3f7ace324e178a9466f3f1a002e3e5a025c070df\", \"got_dawn_revision\": \"14487c34f7d9627711cbcab63af9ce853718762e\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"07e4b034349122513504f7dbeb3e721b02d43257\", \"got_revision_cp\": \"refs/heads/master@{#646223}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"7a643808c30fc1e816e8faeba65de934f835e2b4\", \"got_v8_revision_cp\": \"refs/heads/7.5.150@{#1}\", \"got_webrtc_revision\": \"89cd0c045035b56d570afa2671faf7151d01d2a0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27375}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"07e4b034349122513504f7dbeb3e721b02d43257\", \"swarm_hashes\": {\"accessibility_unittests\": \"f1ef74c13b518b92d718fb5d64d1d716f16c45f8\", \"angle_unittests\": \"42f83f4fe79021e29d42515b2368d6f05f6140f1\", \"app_list_unittests\": \"02af6eb7f8bda349ea5c8a0622453fc171a014c3\", \"app_shell_unittests\": \"829b090d26abb362277c2de8b8f69e3d3d04eecb\", \"ash_unittests\": \"ffe8eb833e168217a3f8b4f1a6b2e3c152d53ca0\", \"aura_unittests\": \"8a100d86363230065e5dbc693029b9a11b64fc9f\", \"base_unittests\": \"d68463e0cfaec7fe2087463ff9d17d1565674bb2\", \"blink_common_unittests\": \"41ae408d15feb4907fdfa66263f93866a8aff53f\", \"blink_fuzzer_unittests\": \"0cd4b7e74b9d6619d4bc03508276c29070847e75\", \"blink_heap_unittests\": \"1c48acd37d0f3c9886dc862dfa5c7160768b151b\", \"blink_platform_unittests\": \"2c5f38b46810513007f43d2ecedf925fee549ecb\", \"blink_unittests\": \"b4c8c60c6ebce1fb0dd2846bea9eed36a765955e\", \"boringssl_crypto_tests\": \"a71a961e61bd290a318397212a1dccd150ab99cd\", \"boringssl_ssl_tests\": \"e72d93300390371e589bd40dfbcea4ff480243f6\", \"browser_tests\": \"9aa5160e2830df9951eb6e33e8a2d33190b5d932\", \"cacheinvalidation_unittests\": \"08438ceda3f3bc849740f781dc8fdae5d86e0683\", \"capture_unittests\": \"23fde96c789969592ae39f8f509500a7a147c0f7\", \"cast_unittests\": \"2cb7e5e52838ffdfa4eba1fc1f6b94e2c39417ec\", \"cc_unittests\": \"2a0f134d742970feeaa4f6053c5a80d568c0d76c\", \"chrome_app_unittests\": \"940b0acdade21e167bab56447e126f2eb35f960c\", \"chromedriver_unittests\": \"fb8aa333f9318c82960e1d68cb5110e285084da9\", \"chromeos_components_unittests\": \"879dec59d71fbf2b73b6a4a7838c99140f7c3dea\", \"chromeos_unittests\": \"9ca54412c8f675060dc242c2c5f692bd84fa917c\", \"components_browsertests\": \"164206397d6707714b0b623a0a4c162e94367cde\", \"components_unittests\": \"45b07eb2c5c810177ce08faf5e6e1c863a4ee4ca\", \"compositor_unittests\": \"0bfa7f9895e20b9da25b713ed7c0e110eafd16f0\", \"content_browsertests\": \"8656e425ad1fc364fafff5cd0c5b82ffb55a53b8\", \"content_unittests\": \"19b0c04ec1c9af4ef8d77ffc0dda819262e56b6a\", \"crypto_unittests\": \"e33aa72785b63a9c5e181c503eac2a8ee19d6c1e\", \"dbus_unittests\": \"d90504f4580cfa582084e4444f6537693049822d\", \"device_unittests\": \"29e54bb42d5f53776d8101e9ad2f9d893636ce68\", \"display_unittests\": \"027f2de55550d76e5968b4d562c7b7cbe5225598\", \"events_unittests\": \"5f7ff242627bf9a068e4dc1cc71e5cba4bb64dfb\", \"exo_unittests\": \"c7ebd636a4f1a98ac94a8f974f0a49e84c795cc4\", \"extensions_browsertests\": \"b61918d30c70a941821eb3064639b9a0694de46b\", \"extensions_unittests\": \"dd7f7559ed85bc6f97bfce357ee2a27ff6e26828\", \"filesystem_service_unittests\": \"f7e7d4fcecd33ad58da899c9d3af9b38c0491712\", \"gcm_unit_tests\": \"6cdf297fc4912318402d4c9e043637016732f630\", \"gfx_unittests\": \"2d01b2d4f05cbd5816cf3aa95642e224b06a3479\", \"gin_unittests\": \"5f41b23fb71f88675e33c2743d9e8d61e89ba6f2\", \"gl_unittests_ozone\": \"dfed205f0723dc982727f1ff56e8dd176a18602c\", \"google_apis_unittests\": \"a6c67050b262716dd8ba396d43957a8ad6d1637d\", \"gpu_unittests\": \"000d3571238ef1684e9de75c1112f51aa5b7b8f8\", \"interactive_ui_tests\": \"06335ba00e674393b463f3782435a805ef325fba\", \"ipc_tests\": \"544eccf6087274ccb9f07a229fefa909df5b9e6f\", \"jingle_unittests\": \"1eaa7e1593dfcdf2b010a95f9ae221060d589c2e\", \"keyboard_unittests\": \"9053ca52a8a6d2aa9c89d97b18c924bac57a36b0\", \"latency_unittests\": \"83efef363d69ab719c0acb9d3260f496ad734e8b\", \"leveldb_service_unittests\": \"8bc590c7a2cbd41cb7a51403d9cb5d68d8040d89\", \"libjingle_xmpp_unittests\": \"f50160adfde3ff71fa5512b284b53416a485fe6e\", \"media_blink_unittests\": \"b881c2d3ef07e8444033b2616620d173f2135d55\", \"media_service_unittests\": \"8680ee423a5dc07b058cf2b783a54525b98f6cb8\", \"media_unittests\": \"899adb7cc114101577267d3fe0f76e5ca88c9ab6\", \"message_center_unittests\": \"bcbed5b42294d8b687d537d6011834757d9f491e\", \"midi_unittests\": \"9cdffec1fdc4f3cb9bcab142355a8b5f604a9a00\", \"mojo_core_unittests\": \"953bd1be1e47dd54ec02a365117240d874d89419\", \"mojo_unittests\": \"9956eecc8ecddaab6b0f75a02cc86a905d51d717\", \"nacl_helper_nonsfi_unittests\": \"a9166899f767704d0ba084a93f7a127ba32b0957\", \"nacl_loader_unittests\": \"12a79d8f8913f600dbc3f22543c16abd2182b71a\", \"native_theme_unittests\": \"98cf80cb3a3b13d202d06860afaf321258c20a36\", \"net_unittests\": \"b80064d59597d16bac3a3027ceab6398cc6eeb00\", \"ozone_gl_unittests\": \"08de16d020225a05f96cbd473b615c03fcec11b4\", \"ozone_unittests\": \"136a29ea0cad00a3e49ebdaf78a429b28f9706ab\", \"ozone_x11_unittests\": \"43b7b1e4ff4f61dd329ee341e1f20ab5db506b6e\", \"pdf_unittests\": \"2515c9e7de8d2b93aedc40299eaf833ac7a03b07\", \"perfetto_unittests\": \"d51e716dd049a19395c278c0c895dbda5aa795b9\", \"ppapi_unittests\": \"140ffe0d1610cbbd8f1b8776d51c9503824a05c8\", \"printing_unittests\": \"9227f6aded0d1f0fd8d44185b87b78cad2696914\", \"remoting_unittests\": \"121a3828cb3daa6c0759a89c57f26852041309da\", \"sandbox_linux_unittests\": \"8c0e744f0f62d9b37b0ef2783417d994504ab532\", \"service_manager_unittests\": \"d6813a550d37bf155da04a18dc1d005e062e843b\", \"services_unittests\": \"90809af0f81aded3b9739dbc48095324b48a21c4\", \"shell_dialogs_unittests\": \"8b3e6ecc06cac441f5cc20146b2754c16a31ec5a\", \"skia_unittests\": \"53d0fa761653ced700ac124cd82af2977828e848\", \"snapshot_unittests\": \"27328942c7c16d57cf3b5c91e87e77ee8853785e\", \"sql_unittests\": \"3f09e6982f6ff78b1cecdef1c4eee07db45b1199\", \"storage_unittests\": \"0d8719555adefdf127d11236311c1d8f9dc752a2\", \"sync_integration_tests\": \"2f1d00167e5cb0136b74eff0cc4dca80cd76ab7e\", \"traffic_annotation_auditor_unittests\": \"6c7857af39f218310e21080e2a3d18c3cb57b422\", \"ui_base_unittests\": \"be10868c802735830867dedccf70e663684b9662\", \"ui_chromeos_unittests\": \"e13640de8a6719fce84689784e9d54af1d110ded\", \"ui_touch_selection_unittests\": \"af11d9613d175f31886590bf925646e6955b4603\", \"unit_tests\": \"c315286703b44b28c96e462ad2b2490265771aba\", \"url_unittests\": \"9b876a97655690b9417506f9ec589c2128ee8f11\", \"usage_time_limit_unittests\": \"742f013d5d1bcba95ef01c01cd9f9a811851aaa7\", \"views_unittests\": \"1ba6274a02ed7feac2e4ee9eab4f77c5370d8edd\", \"viz_unittests\": \"488dabf95b67a667ba586d2df02686d4d24871c6\", \"wm_unittests\": \"8026fe6e387f97dbf96089a1e6f55a92879bfd6a\", \"wtf_unittests\": \"9199b04897b593995223585cad81672af6cf4583\"}, \"swarm_hashes_refs/heads/master(at){#646223}_without_patch\": {\"accessibility_unittests\": \"f1ef74c13b518b92d718fb5d64d1d716f16c45f8\", \"angle_unittests\": \"42f83f4fe79021e29d42515b2368d6f05f6140f1\", \"app_list_unittests\": \"02af6eb7f8bda349ea5c8a0622453fc171a014c3\", \"app_shell_unittests\": \"829b090d26abb362277c2de8b8f69e3d3d04eecb\", \"ash_unittests\": \"ffe8eb833e168217a3f8b4f1a6b2e3c152d53ca0\", \"aura_unittests\": \"8a100d86363230065e5dbc693029b9a11b64fc9f\", \"base_unittests\": \"d68463e0cfaec7fe2087463ff9d17d1565674bb2\", \"blink_common_unittests\": \"41ae408d15feb4907fdfa66263f93866a8aff53f\", \"blink_fuzzer_unittests\": \"0cd4b7e74b9d6619d4bc03508276c29070847e75\", \"blink_heap_unittests\": \"1c48acd37d0f3c9886dc862dfa5c7160768b151b\", \"blink_platform_unittests\": \"2c5f38b46810513007f43d2ecedf925fee549ecb\", \"blink_unittests\": \"b4c8c60c6ebce1fb0dd2846bea9eed36a765955e\", \"boringssl_crypto_tests\": \"a71a961e61bd290a318397212a1dccd150ab99cd\", \"boringssl_ssl_tests\": \"e72d93300390371e589bd40dfbcea4ff480243f6\", \"browser_tests\": \"9aa5160e2830df9951eb6e33e8a2d33190b5d932\", \"cacheinvalidation_unittests\": \"08438ceda3f3bc849740f781dc8fdae5d86e0683\", \"capture_unittests\": \"23fde96c789969592ae39f8f509500a7a147c0f7\", \"cast_unittests\": \"2cb7e5e52838ffdfa4eba1fc1f6b94e2c39417ec\", \"cc_unittests\": \"2a0f134d742970feeaa4f6053c5a80d568c0d76c\", \"chrome_app_unittests\": \"940b0acdade21e167bab56447e126f2eb35f960c\", \"chromedriver_unittests\": \"fb8aa333f9318c82960e1d68cb5110e285084da9\", \"chromeos_components_unittests\": \"879dec59d71fbf2b73b6a4a7838c99140f7c3dea\", \"chromeos_unittests\": \"9ca54412c8f675060dc242c2c5f692bd84fa917c\", \"components_browsertests\": \"164206397d6707714b0b623a0a4c162e94367cde\", \"components_unittests\": \"45b07eb2c5c810177ce08faf5e6e1c863a4ee4ca\", \"compositor_unittests\": \"0bfa7f9895e20b9da25b713ed7c0e110eafd16f0\", \"content_browsertests\": \"8656e425ad1fc364fafff5cd0c5b82ffb55a53b8\", \"content_unittests\": \"19b0c04ec1c9af4ef8d77ffc0dda819262e56b6a\", \"crypto_unittests\": \"e33aa72785b63a9c5e181c503eac2a8ee19d6c1e\", \"dbus_unittests\": \"d90504f4580cfa582084e4444f6537693049822d\", \"device_unittests\": \"29e54bb42d5f53776d8101e9ad2f9d893636ce68\", \"display_unittests\": \"027f2de55550d76e5968b4d562c7b7cbe5225598\", \"events_unittests\": \"5f7ff242627bf9a068e4dc1cc71e5cba4bb64dfb\", \"exo_unittests\": \"c7ebd636a4f1a98ac94a8f974f0a49e84c795cc4\", \"extensions_browsertests\": \"b61918d30c70a941821eb3064639b9a0694de46b\", \"extensions_unittests\": \"dd7f7559ed85bc6f97bfce357ee2a27ff6e26828\", \"filesystem_service_unittests\": \"f7e7d4fcecd33ad58da899c9d3af9b38c0491712\", \"gcm_unit_tests\": \"6cdf297fc4912318402d4c9e043637016732f630\", \"gfx_unittests\": \"2d01b2d4f05cbd5816cf3aa95642e224b06a3479\", \"gin_unittests\": \"5f41b23fb71f88675e33c2743d9e8d61e89ba6f2\", \"gl_unittests_ozone\": \"dfed205f0723dc982727f1ff56e8dd176a18602c\", \"google_apis_unittests\": \"a6c67050b262716dd8ba396d43957a8ad6d1637d\", \"gpu_unittests\": \"000d3571238ef1684e9de75c1112f51aa5b7b8f8\", \"interactive_ui_tests\": \"06335ba00e674393b463f3782435a805ef325fba\", \"ipc_tests\": \"544eccf6087274ccb9f07a229fefa909df5b9e6f\", \"jingle_unittests\": \"1eaa7e1593dfcdf2b010a95f9ae221060d589c2e\", \"keyboard_unittests\": \"9053ca52a8a6d2aa9c89d97b18c924bac57a36b0\", \"latency_unittests\": \"83efef363d69ab719c0acb9d3260f496ad734e8b\", \"leveldb_service_unittests\": \"8bc590c7a2cbd41cb7a51403d9cb5d68d8040d89\", \"libjingle_xmpp_unittests\": \"f50160adfde3ff71fa5512b284b53416a485fe6e\", \"media_blink_unittests\": \"b881c2d3ef07e8444033b2616620d173f2135d55\", \"media_service_unittests\": \"8680ee423a5dc07b058cf2b783a54525b98f6cb8\", \"media_unittests\": \"899adb7cc114101577267d3fe0f76e5ca88c9ab6\", \"message_center_unittests\": \"bcbed5b42294d8b687d537d6011834757d9f491e\", \"midi_unittests\": \"9cdffec1fdc4f3cb9bcab142355a8b5f604a9a00\", \"mojo_core_unittests\": \"953bd1be1e47dd54ec02a365117240d874d89419\", \"mojo_unittests\": \"9956eecc8ecddaab6b0f75a02cc86a905d51d717\", \"nacl_helper_nonsfi_unittests\": \"a9166899f767704d0ba084a93f7a127ba32b0957\", \"nacl_loader_unittests\": \"12a79d8f8913f600dbc3f22543c16abd2182b71a\", \"native_theme_unittests\": \"98cf80cb3a3b13d202d06860afaf321258c20a36\", \"net_unittests\": \"b80064d59597d16bac3a3027ceab6398cc6eeb00\", \"ozone_gl_unittests\": \"08de16d020225a05f96cbd473b615c03fcec11b4\", \"ozone_unittests\": \"136a29ea0cad00a3e49ebdaf78a429b28f9706ab\", \"ozone_x11_unittests\": \"43b7b1e4ff4f61dd329ee341e1f20ab5db506b6e\", \"pdf_unittests\": \"2515c9e7de8d2b93aedc40299eaf833ac7a03b07\", \"perfetto_unittests\": \"d51e716dd049a19395c278c0c895dbda5aa795b9\", \"ppapi_unittests\": \"140ffe0d1610cbbd8f1b8776d51c9503824a05c8\", \"printing_unittests\": \"9227f6aded0d1f0fd8d44185b87b78cad2696914\", \"remoting_unittests\": \"121a3828cb3daa6c0759a89c57f26852041309da\", \"sandbox_linux_unittests\": \"8c0e744f0f62d9b37b0ef2783417d994504ab532\", \"service_manager_unittests\": \"d6813a550d37bf155da04a18dc1d005e062e843b\", \"services_unittests\": \"90809af0f81aded3b9739dbc48095324b48a21c4\", \"shell_dialogs_unittests\": \"8b3e6ecc06cac441f5cc20146b2754c16a31ec5a\", \"skia_unittests\": \"53d0fa761653ced700ac124cd82af2977828e848\", \"snapshot_unittests\": \"27328942c7c16d57cf3b5c91e87e77ee8853785e\", \"sql_unittests\": \"3f09e6982f6ff78b1cecdef1c4eee07db45b1199\", \"storage_unittests\": \"0d8719555adefdf127d11236311c1d8f9dc752a2\", \"sync_integration_tests\": \"2f1d00167e5cb0136b74eff0cc4dca80cd76ab7e\", \"traffic_annotation_auditor_unittests\": \"6c7857af39f218310e21080e2a3d18c3cb57b422\", \"ui_base_unittests\": \"be10868c802735830867dedccf70e663684b9662\", \"ui_chromeos_unittests\": \"e13640de8a6719fce84689784e9d54af1d110ded\", \"ui_touch_selection_unittests\": \"af11d9613d175f31886590bf925646e6955b4603\", \"unit_tests\": \"c315286703b44b28c96e462ad2b2490265771aba\", \"url_unittests\": \"9b876a97655690b9417506f9ec589c2128ee8f11\", \"usage_time_limit_unittests\": \"742f013d5d1bcba95ef01c01cd9f9a811851aaa7\", \"views_unittests\": \"1ba6274a02ed7feac2e4ee9eab4f77c5370d8edd\", \"viz_unittests\": \"488dabf95b67a667ba586d2df02686d4d24871c6\", \"wm_unittests\": \"8026fe6e387f97dbf96089a1e6f55a92879bfd6a\", \"wtf_unittests\": \"9199b04897b593995223585cad81672af6cf4583\"}}, \"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\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"3 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554110358555714",
"status": "COMPLETED",
"status_changed_ts": "1554110358895157",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11640",
"builder:linux-chromeos-dbg",
"buildset:commit/git/07e4b034349122513504f7dbeb3e721b02d43257",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/07e4b034349122513504f7dbeb3e721b02d43257",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082821291389944288",
"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/8917391229013007488/+/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:43f0fec9fc7f4710",
"user_agent:luci-scheduler"
],
"updated_ts": "1554115951618937",
"url": "https://ci.chromium.org/b/8917391229013007488",
"utcnow_ts": "1554115952583218"
}
}
[10:52:32.598] Invocation finished in 1h38m36.497053628s with status FAILED