[22:59:28.087] New invocation is queued and will start shortly
[22:59:29.178] Starting the invocation (attempt 1)
[22:59:29.220] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:59:29.220] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:59:29.221] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083222338197545296",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a7ae450d0d465647eaff6dec086bd3f1aabd2a7d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083222338197545296",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/a7ae450d0d465647eaff6dec086bd3f1aabd2a7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7ae450d0d465647eaff6dec086bd3f1aabd2a7d",
"gitiles_ref:refs/heads/master"
]
}
[22:59:30.089] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553727569412694",
"id": "8917792276134461888",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a7ae450d0d465647eaff6dec086bd3f1aabd2a7d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553727569973365",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11574",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a7ae450d0d465647eaff6dec086bd3f1aabd2a7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7ae450d0d465647eaff6dec086bd3f1aabd2a7d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083222338197545296",
"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/8917792276134461888/+/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": "1553727569973477",
"url": "https://ci.chromium.org/b/8917792276134461888",
"utcnow_ts": "1553727570080149"
}
}
[22:59:30.089] Task URL: https://ci.chromium.org/b/8917792276134461888
[22:59:30.089] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:2:0) after 1m0s
[23:00:30.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:2:0)
[23:00:30.109] Timer tick, asking Buildbucket for the build status
[23:00:30.723] Build 8917792276134461888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:30.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:3:0) after 1m0s
[23:01:30.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:3:0)
[23:01:30.726] Timer tick, asking Buildbucket for the build status
[23:01:30.828] Build 8917792276134461888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:30.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:4:0) after 1m0s
[23:02:30.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:4:0)
[23:02:30.812] Timer tick, asking Buildbucket for the build status
[23:02:31.140] Build 8917792276134461888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:31.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:5:0) after 1m0s
[23:03:31.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:5:0)
[23:03:31.158] Timer tick, asking Buildbucket for the build status
[23:03:31.362] Build 8917792276134461888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:31.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:6:0) after 1m0s
[23:04:31.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:6:0)
[23:04:31.381] Timer tick, asking Buildbucket for the build status
[23:04:31.986] Build 8917792276134461888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:31.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:7:0) after 1m0s
[23:05:32.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:7:0)
[23:05:32.003] Timer tick, asking Buildbucket for the build status
[23:05:32.137] Build 8917792276134461888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:32.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:8:0) after 1m0s
[23:05:51.359] Received PubSub notification, asking Buildbucket for the build status
[23:05:51.523] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:32.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:8:0)
[23:06:32.156] Timer tick, asking Buildbucket for the build status
[23:06:32.367] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:32.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:10:0) after 1m0s
[23:07:32.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:10:0)
[23:07:32.388] Timer tick, asking Buildbucket for the build status
[23:07:32.551] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:32.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:11:0) after 1m0s
[23:08:32.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:11:0)
[23:08:32.550] Timer tick, asking Buildbucket for the build status
[23:08:32.716] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:32.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:12:0) after 1m0s
[23:09:32.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:12:0)
[23:09:32.734] Timer tick, asking Buildbucket for the build status
[23:09:32.841] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:32.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:13:0) after 1m0s
[23:10:32.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:13:0)
[23:10:32.864] Timer tick, asking Buildbucket for the build status
[23:10:32.976] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:32.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:14:0) after 1m0s
[23:11:32.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:14:0)
[23:11:32.955] Timer tick, asking Buildbucket for the build status
[23:11:33.098] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:33.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:15:0) after 1m0s
[23:12:33.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:15:0)
[23:12:33.118] Timer tick, asking Buildbucket for the build status
[23:12:33.296] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:33.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:16:0) after 1m0s
[23:13:33.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:16:0)
[23:13:33.317] Timer tick, asking Buildbucket for the build status
[23:13:33.532] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:33.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:17:0) after 1m0s
[23:14:33.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:17:0)
[23:14:33.583] Timer tick, asking Buildbucket for the build status
[23:14:33.727] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:33.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:18:0) after 1m0s
[23:15:33.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:18:0)
[23:15:33.746] Timer tick, asking Buildbucket for the build status
[23:15:34.058] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:34.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:19:0) after 1m0s
[23:16:34.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:19:0)
[23:16:34.085] Timer tick, asking Buildbucket for the build status
[23:16:34.184] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:34.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:20:0) after 1m0s
[23:17:34.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:20:0)
[23:17:34.194] Timer tick, asking Buildbucket for the build status
[23:17:34.311] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:34.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:21:0) after 1m0s
[23:18:34.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:21:0)
[23:18:34.331] Timer tick, asking Buildbucket for the build status
[23:18:34.708] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:34.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:22:0) after 1m0s
[23:19:34.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:22:0)
[23:19:34.711] Timer tick, asking Buildbucket for the build status
[23:19:34.790] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:34.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:23:0) after 1m0s
[23:20:34.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:23:0)
[23:20:34.820] Timer tick, asking Buildbucket for the build status
[23:20:34.966] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:34.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:24:0) after 1m0s
[23:21:34.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:24:0)
[23:21:34.977] Timer tick, asking Buildbucket for the build status
[23:21:35.210] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:35.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:25:0) after 1m0s
[23:22:35.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:25:0)
[23:22:35.234] Timer tick, asking Buildbucket for the build status
[23:22:35.925] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:35.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:26:0) after 1m0s
[23:23:35.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:26:0)
[23:23:35.948] Timer tick, asking Buildbucket for the build status
[23:23:36.072] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:36.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:27:0) after 1m0s
[23:24:36.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:27:0)
[23:24:36.094] Timer tick, asking Buildbucket for the build status
[23:24:36.197] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:36.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:28:0) after 1m0s
[23:25:36.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:28:0)
[23:25:36.181] Timer tick, asking Buildbucket for the build status
[23:25:36.659] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:36.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:29:0) after 1m0s
[23:26:36.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:29:0)
[23:26:36.693] Timer tick, asking Buildbucket for the build status
[23:26:37.221] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:37.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:30:0) after 1m0s
[23:27:37.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:30:0)
[23:27:37.249] Timer tick, asking Buildbucket for the build status
[23:27:37.844] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:37.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:31:0) after 1m0s
[23:28:37.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:31:0)
[23:28:37.863] Timer tick, asking Buildbucket for the build status
[23:28:38.721] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:38.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:32:0) after 1m0s
[23:29:38.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:32:0)
[23:29:38.713] Timer tick, asking Buildbucket for the build status
[23:29:38.838] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:38.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:33:0) after 1m0s
[23:30:38.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:33:0)
[23:30:38.867] Timer tick, asking Buildbucket for the build status
[23:30:39.195] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:39.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:34:0) after 1m0s
[23:31:39.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:34:0)
[23:31:39.210] Timer tick, asking Buildbucket for the build status
[23:31:39.686] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:39.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:35:0) after 1m0s
[23:32:39.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:35:0)
[23:32:39.685] Timer tick, asking Buildbucket for the build status
[23:32:39.794] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:39.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:36:0) after 1m0s
[23:33:39.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:36:0)
[23:33:39.808] Timer tick, asking Buildbucket for the build status
[23:33:40.291] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:40.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:37:0) after 1m0s
[23:34:40.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:37:0)
[23:34:40.311] Timer tick, asking Buildbucket for the build status
[23:34:40.524] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:40.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:38:0) after 1m0s
[23:35:40.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:38:0)
[23:35:40.517] Timer tick, asking Buildbucket for the build status
[23:35:41.329] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:41.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:39:0) after 1m0s
[23:36:41.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:39:0)
[23:36:41.354] Timer tick, asking Buildbucket for the build status
[23:36:41.615] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:41.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:40:0) after 1m0s
[23:37:41.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:40:0)
[23:37:41.643] Timer tick, asking Buildbucket for the build status
[23:37:42.104] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:42.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:41:0) after 1m0s
[23:38:42.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:41:0)
[23:38:42.139] Timer tick, asking Buildbucket for the build status
[23:38:42.435] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:42.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:42:0) after 1m0s
[23:39:42.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:42:0)
[23:39:42.454] Timer tick, asking Buildbucket for the build status
[23:39:42.630] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:42.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:43:0) after 1m0s
[23:40:42.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:43:0)
[23:40:42.653] Timer tick, asking Buildbucket for the build status
[23:40:43.276] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:43.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:44:0) after 1m0s
[23:41:43.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:44:0)
[23:41:43.297] Timer tick, asking Buildbucket for the build status
[23:41:43.443] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:43.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:45:0) after 1m0s
[23:42:43.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:45:0)
[23:42:43.437] Timer tick, asking Buildbucket for the build status
[23:42:43.642] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:43.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:46:0) after 1m0s
[23:43:43.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:46:0)
[23:43:43.715] Timer tick, asking Buildbucket for the build status
[23:43:43.839] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:43.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:47:0) after 1m0s
[23:44:43.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:47:0)
[23:44:43.864] Timer tick, asking Buildbucket for the build status
[23:44:44.129] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:44.129] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:48:0) after 1m0s
[23:45:44.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:48:0)
[23:45:44.113] Timer tick, asking Buildbucket for the build status
[23:45:44.352] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:44.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:49:0) after 1m0s
[23:46:44.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:49:0)
[23:46:44.389] Timer tick, asking Buildbucket for the build status
[23:46:45.344] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:45.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:50:0) after 1m0s
[23:47:45.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:50:0)
[23:47:45.317] Timer tick, asking Buildbucket for the build status
[23:47:45.457] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:45.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:51:0) after 1m0s
[23:48:45.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:51:0)
[23:48:45.442] Timer tick, asking Buildbucket for the build status
[23:48:45.561] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:45.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:52:0) after 1m0s
[23:49:45.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:52:0)
[23:49:45.584] Timer tick, asking Buildbucket for the build status
[23:49:45.711] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:45.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:53:0) after 1m0s
[23:50:45.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:53:0)
[23:50:45.721] Timer tick, asking Buildbucket for the build status
[23:50:45.864] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:45.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:54:0) after 1m0s
[23:51:45.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:54:0)
[23:51:45.883] Timer tick, asking Buildbucket for the build status
[23:51:46.927] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:46.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:55:0) after 1m0s
[23:52:46.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:55:0)
[23:52:46.957] Timer tick, asking Buildbucket for the build status
[23:52:47.809] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:47.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:56:0) after 1m0s
[23:53:47.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:56:0)
[23:53:47.833] Timer tick, asking Buildbucket for the build status
[23:53:48.655] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:48.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:57:0) after 1m0s
[23:54:48.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:57:0)
[23:54:48.643] Timer tick, asking Buildbucket for the build status
[23:54:48.911] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:48.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:58:0) after 1m0s
[23:55:48.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:58:0)
[23:55:48.933] Timer tick, asking Buildbucket for the build status
[23:55:49.220] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:49.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:59:0) after 1m0s
[23:56:49.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:59:0)
[23:56:49.268] Timer tick, asking Buildbucket for the build status
[23:56:49.477] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:49.477] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:60:0) after 1m0s
[23:57:49.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:60:0)
[23:57:49.502] Timer tick, asking Buildbucket for the build status
[23:57:49.972] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:49.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:61:0) after 1m0s
[23:58:49.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:61:0)
[23:58:49.991] Timer tick, asking Buildbucket for the build status
[23:58:51.630] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:51.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:62:0) after 1m0s
[23:59:51.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:62:0)
[23:59:51.651] Timer tick, asking Buildbucket for the build status
[23:59:51.901] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:51.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:63:0) after 1m0s
[00:00:51.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:63:0)
[00:00:51.923] Timer tick, asking Buildbucket for the build status
[00:00:52.063] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:52.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:64:0) after 1m0s
[00:01:52.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:64:0)
[00:01:52.132] Timer tick, asking Buildbucket for the build status
[00:01:53.120] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:53.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:65:0) after 1m0s
[00:02:53.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:65:0)
[00:02:53.120] Timer tick, asking Buildbucket for the build status
[00:02:53.570] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:53.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:66:0) after 1m0s
[00:03:53.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:66:0)
[00:03:53.552] Timer tick, asking Buildbucket for the build status
[00:03:53.696] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:53.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:67:0) after 1m0s
[00:04:53.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:67:0)
[00:04:53.722] Timer tick, asking Buildbucket for the build status
[00:04:54.227] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:54.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:68:0) after 1m0s
[00:05:54.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:68:0)
[00:05:54.254] Timer tick, asking Buildbucket for the build status
[00:05:54.577] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:54.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:69:0) after 1m0s
[00:06:54.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:69:0)
[00:06:54.582] Timer tick, asking Buildbucket for the build status
[00:06:54.887] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:54.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:70:0) after 1m0s
[00:07:54.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:70:0)
[00:07:54.911] Timer tick, asking Buildbucket for the build status
[00:07:56.014] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:56.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:71:0) after 1m0s
[00:08:56.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:71:0)
[00:08:56.027] Timer tick, asking Buildbucket for the build status
[00:08:56.749] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:56.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:72:0) after 1m0s
[00:09:56.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:72:0)
[00:09:56.847] Timer tick, asking Buildbucket for the build status
[00:09:57.228] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:57.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:73:0) after 1m0s
[00:10:57.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:73:0)
[00:10:57.212] Timer tick, asking Buildbucket for the build status
[00:10:57.437] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:57.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:74:0) after 1m0s
[00:11:57.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:74:0)
[00:11:57.463] Timer tick, asking Buildbucket for the build status
[00:11:58.135] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:58.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:75:0) after 1m0s
[00:12:58.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:75:0)
[00:12:58.148] Timer tick, asking Buildbucket for the build status
[00:12:58.267] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:58.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:76:0) after 1m0s
[00:13:58.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:76:0)
[00:13:58.262] Timer tick, asking Buildbucket for the build status
[00:13:58.699] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:58.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:77:0) after 1m0s
[00:14:58.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:77:0)
[00:14:58.720] Timer tick, asking Buildbucket for the build status
[00:14:59.623] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:59.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:78:0) after 1m0s
[00:15:59.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:78:0)
[00:15:59.648] Timer tick, asking Buildbucket for the build status
[00:15:59.764] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:59.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:79:0) after 1m0s
[00:16:59.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:79:0)
[00:16:59.827] Timer tick, asking Buildbucket for the build status
[00:17:00.111] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:00.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:80:0) after 1m0s
[00:18:00.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:80:0)
[00:18:00.104] Timer tick, asking Buildbucket for the build status
[00:18:00.317] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:00.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:81:0) after 1m0s
[00:19:00.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:81:0)
[00:19:00.371] Timer tick, asking Buildbucket for the build status
[00:19:00.505] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:00.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:82:0) after 1m0s
[00:20:00.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:82:0)
[00:20:00.521] Timer tick, asking Buildbucket for the build status
[00:20:01.516] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:01.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:83:0) after 1m0s
[00:21:01.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:83:0)
[00:21:01.569] Timer tick, asking Buildbucket for the build status
[00:21:01.806] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:01.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:84:0) after 1m0s
[00:22:01.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:84:0)
[00:22:01.832] Timer tick, asking Buildbucket for the build status
[00:22:01.966] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:01.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:85:0) after 1m0s
[00:23:01.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:85:0)
[00:23:01.940] Timer tick, asking Buildbucket for the build status
[00:23:02.202] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:02.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:86:0) after 1m0s
[00:24:02.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:86:0)
[00:24:02.225] Timer tick, asking Buildbucket for the build status
[00:24:02.615] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:02.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:87:0) after 1m0s
[00:25:02.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:87:0)
[00:25:02.613] Timer tick, asking Buildbucket for the build status
[00:25:02.753] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:02.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:88:0) after 1m0s
[00:26:02.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:88:0)
[00:26:02.816] Timer tick, asking Buildbucket for the build status
[00:26:03.094] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:03.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:89:0) after 1m0s
[00:27:03.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:89:0)
[00:27:03.117] Timer tick, asking Buildbucket for the build status
[00:27:03.281] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:03.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:90:0) after 1m0s
[00:28:03.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:90:0)
[00:28:03.305] Timer tick, asking Buildbucket for the build status
[00:28:03.673] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:03.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:91:0) after 1m0s
[00:29:03.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:91:0)
[00:29:03.694] Timer tick, asking Buildbucket for the build status
[00:29:03.794] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:03.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:92:0) after 1m0s
[00:30:03.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:92:0)
[00:30:03.814] Timer tick, asking Buildbucket for the build status
[00:30:03.954] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:03.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:93:0) after 1m0s
[00:31:03.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:93:0)
[00:31:03.983] Timer tick, asking Buildbucket for the build status
[00:31:04.109] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:04.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:94:0) after 1m0s
[00:32:04.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:94:0)
[00:32:04.155] Timer tick, asking Buildbucket for the build status
[00:32:04.455] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:04.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:95:0) after 1m0s
[00:33:04.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:95:0)
[00:33:04.450] Timer tick, asking Buildbucket for the build status
[00:33:04.741] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:04.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:96:0) after 1m0s
[00:34:04.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:96:0)
[00:34:04.771] Timer tick, asking Buildbucket for the build status
[00:34:05.007] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:05.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:97:0) after 1m0s
[00:35:05.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:97:0)
[00:35:05.033] Timer tick, asking Buildbucket for the build status
[00:35:05.447] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:05.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:98:0) after 1m0s
[00:36:05.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:98:0)
[00:36:05.474] Timer tick, asking Buildbucket for the build status
[00:36:06.063] Build 8917792276134461888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:06.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083222338197545296:99:0) after 1m0s
[00:36:35.867] Received PubSub notification, asking Buildbucket for the build status
[00:36:36.029] Build 8917792276134461888: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:36:36.030] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553733394704852",
"created_by": "project:chromium",
"created_ts": "1553727569412694",
"failure_reason": "BUILD_FAILURE",
"id": "8917792276134461888",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a7ae450d0d465647eaff6dec086bd3f1aabd2a7d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['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\": 11574, \"got_angle_revision\": \"518c679da10956a4c401214cf3ac304e09eb1a12\", \"got_dawn_revision\": \"5490e9aca17eb2f58633b00d823a3fc1e58f2b61\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"a7ae450d0d465647eaff6dec086bd3f1aabd2a7d\", \"got_revision_cp\": \"refs/heads/master@{#645057}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"3f1cb6302896e7c94df29234af337de08c0d12d7\", \"got_v8_revision_cp\": \"refs/heads/7.5.127@{#1}\", \"got_webrtc_revision\": \"ed2207abeedb16fe818bf91ea37b0b47a114f0f7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27325}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a7ae450d0d465647eaff6dec086bd3f1aabd2a7d\", \"swarm_hashes\": {\"accessibility_unittests\": \"26928b14f6eab432dc7d7ce91787b53bb5c6168b\", \"angle_unittests\": \"fe5e80bbfb158c5bff88bb18cb4b56320b9cada8\", \"app_list_unittests\": \"916076bb4da29ab0a2a493d3e3682c49fc2039f1\", \"app_shell_unittests\": \"f11de154c56534847cf6b54307aebeef38032fdc\", \"ash_unittests\": \"e8e08fc3193bc7ec989ae868cb650d928867bdf0\", \"aura_unittests\": \"e6530ce29a86a4ae8edac810c563a83fca395550\", \"base_unittests\": \"ecf6f5e5a8edf0a378525a4ed1d969e95a5ad752\", \"blink_common_unittests\": \"8a9fe528006025dfce58ead3c1ee975a7d41f0b9\", \"blink_fuzzer_unittests\": \"15746edcfaefb3f9aaaf19edc790c5f27897ee11\", \"blink_heap_unittests\": \"1771891cb7db5848458fd774971e765bd7bdcfa3\", \"blink_platform_unittests\": \"a19956095c1671b2c776006d27f975d910cee5cf\", \"blink_unittests\": \"4ada57c4da1d4a1b68a472cfc9a979940d970da6\", \"boringssl_crypto_tests\": \"114cc5aa8a379d0eef5d13d7e44d4e683de08dc5\", \"boringssl_ssl_tests\": \"294d6f00e233a5248644263a48bf2e729a4fa735\", \"browser_tests\": \"4416fcbbf109af3b418edce473f5444a1e32f090\", \"cacheinvalidation_unittests\": \"e9e9d02f07b81f41659fbd04e64e410a9a7a69e7\", \"capture_unittests\": \"76f87a8af4000ffaf7516a96558cdcbee9f1dc40\", \"cast_unittests\": \"775e578cd01b458bd7a57b0b7068a1e461b28573\", \"cc_unittests\": \"4dbc174cc0040580cace1df01f401823af6619f9\", \"chrome_app_unittests\": \"d86409c616ee504b0ac482e41122d6e9129d0f19\", \"chromedriver_unittests\": \"3518fd53b00ed77249bb88efd307f4308cb4f582\", \"chromeos_components_unittests\": \"f661bf9a1919d6f88f580f0cc11c6f727a795ec0\", \"chromeos_unittests\": \"32c0da3467ec9b42fff1732158a0e9931fab6c07\", \"components_browsertests\": \"1c41d92da3c9698dac5a3fa8b2754f20fbd52752\", \"components_unittests\": \"4cac4e6adbb1f9246ee36ce810a774736d249e89\", \"compositor_unittests\": \"0b68544078463e614e4e32f2808c555b8f5a88e4\", \"content_browsertests\": \"edf081c6259fd7c0b94be17802510fc96e54ce56\", \"content_unittests\": \"7f0a578437780e75ef01f360179a26cbb97df023\", \"crypto_unittests\": \"a37566a163967a0764f271f3e36c84cdb1ca0114\", \"dbus_unittests\": \"56567ce1b8dc1071de6713046cbc56860dfa25c9\", \"device_unittests\": \"6d4e49982c20dd2598a9b26980240753d3d9a326\", \"display_unittests\": \"6cc47eae93a0acabb6c864be227acce15a990425\", \"events_unittests\": \"4faa7843e69fafa8492d6da7696fa688e63e1ceb\", \"exo_unittests\": \"62b5c6413935d9f3770d5c6bfb3837fea78564ab\", \"extensions_browsertests\": \"b7fbf797c2244317d7eb792c7d920bfb7a43b72d\", \"extensions_unittests\": \"223972e777454d5da83389134750710d526116b3\", \"filesystem_service_unittests\": \"5caf5d15da44e9646a37a63851bd99da6a14487f\", \"gcm_unit_tests\": \"022b69cd495bbb71aade1237eff030b9ebba72a3\", \"gfx_unittests\": \"f0687601bdc76de03872d14afcf94d7b96756d52\", \"gin_unittests\": \"39bae9184c995995c0ad37297974886232f8e02c\", \"gl_unittests_ozone\": \"9546e63469edb251e95a650da2aae66a9e3008a7\", \"google_apis_unittests\": \"2de5740225228b8dad627fe01c585b178d76a9dc\", \"gpu_unittests\": \"894abde6b715fad859661bb6c3d23293a4d8e94f\", \"interactive_ui_tests\": \"3f4d678dc414f97ef2e0b9e23f88988c085a9b71\", \"ipc_tests\": \"628e8548b8b171a593463ce2c49a21ed50c05e44\", \"jingle_unittests\": \"9038ddf472a85029458f40ed42b172a93d2966c4\", \"keyboard_unittests\": \"9abbf511c082814cfaf1254c230564e5183d93e6\", \"latency_unittests\": \"e3fba2bfd8a73ec5d1dd10ff8133cafaa91c7ba2\", \"leveldb_service_unittests\": \"37ed6df2ceb4fd72d12af000f09d7259051f252b\", \"libjingle_xmpp_unittests\": \"91f12ba4528a5214ba27a8255d10a23cc13c1fd5\", \"media_blink_unittests\": \"23390461ffff1b5d6c3804ade1fb791e9de1c794\", \"media_service_unittests\": \"449e31fb702f9ae449e80f402715dd37071ccb8f\", \"media_unittests\": \"2350ded3c213252c583efacd996bb10e2901cfd4\", \"message_center_unittests\": \"24531c02c4d53d0116f307dd6911039cc28069a5\", \"midi_unittests\": \"953de307de9cc72d24a9578cebee469a45ea7021\", \"mojo_core_unittests\": \"db347681f0aaf25ea07dc3979de65af08bae58b1\", \"mojo_unittests\": \"bc6b2bb2e7108b7d4f2b498a98aa46c903489ef6\", \"nacl_helper_nonsfi_unittests\": \"4d0ea0e128624ed500045b530f2711be15548ace\", \"nacl_loader_unittests\": \"3e5d408c3706f631e91e467b83c20e37c351e423\", \"native_theme_unittests\": \"289424623b1ea549969e86f5ae6c51874f3469ff\", \"net_unittests\": \"7a3fdf256f5f8c195419f896e70dd83ca3d1b1bd\", \"ozone_gl_unittests\": \"35c89e910883d96ca3bc6a9e0284cc30e6264b04\", \"ozone_unittests\": \"f29e847c638b6d2131fec6b4a705414a94055172\", \"ozone_x11_unittests\": \"d1ea56ee261d6f98cdf28b11c9d4b691f8e369ba\", \"pdf_unittests\": \"70f599133e5ba12ae448dda6aebfd05d4f790aae\", \"perfetto_unittests\": \"88330a8bb9b45946edbdd375aba2e1bdc39455c8\", \"ppapi_unittests\": \"91564c60cafea3cd59b9c1021d33a2caf03d60da\", \"printing_unittests\": \"2a15f6f80cc69cfff6ea8039c31e8be5eccee56b\", \"remoting_unittests\": \"bfed96c175a70041b6e3b82f5275d764650d4b1f\", \"sandbox_linux_unittests\": \"250ea983ede38de2a228e32265b0a670bdec580e\", \"service_manager_unittests\": \"d0602ff7860d1919400d0f11929185b99e3e655d\", \"services_unittests\": \"ac9e5301051cb1558a9805becc726aa33f95bc99\", \"shell_dialogs_unittests\": \"76576c6020b67de56fe90e6e65cc2d9f49d26811\", \"skia_unittests\": \"a6c7970d8d340a5053c9bf29531c1f16e4184104\", \"snapshot_unittests\": \"24d453304e6e10a8601de2a8f1e5f64b6e886fc0\", \"sql_unittests\": \"930871529fc73e20925462574ddeac340e84c79b\", \"storage_unittests\": \"39da9ab5345d783c091ab85db850a36b3c34c64e\", \"sync_integration_tests\": \"bd4ad7dcecd9a46e58e18398e400fe1a2b8f76ce\", \"traffic_annotation_auditor_unittests\": \"e9b46dcec0fe9f96200dd6ae96cc274b7c9b9cb6\", \"ui_base_unittests\": \"6a35f2673625b248d448ee90d319b5bf5e3acf44\", \"ui_chromeos_unittests\": \"1f67586ad308ec1a80ee5e2ddd8bb8bce3da3adf\", \"ui_touch_selection_unittests\": \"debf81c782f439d6868bb8a11dc05b7c2ed7dc2c\", \"unit_tests\": \"efe72b3fc7549501003be8851a4f619ea66a8c7b\", \"url_unittests\": \"232edca91755f778a7f95e12aa325be45d4cd90e\", \"usage_time_limit_unittests\": \"650279eafb31ece5ea03473b944934dce2d8e89e\", \"views_unittests\": \"30e9f6487df25b5c609a3171d157965dc7323a55\", \"viz_unittests\": \"1f0b81d46b9da85269a0757397326b85044c17fa\", \"wm_unittests\": \"68da9f3a433eaa3c20ca635d954bd96696ff8693\", \"wtf_unittests\": \"875de2dd755ea49602ec270b428178edf01d5f59\"}, \"swarm_hashes_refs/heads/master(at){#645057}_without_patch\": {\"accessibility_unittests\": \"26928b14f6eab432dc7d7ce91787b53bb5c6168b\", \"angle_unittests\": \"fe5e80bbfb158c5bff88bb18cb4b56320b9cada8\", \"app_list_unittests\": \"916076bb4da29ab0a2a493d3e3682c49fc2039f1\", \"app_shell_unittests\": \"f11de154c56534847cf6b54307aebeef38032fdc\", \"ash_unittests\": \"e8e08fc3193bc7ec989ae868cb650d928867bdf0\", \"aura_unittests\": \"e6530ce29a86a4ae8edac810c563a83fca395550\", \"base_unittests\": \"ecf6f5e5a8edf0a378525a4ed1d969e95a5ad752\", \"blink_common_unittests\": \"8a9fe528006025dfce58ead3c1ee975a7d41f0b9\", \"blink_fuzzer_unittests\": \"15746edcfaefb3f9aaaf19edc790c5f27897ee11\", \"blink_heap_unittests\": \"1771891cb7db5848458fd774971e765bd7bdcfa3\", \"blink_platform_unittests\": \"a19956095c1671b2c776006d27f975d910cee5cf\", \"blink_unittests\": \"4ada57c4da1d4a1b68a472cfc9a979940d970da6\", \"boringssl_crypto_tests\": \"114cc5aa8a379d0eef5d13d7e44d4e683de08dc5\", \"boringssl_ssl_tests\": \"294d6f00e233a5248644263a48bf2e729a4fa735\", \"browser_tests\": \"4416fcbbf109af3b418edce473f5444a1e32f090\", \"cacheinvalidation_unittests\": \"e9e9d02f07b81f41659fbd04e64e410a9a7a69e7\", \"capture_unittests\": \"76f87a8af4000ffaf7516a96558cdcbee9f1dc40\", \"cast_unittests\": \"775e578cd01b458bd7a57b0b7068a1e461b28573\", \"cc_unittests\": \"4dbc174cc0040580cace1df01f401823af6619f9\", \"chrome_app_unittests\": \"d86409c616ee504b0ac482e41122d6e9129d0f19\", \"chromedriver_unittests\": \"3518fd53b00ed77249bb88efd307f4308cb4f582\", \"chromeos_components_unittests\": \"f661bf9a1919d6f88f580f0cc11c6f727a795ec0\", \"chromeos_unittests\": \"32c0da3467ec9b42fff1732158a0e9931fab6c07\", \"components_browsertests\": \"1c41d92da3c9698dac5a3fa8b2754f20fbd52752\", \"components_unittests\": \"4cac4e6adbb1f9246ee36ce810a774736d249e89\", \"compositor_unittests\": \"0b68544078463e614e4e32f2808c555b8f5a88e4\", \"content_browsertests\": \"edf081c6259fd7c0b94be17802510fc96e54ce56\", \"content_unittests\": \"7f0a578437780e75ef01f360179a26cbb97df023\", \"crypto_unittests\": \"a37566a163967a0764f271f3e36c84cdb1ca0114\", \"dbus_unittests\": \"56567ce1b8dc1071de6713046cbc56860dfa25c9\", \"device_unittests\": \"6d4e49982c20dd2598a9b26980240753d3d9a326\", \"display_unittests\": \"6cc47eae93a0acabb6c864be227acce15a990425\", \"events_unittests\": \"4faa7843e69fafa8492d6da7696fa688e63e1ceb\", \"exo_unittests\": \"62b5c6413935d9f3770d5c6bfb3837fea78564ab\", \"extensions_browsertests\": \"b7fbf797c2244317d7eb792c7d920bfb7a43b72d\", \"extensions_unittests\": \"223972e777454d5da83389134750710d526116b3\", \"filesystem_service_unittests\": \"5caf5d15da44e9646a37a63851bd99da6a14487f\", \"gcm_unit_tests\": \"022b69cd495bbb71aade1237eff030b9ebba72a3\", \"gfx_unittests\": \"f0687601bdc76de03872d14afcf94d7b96756d52\", \"gin_unittests\": \"39bae9184c995995c0ad37297974886232f8e02c\", \"gl_unittests_ozone\": \"9546e63469edb251e95a650da2aae66a9e3008a7\", \"google_apis_unittests\": \"2de5740225228b8dad627fe01c585b178d76a9dc\", \"gpu_unittests\": \"894abde6b715fad859661bb6c3d23293a4d8e94f\", \"interactive_ui_tests\": \"3f4d678dc414f97ef2e0b9e23f88988c085a9b71\", \"ipc_tests\": \"628e8548b8b171a593463ce2c49a21ed50c05e44\", \"jingle_unittests\": \"9038ddf472a85029458f40ed42b172a93d2966c4\", \"keyboard_unittests\": \"9abbf511c082814cfaf1254c230564e5183d93e6\", \"latency_unittests\": \"e3fba2bfd8a73ec5d1dd10ff8133cafaa91c7ba2\", \"leveldb_service_unittests\": \"37ed6df2ceb4fd72d12af000f09d7259051f252b\", \"libjingle_xmpp_unittests\": \"91f12ba4528a5214ba27a8255d10a23cc13c1fd5\", \"media_blink_unittests\": \"23390461ffff1b5d6c3804ade1fb791e9de1c794\", \"media_service_unittests\": \"449e31fb702f9ae449e80f402715dd37071ccb8f\", \"media_unittests\": \"2350ded3c213252c583efacd996bb10e2901cfd4\", \"message_center_unittests\": \"24531c02c4d53d0116f307dd6911039cc28069a5\", \"midi_unittests\": \"953de307de9cc72d24a9578cebee469a45ea7021\", \"mojo_core_unittests\": \"db347681f0aaf25ea07dc3979de65af08bae58b1\", \"mojo_unittests\": \"bc6b2bb2e7108b7d4f2b498a98aa46c903489ef6\", \"nacl_helper_nonsfi_unittests\": \"4d0ea0e128624ed500045b530f2711be15548ace\", \"nacl_loader_unittests\": \"3e5d408c3706f631e91e467b83c20e37c351e423\", \"native_theme_unittests\": \"289424623b1ea549969e86f5ae6c51874f3469ff\", \"net_unittests\": \"7a3fdf256f5f8c195419f896e70dd83ca3d1b1bd\", \"ozone_gl_unittests\": \"35c89e910883d96ca3bc6a9e0284cc30e6264b04\", \"ozone_unittests\": \"f29e847c638b6d2131fec6b4a705414a94055172\", \"ozone_x11_unittests\": \"d1ea56ee261d6f98cdf28b11c9d4b691f8e369ba\", \"pdf_unittests\": \"70f599133e5ba12ae448dda6aebfd05d4f790aae\", \"perfetto_unittests\": \"88330a8bb9b45946edbdd375aba2e1bdc39455c8\", \"ppapi_unittests\": \"91564c60cafea3cd59b9c1021d33a2caf03d60da\", \"printing_unittests\": \"2a15f6f80cc69cfff6ea8039c31e8be5eccee56b\", \"remoting_unittests\": \"bfed96c175a70041b6e3b82f5275d764650d4b1f\", \"sandbox_linux_unittests\": \"250ea983ede38de2a228e32265b0a670bdec580e\", \"service_manager_unittests\": \"d0602ff7860d1919400d0f11929185b99e3e655d\", \"services_unittests\": \"ac9e5301051cb1558a9805becc726aa33f95bc99\", \"shell_dialogs_unittests\": \"76576c6020b67de56fe90e6e65cc2d9f49d26811\", \"skia_unittests\": \"a6c7970d8d340a5053c9bf29531c1f16e4184104\", \"snapshot_unittests\": \"24d453304e6e10a8601de2a8f1e5f64b6e886fc0\", \"sql_unittests\": \"930871529fc73e20925462574ddeac340e84c79b\", \"storage_unittests\": \"39da9ab5345d783c091ab85db850a36b3c34c64e\", \"sync_integration_tests\": \"bd4ad7dcecd9a46e58e18398e400fe1a2b8f76ce\", \"traffic_annotation_auditor_unittests\": \"e9b46dcec0fe9f96200dd6ae96cc274b7c9b9cb6\", \"ui_base_unittests\": \"6a35f2673625b248d448ee90d319b5bf5e3acf44\", \"ui_chromeos_unittests\": \"1f67586ad308ec1a80ee5e2ddd8bb8bce3da3adf\", \"ui_touch_selection_unittests\": \"debf81c782f439d6868bb8a11dc05b7c2ed7dc2c\", \"unit_tests\": \"efe72b3fc7549501003be8851a4f619ea66a8c7b\", \"url_unittests\": \"232edca91755f778a7f95e12aa325be45d4cd90e\", \"usage_time_limit_unittests\": \"650279eafb31ece5ea03473b944934dce2d8e89e\", \"views_unittests\": \"30e9f6487df25b5c609a3171d157965dc7323a55\", \"viz_unittests\": \"1f0b81d46b9da85269a0757397326b85044c17fa\", \"wm_unittests\": \"68da9f3a433eaa3c20ca635d954bd96696ff8693\", \"wtf_unittests\": \"875de2dd755ea49602ec270b428178edf01d5f59\"}}, \"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\": [\"4221-9c50278\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 tests failed: ['non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553727950839762",
"status": "COMPLETED",
"status_changed_ts": "1553727951077276",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11574",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a7ae450d0d465647eaff6dec086bd3f1aabd2a7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7ae450d0d465647eaff6dec086bd3f1aabd2a7d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083222338197545296",
"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/8917792276134461888/+/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:43da32c911c51d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553733395112930",
"url": "https://ci.chromium.org/b/8917792276134461888",
"utcnow_ts": "1553733396015090"
}
}
[00:36:36.030] Invocation finished in 1h37m7.968403099s with status FAILED