[22:58:56.147] New invocation is queued and will start shortly
[22:58:57.227] Starting the invocation (attempt 1)
[22:58:57.264] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:58:57.265] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:58:57.265] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078964314259258880",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3c9a4cd0515dec14e1923e05e98ec3da0ce27a58\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9078964314259258880",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/3c9a4cd0515dec14e1923e05e98ec3da0ce27a58",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c9a4cd0515dec14e1923e05e98ec3da0ce27a58",
"gitiles_ref:refs/heads/master"
]
}
[22:58:58.162] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557788337406291",
"id": "8913534252274925568",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3c9a4cd0515dec14e1923e05e98ec3da0ce27a58\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557788338029876",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12374",
"builder:linux-chromeos-dbg",
"buildset:commit/git/3c9a4cd0515dec14e1923e05e98ec3da0ce27a58",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c9a4cd0515dec14e1923e05e98ec3da0ce27a58",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078964314259258880",
"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/8913534252274925568/+/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": "1557788338030020",
"url": "https://ci.chromium.org/b/8913534252274925568",
"utcnow_ts": "1557788338153746"
}
}
[22:58:58.162] Task URL: https://ci.chromium.org/b/8913534252274925568
[22:58:58.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:2:0) after 1m0s
[22:59:58.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:2:0)
[22:59:58.190] Timer tick, asking Buildbucket for the build status
[22:59:58.444] Build 8913534252274925568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:58.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:3:0) after 1m0s
[23:00:58.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:3:0)
[23:00:58.478] Timer tick, asking Buildbucket for the build status
[23:00:59.327] Build 8913534252274925568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:59.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:4:0) after 1m0s
[23:01:59.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:4:0)
[23:01:59.353] Timer tick, asking Buildbucket for the build status
[23:01:59.497] Build 8913534252274925568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:59.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:5:0) after 1m0s
[23:02:59.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:5:0)
[23:02:59.573] Timer tick, asking Buildbucket for the build status
[23:02:59.778] Build 8913534252274925568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:59.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:6:0) after 1m0s
[23:03:59.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:6:0)
[23:03:59.796] Timer tick, asking Buildbucket for the build status
[23:03:59.913] Build 8913534252274925568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:59.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:7:0) after 1m0s
[23:04:32.015] Received PubSub notification, asking Buildbucket for the build status
[23:04:32.260] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:59.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:7:0)
[23:04:59.938] Timer tick, asking Buildbucket for the build status
[23:05:00.224] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:00.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:9:0) after 1m0s
[23:06:00.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:9:0)
[23:06:00.249] Timer tick, asking Buildbucket for the build status
[23:06:00.653] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:00.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:10:0) after 1m0s
[23:07:00.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:10:0)
[23:07:00.672] Timer tick, asking Buildbucket for the build status
[23:07:01.662] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:01.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:11:0) after 1m0s
[23:08:01.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:11:0)
[23:08:01.690] Timer tick, asking Buildbucket for the build status
[23:08:04.804] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:04.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:12:0) after 1m0s
[23:09:04.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:12:0)
[23:09:04.823] Timer tick, asking Buildbucket for the build status
[23:09:05.686] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:05.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:13:0) after 1m0s
[23:10:05.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:13:0)
[23:10:05.715] Timer tick, asking Buildbucket for the build status
[23:10:07.169] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:07.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:14:0) after 1m0s
[23:11:07.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:14:0)
[23:11:07.172] Timer tick, asking Buildbucket for the build status
[23:11:07.535] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:07.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:15:0) after 1m0s
[23:12:07.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:15:0)
[23:12:07.530] Timer tick, asking Buildbucket for the build status
[23:12:14.281] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:14.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:16:0) after 1m0s
[23:13:14.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:16:0)
[23:13:14.277] Timer tick, asking Buildbucket for the build status
[23:13:14.402] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:14.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:17:0) after 1m0s
[23:14:14.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:17:0)
[23:14:14.391] Timer tick, asking Buildbucket for the build status
[23:14:15.890] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:15.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:18:0) after 1m0s
[23:15:15.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:18:0)
[23:15:15.872] Timer tick, asking Buildbucket for the build status
[23:15:16.138] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:16.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:19:0) after 1m0s
[23:16:16.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:19:0)
[23:16:16.146] Timer tick, asking Buildbucket for the build status
[23:16:16.668] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:16.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:20:0) after 1m0s
[23:17:16.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:20:0)
[23:17:16.671] Timer tick, asking Buildbucket for the build status
[23:17:16.921] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:16.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:21:0) after 1m0s
[23:18:16.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:21:0)
[23:18:16.922] Timer tick, asking Buildbucket for the build status
[23:18:17.069] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:17.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:22:0) after 1m0s
[23:19:17.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:22:0)
[23:19:17.050] Timer tick, asking Buildbucket for the build status
[23:19:20.250] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:20.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:23:0) after 1m0s
[23:20:20.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:23:0)
[23:20:20.252] Timer tick, asking Buildbucket for the build status
[23:20:20.374] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:20.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:24:0) after 1m0s
[23:21:20.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:24:0)
[23:21:20.395] Timer tick, asking Buildbucket for the build status
[23:21:20.507] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:20.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:25:0) after 1m0s
[23:22:20.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:25:0)
[23:22:20.528] Timer tick, asking Buildbucket for the build status
[23:22:20.917] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:20.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:26:0) after 1m0s
[23:23:20.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:26:0)
[23:23:20.917] Timer tick, asking Buildbucket for the build status
[23:23:21.677] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:21.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:27:0) after 1m0s
[23:24:21.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:27:0)
[23:24:21.681] Timer tick, asking Buildbucket for the build status
[23:24:21.791] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:21.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:28:0) after 1m0s
[23:25:21.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:28:0)
[23:25:21.788] Timer tick, asking Buildbucket for the build status
[23:25:23.758] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:23.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:29:0) after 1m0s
[23:26:23.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:29:0)
[23:26:23.751] Timer tick, asking Buildbucket for the build status
[23:26:24.261] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:24.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:30:0) after 1m0s
[23:27:24.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:30:0)
[23:27:24.282] Timer tick, asking Buildbucket for the build status
[23:27:27.076] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:27.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:31:0) after 1m0s
[23:28:27.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:31:0)
[23:28:27.097] Timer tick, asking Buildbucket for the build status
[23:28:27.277] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:27.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:32:0) after 1m0s
[23:29:27.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:32:0)
[23:29:27.330] Timer tick, asking Buildbucket for the build status
[23:29:27.459] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:27.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:33:0) after 1m0s
[23:30:27.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:33:0)
[23:30:27.477] Timer tick, asking Buildbucket for the build status
[23:30:29.728] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:29.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:34:0) after 1m0s
[23:31:29.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:34:0)
[23:31:29.730] Timer tick, asking Buildbucket for the build status
[23:31:29.986] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:29.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:35:0) after 1m0s
[23:32:30.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:35:0)
[23:32:30.013] Timer tick, asking Buildbucket for the build status
[23:32:31.498] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:31.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:36:0) after 1m0s
[23:33:31.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:36:0)
[23:33:31.567] Timer tick, asking Buildbucket for the build status
[23:33:31.834] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:31.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:37:0) after 1m0s
[23:34:31.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:37:0)
[23:34:31.810] Timer tick, asking Buildbucket for the build status
[23:34:32.093] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:32.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:38:0) after 1m0s
[23:35:32.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:38:0)
[23:35:32.075] Timer tick, asking Buildbucket for the build status
[23:35:32.613] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:32.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:39:0) after 1m0s
[23:36:32.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:39:0)
[23:36:32.626] Timer tick, asking Buildbucket for the build status
[23:36:32.823] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:32.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:40:0) after 1m0s
[23:37:32.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:40:0)
[23:37:32.842] Timer tick, asking Buildbucket for the build status
[23:37:33.072] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:33.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:41:0) after 1m0s
[23:38:33.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:41:0)
[23:38:33.062] Timer tick, asking Buildbucket for the build status
[23:38:33.198] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:33.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:42:0) after 1m0s
[23:39:33.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:42:0)
[23:39:33.221] Timer tick, asking Buildbucket for the build status
[23:39:33.563] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:33.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:43:0) after 1m0s
[23:40:33.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:43:0)
[23:40:33.591] Timer tick, asking Buildbucket for the build status
[23:40:36.459] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:36.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:44:0) after 1m0s
[23:41:36.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:44:0)
[23:41:36.490] Timer tick, asking Buildbucket for the build status
[23:41:36.713] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:36.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:45:0) after 1m0s
[23:42:36.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:45:0)
[23:42:36.746] Timer tick, asking Buildbucket for the build status
[23:42:37.231] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:37.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:46:0) after 1m0s
[23:43:37.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:46:0)
[23:43:37.210] Timer tick, asking Buildbucket for the build status
[23:43:37.381] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:37.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:47:0) after 1m0s
[23:44:37.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:47:0)
[23:44:37.428] Timer tick, asking Buildbucket for the build status
[23:44:37.578] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:37.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:48:0) after 1m0s
[23:45:37.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:48:0)
[23:45:37.562] Timer tick, asking Buildbucket for the build status
[23:45:37.773] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:37.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:49:0) after 1m0s
[23:46:37.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:49:0)
[23:46:37.799] Timer tick, asking Buildbucket for the build status
[23:46:37.952] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:37.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:50:0) after 1m0s
[23:47:37.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:50:0)
[23:47:37.973] Timer tick, asking Buildbucket for the build status
[23:47:38.187] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:38.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:51:0) after 1m0s
[23:48:38.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:51:0)
[23:48:38.297] Timer tick, asking Buildbucket for the build status
[23:48:39.186] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:39.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:52:0) after 1m0s
[23:49:39.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:52:0)
[23:49:39.213] Timer tick, asking Buildbucket for the build status
[23:49:39.419] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:39.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:53:0) after 1m0s
[23:50:39.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:53:0)
[23:50:39.507] Timer tick, asking Buildbucket for the build status
[23:50:39.750] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:39.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:54:0) after 1m0s
[23:51:39.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:54:0)
[23:51:39.735] Timer tick, asking Buildbucket for the build status
[23:51:40.195] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:40.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:55:0) after 1m0s
[23:52:40.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:55:0)
[23:52:40.206] Timer tick, asking Buildbucket for the build status
[23:52:40.463] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:40.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:56:0) after 1m0s
[23:53:40.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:56:0)
[23:53:40.478] Timer tick, asking Buildbucket for the build status
[23:53:40.764] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:40.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:57:0) after 1m0s
[23:54:40.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:57:0)
[23:54:40.766] Timer tick, asking Buildbucket for the build status
[23:54:41.122] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:41.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:58:0) after 1m0s
[23:55:41.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:58:0)
[23:55:41.327] Timer tick, asking Buildbucket for the build status
[23:55:41.453] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:41.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:59:0) after 1m0s
[23:56:41.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:59:0)
[23:56:41.471] Timer tick, asking Buildbucket for the build status
[23:56:41.582] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:41.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:60:0) after 1m0s
[23:57:41.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:60:0)
[23:57:41.628] Timer tick, asking Buildbucket for the build status
[23:57:41.907] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:41.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:61:0) after 1m0s
[23:58:43.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:61:0)
[23:58:43.166] Timer tick, asking Buildbucket for the build status
[23:58:43.447] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:62:0) after 1m0s
[23:59:43.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:62:0)
[23:59:43.445] Timer tick, asking Buildbucket for the build status
[23:59:44.029] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:44.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:63:0) after 1m0s
[00:00:44.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:63:0)
[00:00:44.009] Timer tick, asking Buildbucket for the build status
[00:00:44.637] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:44.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:64:0) after 1m0s
[00:01:44.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:64:0)
[00:01:44.615] Timer tick, asking Buildbucket for the build status
[00:01:45.014] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:45.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:65:0) after 1m0s
[00:02:45.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:65:0)
[00:02:45.037] Timer tick, asking Buildbucket for the build status
[00:02:45.788] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:45.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:66:0) after 1m0s
[00:03:45.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:66:0)
[00:03:45.785] Timer tick, asking Buildbucket for the build status
[00:03:47.959] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:47.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:67:0) after 1m0s
[00:04:47.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:67:0)
[00:04:47.981] Timer tick, asking Buildbucket for the build status
[00:04:50.065] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:50.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:68:0) after 1m0s
[00:05:50.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:68:0)
[00:05:50.101] Timer tick, asking Buildbucket for the build status
[00:05:50.419] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:50.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:69:0) after 1m0s
[00:06:50.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:69:0)
[00:06:50.439] Timer tick, asking Buildbucket for the build status
[00:06:50.649] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:50.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:70:0) after 1m0s
[00:07:50.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:70:0)
[00:07:50.668] Timer tick, asking Buildbucket for the build status
[00:07:52.290] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:52.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:71:0) after 1m0s
[00:08:52.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:71:0)
[00:08:52.320] Timer tick, asking Buildbucket for the build status
[00:08:52.533] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:52.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:72:0) after 1m0s
[00:09:52.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:72:0)
[00:09:52.529] Timer tick, asking Buildbucket for the build status
[00:09:53.509] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:53.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:73:0) after 1m0s
[00:10:53.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:73:0)
[00:10:53.573] Timer tick, asking Buildbucket for the build status
[00:10:55.520] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:55.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:74:0) after 1m0s
[00:11:55.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:74:0)
[00:11:55.662] Timer tick, asking Buildbucket for the build status
[00:11:57.958] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:57.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:75:0) after 1m0s
[00:12:58.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:75:0)
[00:12:58.065] Timer tick, asking Buildbucket for the build status
[00:12:59.093] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:59.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:76:0) after 1m0s
[00:13:59.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:76:0)
[00:13:59.125] Timer tick, asking Buildbucket for the build status
[00:14:01.491] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:01.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:77:0) after 1m0s
[00:15:01.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:77:0)
[00:15:01.668] Timer tick, asking Buildbucket for the build status
[00:15:05.886] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:05.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:78:0) after 1m0s
[00:16:05.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:78:0)
[00:16:05.910] Timer tick, asking Buildbucket for the build status
[00:16:07.148] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:07.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:79:0) after 1m0s
[00:17:07.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:79:0)
[00:17:07.180] Timer tick, asking Buildbucket for the build status
[00:17:07.468] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:07.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:80:0) after 1m0s
[00:18:07.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:80:0)
[00:18:07.726] Timer tick, asking Buildbucket for the build status
[00:18:08.696] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:08.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:81:0) after 1m0s
[00:19:08.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:81:0)
[00:19:08.710] Timer tick, asking Buildbucket for the build status
[00:19:09.000] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:09.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:82:0) after 1m0s
[00:20:09.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:82:0)
[00:20:09.023] Timer tick, asking Buildbucket for the build status
[00:20:11.420] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:11.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:83:0) after 1m0s
[00:21:11.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:83:0)
[00:21:11.435] Timer tick, asking Buildbucket for the build status
[00:21:11.875] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:11.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:84:0) after 1m0s
[00:22:11.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:84:0)
[00:22:11.865] Timer tick, asking Buildbucket for the build status
[00:22:12.266] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:12.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:85:0) after 1m0s
[00:23:12.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:85:0)
[00:23:12.289] Timer tick, asking Buildbucket for the build status
[00:23:13.839] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:13.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:86:0) after 1m0s
[00:24:13.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:86:0)
[00:24:13.886] Timer tick, asking Buildbucket for the build status
[00:24:15.574] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:15.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:87:0) after 1m0s
[00:25:15.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:87:0)
[00:25:15.703] Timer tick, asking Buildbucket for the build status
[00:25:16.532] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:16.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:88:0) after 1m0s
[00:26:16.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:88:0)
[00:26:16.552] Timer tick, asking Buildbucket for the build status
[00:26:18.933] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:18.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:89:0) after 1m0s
[00:27:18.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:89:0)
[00:27:18.963] Timer tick, asking Buildbucket for the build status
[00:27:19.630] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:19.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:90:0) after 1m0s
[00:28:19.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:90:0)
[00:28:19.605] Timer tick, asking Buildbucket for the build status
[00:28:19.900] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:19.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:91:0) after 1m0s
[00:29:19.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:91:0)
[00:29:19.921] Timer tick, asking Buildbucket for the build status
[00:29:20.739] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:20.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:92:0) after 1m0s
[00:30:20.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:92:0)
[00:30:20.799] Timer tick, asking Buildbucket for the build status
[00:30:21.333] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:21.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:93:0) after 1m0s
[00:31:21.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:93:0)
[00:31:21.328] Timer tick, asking Buildbucket for the build status
[00:31:23.609] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:23.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:94:0) after 1m0s
[00:32:23.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:94:0)
[00:32:23.641] Timer tick, asking Buildbucket for the build status
[00:32:23.770] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:23.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:95:0) after 1m0s
[00:33:23.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:95:0)
[00:33:23.753] Timer tick, asking Buildbucket for the build status
[00:33:24.461] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:24.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:96:0) after 1m0s
[00:34:24.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:96:0)
[00:34:24.488] Timer tick, asking Buildbucket for the build status
[00:34:27.506] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:27.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:97:0) after 1m0s
[00:35:27.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:97:0)
[00:35:27.487] Timer tick, asking Buildbucket for the build status
[00:35:29.058] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:29.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:98:0) after 1m0s
[00:36:29.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:98:0)
[00:36:29.059] Timer tick, asking Buildbucket for the build status
[00:36:29.579] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:29.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:99:0) after 1m0s
[00:37:29.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:99:0)
[00:37:29.606] Timer tick, asking Buildbucket for the build status
[00:37:30.003] Build 8913534252274925568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:30.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9078964314259258880:100:0) after 1m0s
[00:38:26.776] Received PubSub notification, asking Buildbucket for the build status
[00:38:29.075] Build 8913534252274925568: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:38:29.075] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1557794304825615",
"created_by": "project:chromium",
"created_ts": "1557788337406291",
"failure_reason": "BUILD_FAILURE",
"id": "8913534252274925568",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3c9a4cd0515dec14e1923e05e98ec3da0ce27a58\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"4 tests failed: ['ash_unittests', 'exo_unittests', 'unit_tests', 'chromeos_components_unittests']\"}, \"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\": 12374, \"got_angle_revision\": \"0a1eeb80641a30db9b27bafc78e0fcfd6ac792c1\", \"got_dawn_revision\": \"97f08fa2e68f3505384395a8b6594fb70bdec6a3\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"3c9a4cd0515dec14e1923e05e98ec3da0ce27a58\", \"got_revision_cp\": \"refs/heads/master@{#659257}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"cbffd54f0f4f365415b319b24d3582bb90dd40ea\", \"got_v8_revision_cp\": \"refs/heads/7.6.168@{#1}\", \"got_webrtc_revision\": \"5fc28b11a029b22c341c59dac8e766c178b02d95\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27933}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3c9a4cd0515dec14e1923e05e98ec3da0ce27a58\", \"swarm_hashes\": {\"accessibility_unittests\": \"977267e9c340b682829fa9a38d953f58122ccdcb\", \"angle_unittests\": \"9770c6dc177ea3008d0627c5d67645a2a2867c11\", \"app_list_unittests\": \"fcfd7fd6a04d3344d623c6b9add5fcc41070129d\", \"app_shell_unittests\": \"e73cf850a1b557b21ae54600bb7c86c4c45f0def\", \"ash_unittests\": \"9624ab3ac1ee06029c8a1c0c0152538176b45ed3\", \"aura_unittests\": \"6f0e0b75ebdd7f884715c777ef9c212678701518\", \"base_unittests\": \"a3ce8c3095f4a18dba31acdcd5fc1c87a5bc49c4\", \"base_util_unittests\": \"2caa2f1f6f6e7f4ad552adc568ec47562d633b77\", \"blink_common_unittests\": \"cb665df8575187518e8a2bff03fb9b7071fecbbc\", \"blink_fuzzer_unittests\": \"2eb136399b63a2894a2c89f0203e6bcdd83d034b\", \"blink_heap_unittests\": \"8391d9df36c760d9c5930d0e453dac4762eaaafb\", \"blink_platform_unittests\": \"61630a13614bd58beca7c62145882eb63b41f59c\", \"blink_unittests\": \"24f2aa15cf7963c4ab9d449ec89f0dcc5488644a\", \"boringssl_crypto_tests\": \"6f8e4079f845dfa1208e7f828430e2fffeda37ee\", \"boringssl_ssl_tests\": \"bebf09f643f65085d5bd2c239ca01f6045268009\", \"browser_tests\": \"7294ac03dd83b9e818cfcfe1cf4070d7a853269d\", \"cacheinvalidation_unittests\": \"da1e213c2a8076455554b72162431a62cd608591\", \"capture_unittests\": \"09ac443f956c126ebcd5b5e89354f5b9b3231fd2\", \"cast_unittests\": \"71da075bd4da8cb0ae17027ac239e5889db4b0cb\", \"cc_unittests\": \"c8f422935fb8670315c69ac13c230d59acb486a3\", \"chrome_app_unittests\": \"81c8cfd99967589a4890800e19b7dc2f4844b296\", \"chromedriver_unittests\": \"83e007adfb455d5593150b6a64114fe636db59ac\", \"chromeos_components_unittests\": \"15406638709efaa2eb7d7f985058f8b4ed6bc22d\", \"chromeos_unittests\": \"f378c62b7ae462589170b735f33610c0c90e27cc\", \"components_browsertests\": \"a3dfdc1eac3fc56cd82e53c37f8efed2c8c37057\", \"components_unittests\": \"5d17358efa4254576c681f7d2d8fd366237d236a\", \"compositor_unittests\": \"7ec0d281eef66af3f8a8189d265bf554070d0bb2\", \"content_browsertests\": \"2ce2aebdc46c65541b06978f4fdd6c3d63e6d444\", \"content_unittests\": \"6ccaa49496e46012eb5a1789cdf0a14c0e392910\", \"crypto_unittests\": \"06a38bdd041739f97387761f5cce70d8cb4bca44\", \"dbus_unittests\": \"fb460900bc0723710062b29ccbd7e17bb7d23ad4\", \"device_unittests\": \"1a7653624241557cbe78940e40c12cd402a33a3e\", \"display_unittests\": \"70b2e402eed9759d1da8b3566967f15a5ad04da6\", \"events_unittests\": \"c906267ff8bfe31d6701bd86905cf1323f9494dd\", \"exo_unittests\": \"c65f78876952ae88e6b800dcd212cdb72bd71524\", \"extensions_browsertests\": \"7c058615b0403f14f2d9b095e7a7f8ee57ab9802\", \"extensions_unittests\": \"81615e6a1c487c55b043b87a80e58f849f383416\", \"filesystem_service_unittests\": \"ee6670ace3ffd9116d998e85463197bb856880f2\", \"gcm_unit_tests\": \"c08c9f1e483837ab9c3227017dfb2349de7c978f\", \"gfx_unittests\": \"acdc966a178db61a4000183e539425879072aa99\", \"gin_unittests\": \"d472987bbbadda2e341074d5cdaba31968e228d1\", \"gl_unittests_ozone\": \"bcca3ae90dbb0ee5517653179e60f3f1310a1f84\", \"google_apis_unittests\": \"d444f43ec650d05e414ae0848ae4560ff46457c7\", \"gpu_unittests\": \"e34f872742c90b16d41c23a59d7ed2ff15804079\", \"interactive_ui_tests\": \"755b0ea6d36366d7235f9a869e0c3bde850b2271\", \"ipc_tests\": \"6dfe7fa3b0b9bc3bed883ef91f498d92d13b8f3c\", \"jingle_unittests\": \"9af2132c624a02be5bf20a91eb1fa9c399abe303\", \"keyboard_unittests\": \"e75db6ba31bc2bbd3d0f7e731960984c2850afaf\", \"latency_unittests\": \"098f0867fad9d2d7a779f46a8c027a0aef78daf4\", \"leveldb_service_unittests\": \"1e6aefe2f2fc117ac02193fa579e23cd1477cfa8\", \"libjingle_xmpp_unittests\": \"25479ff0ea68d89d5d425b5487eda2ae255ad794\", \"media_blink_unittests\": \"927577de22fce26c7e06f0e9fb4571c6459a4689\", \"media_service_unittests\": \"62e7eb58ad10005a4e1698b3495a9abf909b6faf\", \"media_unittests\": \"18fa00369d4487e95b5a9f6c74b8da37d595a007\", \"message_center_unittests\": \"dbe838dda5ebdf7b0b70e69ecae9e34fda9e5732\", \"midi_unittests\": \"b532bb9ccecd4e091fb712ed186ec58e2da1d70e\", \"mojo_core_unittests\": \"ac465b371804c7582e3651af7050fac0e3bd7631\", \"mojo_unittests\": \"f6d05b31941dda03384f8e89aa102453bd39d380\", \"nacl_helper_nonsfi_unittests\": \"a5e82ec066b651cae2e35148befa45be4a5dc5d1\", \"nacl_loader_unittests\": \"e46cabdbd5f1ea4f1d36ef68ea3f22fa5f550958\", \"native_theme_unittests\": \"5e1935175cd4de7ec6ab8b9a70d968399f4a35e4\", \"net_unittests\": \"04297ffeb9eadc8b570fed5138f7692c02706b3a\", \"ozone_gl_unittests\": \"433a9d1ee2c8b289850b8793635fb547d848a522\", \"ozone_unittests\": \"b20752554a5a9f235fd16d99a9caf6a867066d1d\", \"ozone_x11_unittests\": \"5a7bdfb25cfbfa0b58fc7d1b4f594b31bbf47cbb\", \"pdf_unittests\": \"c59a22c046a302daf0290d4b48c913997286338b\", \"perfetto_unittests\": \"5ab97a3e7f549341b1b453dc2486d1d84e8e5c64\", \"ppapi_unittests\": \"55d9661ad0cc9b6bafaa4f94c42279b7a70710a2\", \"printing_unittests\": \"c80322406ec0d7cf78ff9c5a107503a8f0614257\", \"remoting_unittests\": \"159f60b93cda950c0f325af6f63484c92212e8c0\", \"sandbox_linux_unittests\": \"bcc20b664588f9a715511dbbba48bbab56a651ea\", \"service_manager_unittests\": \"81a51f64d7aca356ef0bf86c67720866f49014bb\", \"services_unittests\": \"e8af9a3fe0e2e3808605c2ebe099466484f8829a\", \"shell_dialogs_unittests\": \"e5f14ec483e6ed501bccd9ebd1d39b09bab61ca4\", \"skia_unittests\": \"058c28d72451b50403e3bce3a5008ab4dc4e9756\", \"snapshot_unittests\": \"91ab5605c31c8a5cf70fd816aecc3954845f63c7\", \"sql_unittests\": \"a846c0511d50abd3434aa8c50be3ecf0a79e8fac\", \"storage_unittests\": \"11d23865ec64da907d2751a43ddbd325884e4027\", \"sync_integration_tests\": \"d8b80f45d3b6cc287aa4852599a63de12a2e96d5\", \"traffic_annotation_auditor_unittests\": \"1ff4ccf0748b8a914c669993384d8670ebbe0d5d\", \"ui_base_unittests\": \"bf0b203b4b34824e52d76add1a0d62283506be2e\", \"ui_chromeos_unittests\": \"b754f2f097f79c6f654dca929300c5d6594ca7b0\", \"ui_touch_selection_unittests\": \"32c6f6b9838adfa4af5a1452c23a9af25f4ece8c\", \"unit_tests\": \"d9f150df1ab3555cbf739c83850cdb997ecfbab0\", \"url_unittests\": \"d08b387fe427d68db300d20062e42a11df845165\", \"usage_time_limit_unittests\": \"e5699a96533ee6e3e4f83789e4bef45556ad7d53\", \"views_unittests\": \"9033a561a7ff1adc05a3d889c353a6b3e5d240d8\", \"viz_unittests\": \"77e32d9374d0055fcb4342be05ef44f361431e31\", \"wm_unittests\": \"2bc848624f9fbab99e100a24641b66b783dcff16\", \"wtf_unittests\": \"d32843d7d5f6a03c2d92c93fb0dd84a29f0aca2e\"}, \"swarm_hashes_refs/heads/master(at){#659257}_without_patch\": {\"accessibility_unittests\": \"977267e9c340b682829fa9a38d953f58122ccdcb\", \"angle_unittests\": \"9770c6dc177ea3008d0627c5d67645a2a2867c11\", \"app_list_unittests\": \"fcfd7fd6a04d3344d623c6b9add5fcc41070129d\", \"app_shell_unittests\": \"e73cf850a1b557b21ae54600bb7c86c4c45f0def\", \"ash_unittests\": \"9624ab3ac1ee06029c8a1c0c0152538176b45ed3\", \"aura_unittests\": \"6f0e0b75ebdd7f884715c777ef9c212678701518\", \"base_unittests\": \"a3ce8c3095f4a18dba31acdcd5fc1c87a5bc49c4\", \"base_util_unittests\": \"2caa2f1f6f6e7f4ad552adc568ec47562d633b77\", \"blink_common_unittests\": \"cb665df8575187518e8a2bff03fb9b7071fecbbc\", \"blink_fuzzer_unittests\": \"2eb136399b63a2894a2c89f0203e6bcdd83d034b\", \"blink_heap_unittests\": \"8391d9df36c760d9c5930d0e453dac4762eaaafb\", \"blink_platform_unittests\": \"61630a13614bd58beca7c62145882eb63b41f59c\", \"blink_unittests\": \"24f2aa15cf7963c4ab9d449ec89f0dcc5488644a\", \"boringssl_crypto_tests\": \"6f8e4079f845dfa1208e7f828430e2fffeda37ee\", \"boringssl_ssl_tests\": \"bebf09f643f65085d5bd2c239ca01f6045268009\", \"browser_tests\": \"7294ac03dd83b9e818cfcfe1cf4070d7a853269d\", \"cacheinvalidation_unittests\": \"da1e213c2a8076455554b72162431a62cd608591\", \"capture_unittests\": \"09ac443f956c126ebcd5b5e89354f5b9b3231fd2\", \"cast_unittests\": \"71da075bd4da8cb0ae17027ac239e5889db4b0cb\", \"cc_unittests\": \"c8f422935fb8670315c69ac13c230d59acb486a3\", \"chrome_app_unittests\": \"81c8cfd99967589a4890800e19b7dc2f4844b296\", \"chromedriver_unittests\": \"83e007adfb455d5593150b6a64114fe636db59ac\", \"chromeos_components_unittests\": \"15406638709efaa2eb7d7f985058f8b4ed6bc22d\", \"chromeos_unittests\": \"f378c62b7ae462589170b735f33610c0c90e27cc\", \"components_browsertests\": \"a3dfdc1eac3fc56cd82e53c37f8efed2c8c37057\", \"components_unittests\": \"5d17358efa4254576c681f7d2d8fd366237d236a\", \"compositor_unittests\": \"7ec0d281eef66af3f8a8189d265bf554070d0bb2\", \"content_browsertests\": \"2ce2aebdc46c65541b06978f4fdd6c3d63e6d444\", \"content_unittests\": \"6ccaa49496e46012eb5a1789cdf0a14c0e392910\", \"crypto_unittests\": \"06a38bdd041739f97387761f5cce70d8cb4bca44\", \"dbus_unittests\": \"fb460900bc0723710062b29ccbd7e17bb7d23ad4\", \"device_unittests\": \"1a7653624241557cbe78940e40c12cd402a33a3e\", \"display_unittests\": \"70b2e402eed9759d1da8b3566967f15a5ad04da6\", \"events_unittests\": \"c906267ff8bfe31d6701bd86905cf1323f9494dd\", \"exo_unittests\": \"c65f78876952ae88e6b800dcd212cdb72bd71524\", \"extensions_browsertests\": \"7c058615b0403f14f2d9b095e7a7f8ee57ab9802\", \"extensions_unittests\": \"81615e6a1c487c55b043b87a80e58f849f383416\", \"filesystem_service_unittests\": \"ee6670ace3ffd9116d998e85463197bb856880f2\", \"gcm_unit_tests\": \"c08c9f1e483837ab9c3227017dfb2349de7c978f\", \"gfx_unittests\": \"acdc966a178db61a4000183e539425879072aa99\", \"gin_unittests\": \"d472987bbbadda2e341074d5cdaba31968e228d1\", \"gl_unittests_ozone\": \"bcca3ae90dbb0ee5517653179e60f3f1310a1f84\", \"google_apis_unittests\": \"d444f43ec650d05e414ae0848ae4560ff46457c7\", \"gpu_unittests\": \"e34f872742c90b16d41c23a59d7ed2ff15804079\", \"interactive_ui_tests\": \"755b0ea6d36366d7235f9a869e0c3bde850b2271\", \"ipc_tests\": \"6dfe7fa3b0b9bc3bed883ef91f498d92d13b8f3c\", \"jingle_unittests\": \"9af2132c624a02be5bf20a91eb1fa9c399abe303\", \"keyboard_unittests\": \"e75db6ba31bc2bbd3d0f7e731960984c2850afaf\", \"latency_unittests\": \"098f0867fad9d2d7a779f46a8c027a0aef78daf4\", \"leveldb_service_unittests\": \"1e6aefe2f2fc117ac02193fa579e23cd1477cfa8\", \"libjingle_xmpp_unittests\": \"25479ff0ea68d89d5d425b5487eda2ae255ad794\", \"media_blink_unittests\": \"927577de22fce26c7e06f0e9fb4571c6459a4689\", \"media_service_unittests\": \"62e7eb58ad10005a4e1698b3495a9abf909b6faf\", \"media_unittests\": \"18fa00369d4487e95b5a9f6c74b8da37d595a007\", \"message_center_unittests\": \"dbe838dda5ebdf7b0b70e69ecae9e34fda9e5732\", \"midi_unittests\": \"b532bb9ccecd4e091fb712ed186ec58e2da1d70e\", \"mojo_core_unittests\": \"ac465b371804c7582e3651af7050fac0e3bd7631\", \"mojo_unittests\": \"f6d05b31941dda03384f8e89aa102453bd39d380\", \"nacl_helper_nonsfi_unittests\": \"a5e82ec066b651cae2e35148befa45be4a5dc5d1\", \"nacl_loader_unittests\": \"e46cabdbd5f1ea4f1d36ef68ea3f22fa5f550958\", \"native_theme_unittests\": \"5e1935175cd4de7ec6ab8b9a70d968399f4a35e4\", \"net_unittests\": \"04297ffeb9eadc8b570fed5138f7692c02706b3a\", \"ozone_gl_unittests\": \"433a9d1ee2c8b289850b8793635fb547d848a522\", \"ozone_unittests\": \"b20752554a5a9f235fd16d99a9caf6a867066d1d\", \"ozone_x11_unittests\": \"5a7bdfb25cfbfa0b58fc7d1b4f594b31bbf47cbb\", \"pdf_unittests\": \"c59a22c046a302daf0290d4b48c913997286338b\", \"perfetto_unittests\": \"5ab97a3e7f549341b1b453dc2486d1d84e8e5c64\", \"ppapi_unittests\": \"55d9661ad0cc9b6bafaa4f94c42279b7a70710a2\", \"printing_unittests\": \"c80322406ec0d7cf78ff9c5a107503a8f0614257\", \"remoting_unittests\": \"159f60b93cda950c0f325af6f63484c92212e8c0\", \"sandbox_linux_unittests\": \"bcc20b664588f9a715511dbbba48bbab56a651ea\", \"service_manager_unittests\": \"81a51f64d7aca356ef0bf86c67720866f49014bb\", \"services_unittests\": \"e8af9a3fe0e2e3808605c2ebe099466484f8829a\", \"shell_dialogs_unittests\": \"e5f14ec483e6ed501bccd9ebd1d39b09bab61ca4\", \"skia_unittests\": \"058c28d72451b50403e3bce3a5008ab4dc4e9756\", \"snapshot_unittests\": \"91ab5605c31c8a5cf70fd816aecc3954845f63c7\", \"sql_unittests\": \"a846c0511d50abd3434aa8c50be3ecf0a79e8fac\", \"storage_unittests\": \"11d23865ec64da907d2751a43ddbd325884e4027\", \"sync_integration_tests\": \"d8b80f45d3b6cc287aa4852599a63de12a2e96d5\", \"traffic_annotation_auditor_unittests\": \"1ff4ccf0748b8a914c669993384d8670ebbe0d5d\", \"ui_base_unittests\": \"bf0b203b4b34824e52d76add1a0d62283506be2e\", \"ui_chromeos_unittests\": \"b754f2f097f79c6f654dca929300c5d6594ca7b0\", \"ui_touch_selection_unittests\": \"32c6f6b9838adfa4af5a1452c23a9af25f4ece8c\", \"unit_tests\": \"d9f150df1ab3555cbf739c83850cdb997ecfbab0\", \"url_unittests\": \"d08b387fe427d68db300d20062e42a11df845165\", \"usage_time_limit_unittests\": \"e5699a96533ee6e3e4f83789e4bef45556ad7d53\", \"views_unittests\": \"9033a561a7ff1adc05a3d889c353a6b3e5d240d8\", \"viz_unittests\": \"77e32d9374d0055fcb4342be05ef44f361431e31\", \"wm_unittests\": \"2bc848624f9fbab99e100a24641b66b783dcff16\", \"wtf_unittests\": \"d32843d7d5f6a03c2d92c93fb0dd84a29f0aca2e\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4293-d3520ea\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"4 tests failed: ['ash_unittests', 'exo_unittests', 'unit_tests', 'chromeos_components_unittests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557788671164977",
"status": "COMPLETED",
"status_changed_ts": "1557788671698124",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12374",
"builder:linux-chromeos-dbg",
"buildset:commit/git/3c9a4cd0515dec14e1923e05e98ec3da0ce27a58",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c9a4cd0515dec14e1923e05e98ec3da0ce27a58",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078964314259258880",
"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/8913534252274925568/+/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:44cc3d30ae01d710",
"user_agent:luci-scheduler"
],
"updated_ts": "1557794306103304",
"url": "https://ci.chromium.org/b/8913534252274925568",
"utcnow_ts": "1557794309032173"
}
}
[00:38:29.075] Invocation finished in 1h39m32.945075486s with status FAILED