[23:54:46.532] New invocation is queued and will start shortly
[23:54:47.629] Starting the invocation (attempt 1)
[23:54:47.671] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:54:47.671] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:54:47.671] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024149636454306048",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9024149636454306048",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d",
"gitiles_ref:refs/heads/master"
]
}
[23:54:48.839] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610063687796156",
"id": "8858719574463602576",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610063688576201",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22107",
"builder:linux-chromeos-dbg",
"buildset:commit/git/b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024149636454306048",
"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/8858719574463602576/+/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": "1610063688576380",
"url": "https://ci.chromium.org/b/8858719574463602576",
"utcnow_ts": "1610063688820223"
}
}
[23:54:48.839] Task URL: https://ci.chromium.org/b/8858719574463602576
[23:54:48.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:2:0) after 1m0s
[23:54:52.155] Received PubSub notification, asking Buildbucket for the build status
[23:54:52.512] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:48.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:2:0)
[23:55:48.850] Timer tick, asking Buildbucket for the build status
[23:55:48.987] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:48.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:4:0) after 1m0s
[23:56:49.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:4:0)
[23:56:49.052] Timer tick, asking Buildbucket for the build status
[23:56:49.250] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:49.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:5:0) after 1m0s
[23:57:49.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:5:0)
[23:57:49.384] Timer tick, asking Buildbucket for the build status
[23:57:49.473] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:49.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:6:0) after 1m0s
[23:58:49.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:6:0)
[23:58:49.517] Timer tick, asking Buildbucket for the build status
[23:58:49.793] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:49.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:7:0) after 1m0s
[23:59:49.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:7:0)
[23:59:49.857] Timer tick, asking Buildbucket for the build status
[23:59:49.975] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:49.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:8:0) after 1m0s
[00:00:50.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:8:0)
[00:00:50.050] Timer tick, asking Buildbucket for the build status
[00:00:50.119] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:50.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:9:0) after 1m0s
[00:01:50.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:9:0)
[00:01:50.151] Timer tick, asking Buildbucket for the build status
[00:01:50.451] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:50.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:10:0) after 1m0s
[00:02:50.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:10:0)
[00:02:50.474] Timer tick, asking Buildbucket for the build status
[00:02:50.611] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:50.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:11:0) after 1m0s
[00:03:50.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:11:0)
[00:03:50.642] Timer tick, asking Buildbucket for the build status
[00:03:50.726] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:50.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:12:0) after 1m0s
[00:04:50.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:12:0)
[00:04:50.718] Timer tick, asking Buildbucket for the build status
[00:04:50.834] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:50.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:13:0) after 1m0s
[00:05:50.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:13:0)
[00:05:50.885] Timer tick, asking Buildbucket for the build status
[00:05:51.283] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:51.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:14:0) after 1m0s
[00:06:51.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:14:0)
[00:06:51.367] Timer tick, asking Buildbucket for the build status
[00:06:51.437] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:51.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:15:0) after 1m0s
[00:07:51.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:15:0)
[00:07:51.540] Timer tick, asking Buildbucket for the build status
[00:07:51.629] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:51.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:16:0) after 1m0s
[00:08:51.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:16:0)
[00:08:51.699] Timer tick, asking Buildbucket for the build status
[00:08:51.903] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:51.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:17:0) after 1m0s
[00:09:52.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:17:0)
[00:09:52.027] Timer tick, asking Buildbucket for the build status
[00:09:52.191] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:52.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:18:0) after 1m0s
[00:10:52.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:18:0)
[00:10:52.351] Timer tick, asking Buildbucket for the build status
[00:10:52.512] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:52.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:19:0) after 1m0s
[00:11:52.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:19:0)
[00:11:52.597] Timer tick, asking Buildbucket for the build status
[00:11:53.033] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:53.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:20:0) after 1m0s
[00:12:53.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:20:0)
[00:12:53.073] Timer tick, asking Buildbucket for the build status
[00:12:53.148] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:53.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:21:0) after 1m0s
[00:13:53.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:21:0)
[00:13:53.145] Timer tick, asking Buildbucket for the build status
[00:13:53.202] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:53.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:22:0) after 1m0s
[00:14:53.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:22:0)
[00:14:53.222] Timer tick, asking Buildbucket for the build status
[00:14:53.378] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:53.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:23:0) after 1m0s
[00:15:53.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:23:0)
[00:15:53.449] Timer tick, asking Buildbucket for the build status
[00:15:56.127] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:56.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:24:0) after 1m0s
[00:16:56.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:24:0)
[00:16:56.170] Timer tick, asking Buildbucket for the build status
[00:16:56.246] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:56.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:25:0) after 1m0s
[00:17:56.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:25:0)
[00:17:56.249] Timer tick, asking Buildbucket for the build status
[00:17:56.309] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:26:0) after 1m0s
[00:18:56.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:26:0)
[00:18:56.322] Timer tick, asking Buildbucket for the build status
[00:18:56.529] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:56.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:27:0) after 1m0s
[00:19:56.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:27:0)
[00:19:56.539] Timer tick, asking Buildbucket for the build status
[00:19:56.773] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:56.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:28:0) after 1m0s
[00:20:56.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:28:0)
[00:20:56.797] Timer tick, asking Buildbucket for the build status
[00:20:56.871] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:56.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:29:0) after 1m0s
[00:21:56.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:29:0)
[00:21:56.921] Timer tick, asking Buildbucket for the build status
[00:21:56.978] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:56.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:30:0) after 1m0s
[00:22:57.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:30:0)
[00:22:57.397] Timer tick, asking Buildbucket for the build status
[00:22:57.463] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:57.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:31:0) after 1m0s
[00:23:57.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:31:0)
[00:23:57.886] Timer tick, asking Buildbucket for the build status
[00:23:57.940] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:57.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:32:0) after 1m0s
[00:24:58.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:32:0)
[00:24:58.139] Timer tick, asking Buildbucket for the build status
[00:24:58.277] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:58.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:33:0) after 1m0s
[00:25:58.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:33:0)
[00:25:58.411] Timer tick, asking Buildbucket for the build status
[00:25:58.486] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:58.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:34:0) after 1m0s
[00:26:58.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:34:0)
[00:26:58.544] Timer tick, asking Buildbucket for the build status
[00:26:58.630] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:58.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:35:0) after 1m0s
[00:27:58.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:35:0)
[00:27:58.664] Timer tick, asking Buildbucket for the build status
[00:27:58.750] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:58.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:36:0) after 1m0s
[00:28:58.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:36:0)
[00:28:58.899] Timer tick, asking Buildbucket for the build status
[00:28:59.021] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:59.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:37:0) after 1m0s
[00:29:59.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:37:0)
[00:29:59.283] Timer tick, asking Buildbucket for the build status
[00:29:59.417] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:59.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:38:0) after 1m0s
[00:30:59.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:38:0)
[00:30:59.594] Timer tick, asking Buildbucket for the build status
[00:30:59.667] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:59.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:39:0) after 1m0s
[00:31:59.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:39:0)
[00:31:59.900] Timer tick, asking Buildbucket for the build status
[00:32:00.213] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:00.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:40:0) after 1m0s
[00:33:00.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:40:0)
[00:33:00.336] Timer tick, asking Buildbucket for the build status
[00:33:00.526] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:00.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:41:0) after 1m0s
[00:34:00.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:41:0)
[00:34:00.527] Timer tick, asking Buildbucket for the build status
[00:34:00.744] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:00.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:42:0) after 1m0s
[00:35:00.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:42:0)
[00:35:00.784] Timer tick, asking Buildbucket for the build status
[00:35:01.052] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:01.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:43:0) after 1m0s
[00:36:01.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:43:0)
[00:36:01.501] Timer tick, asking Buildbucket for the build status
[00:36:01.603] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:01.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:44:0) after 1m0s
[00:37:01.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:44:0)
[00:37:01.612] Timer tick, asking Buildbucket for the build status
[00:37:01.685] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:01.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:45:0) after 1m0s
[00:38:01.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:45:0)
[00:38:01.803] Timer tick, asking Buildbucket for the build status
[00:38:01.939] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:01.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:46:0) after 1m0s
[00:39:02.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:46:0)
[00:39:02.156] Timer tick, asking Buildbucket for the build status
[00:39:02.273] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:02.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:47:0) after 1m0s
[00:40:02.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:47:0)
[00:40:02.448] Timer tick, asking Buildbucket for the build status
[00:40:02.560] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:02.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:48:0) after 1m0s
[00:41:02.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:48:0)
[00:41:02.655] Timer tick, asking Buildbucket for the build status
[00:41:02.737] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:02.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:49:0) after 1m0s
[00:42:02.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:49:0)
[00:42:02.820] Timer tick, asking Buildbucket for the build status
[00:42:03.050] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:03.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:50:0) after 1m0s
[00:43:03.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:50:0)
[00:43:03.095] Timer tick, asking Buildbucket for the build status
[00:43:03.649] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:03.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:51:0) after 1m0s
[00:44:03.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:51:0)
[00:44:03.825] Timer tick, asking Buildbucket for the build status
[00:44:03.968] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:03.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:52:0) after 1m0s
[00:45:03.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:52:0)
[00:45:03.975] Timer tick, asking Buildbucket for the build status
[00:45:04.125] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:04.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:53:0) after 1m0s
[00:46:04.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:53:0)
[00:46:04.150] Timer tick, asking Buildbucket for the build status
[00:46:04.256] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:04.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:54:0) after 1m0s
[00:47:04.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:54:0)
[00:47:04.289] Timer tick, asking Buildbucket for the build status
[00:47:04.342] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:04.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:55:0) after 1m0s
[00:48:04.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:55:0)
[00:48:04.383] Timer tick, asking Buildbucket for the build status
[00:48:04.525] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:04.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:56:0) after 1m0s
[00:49:04.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:56:0)
[00:49:04.570] Timer tick, asking Buildbucket for the build status
[00:49:04.728] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:04.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:57:0) after 1m0s
[00:50:04.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:57:0)
[00:50:04.753] Timer tick, asking Buildbucket for the build status
[00:50:04.876] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:04.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:58:0) after 1m0s
[00:51:04.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:58:0)
[00:51:04.946] Timer tick, asking Buildbucket for the build status
[00:51:05.162] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:05.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:59:0) after 1m0s
[00:52:05.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:59:0)
[00:52:05.208] Timer tick, asking Buildbucket for the build status
[00:52:05.338] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:05.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:60:0) after 1m0s
[00:53:05.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:60:0)
[00:53:05.422] Timer tick, asking Buildbucket for the build status
[00:53:05.727] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:05.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:61:0) after 1m0s
[00:54:05.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:61:0)
[00:54:05.756] Timer tick, asking Buildbucket for the build status
[00:54:05.900] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:05.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:62:0) after 1m0s
[00:55:05.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:62:0)
[00:55:05.949] Timer tick, asking Buildbucket for the build status
[00:55:06.064] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:06.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:63:0) after 1m0s
[00:56:06.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:63:0)
[00:56:06.099] Timer tick, asking Buildbucket for the build status
[00:56:06.339] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:06.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:64:0) after 1m0s
[00:57:06.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:64:0)
[00:57:06.403] Timer tick, asking Buildbucket for the build status
[00:57:06.571] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:06.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:65:0) after 1m0s
[00:58:06.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:65:0)
[00:58:06.630] Timer tick, asking Buildbucket for the build status
[00:58:07.122] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:07.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:66:0) after 1m0s
[00:59:07.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:66:0)
[00:59:07.159] Timer tick, asking Buildbucket for the build status
[00:59:07.350] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:07.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:67:0) after 1m0s
[01:00:07.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:67:0)
[01:00:07.451] Timer tick, asking Buildbucket for the build status
[01:00:07.614] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:07.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:68:0) after 1m0s
[01:01:07.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:68:0)
[01:01:07.697] Timer tick, asking Buildbucket for the build status
[01:01:07.832] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:07.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:69:0) after 1m0s
[01:02:07.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:69:0)
[01:02:07.873] Timer tick, asking Buildbucket for the build status
[01:02:07.941] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:07.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:70:0) after 1m0s
[01:03:08.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:70:0)
[01:03:08.004] Timer tick, asking Buildbucket for the build status
[01:03:08.116] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:08.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:71:0) after 1m0s
[01:04:08.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:71:0)
[01:04:08.156] Timer tick, asking Buildbucket for the build status
[01:04:08.280] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:08.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:72:0) after 1m0s
[01:05:08.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:72:0)
[01:05:08.301] Timer tick, asking Buildbucket for the build status
[01:05:08.466] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:08.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:73:0) after 1m0s
[01:06:08.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:73:0)
[01:06:08.464] Timer tick, asking Buildbucket for the build status
[01:06:08.610] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:08.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:74:0) after 1m0s
[01:07:08.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:74:0)
[01:07:08.664] Timer tick, asking Buildbucket for the build status
[01:07:08.809] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:08.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:75:0) after 1m0s
[01:08:08.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:75:0)
[01:08:08.891] Timer tick, asking Buildbucket for the build status
[01:08:09.068] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:09.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:76:0) after 1m0s
[01:09:09.063] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:76:0)
[01:09:09.063] Timer tick, asking Buildbucket for the build status
[01:09:09.137] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:09.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:77:0) after 1m0s
[01:10:09.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:77:0)
[01:10:09.211] Timer tick, asking Buildbucket for the build status
[01:10:09.516] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:09.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:78:0) after 1m0s
[01:11:09.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:78:0)
[01:11:09.506] Timer tick, asking Buildbucket for the build status
[01:11:09.662] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:09.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:79:0) after 1m0s
[01:12:09.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:79:0)
[01:12:09.719] Timer tick, asking Buildbucket for the build status
[01:12:09.957] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:09.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:80:0) after 1m0s
[01:13:10.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:80:0)
[01:13:10.001] Timer tick, asking Buildbucket for the build status
[01:13:10.160] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:10.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:81:0) after 1m0s
[01:14:10.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:81:0)
[01:14:10.336] Timer tick, asking Buildbucket for the build status
[01:14:10.407] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:10.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:82:0) after 1m0s
[01:15:10.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:82:0)
[01:15:10.453] Timer tick, asking Buildbucket for the build status
[01:15:10.928] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:10.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:83:0) after 1m0s
[01:16:10.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:83:0)
[01:16:10.961] Timer tick, asking Buildbucket for the build status
[01:16:11.193] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:11.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:84:0) after 1m0s
[01:17:11.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:84:0)
[01:17:11.228] Timer tick, asking Buildbucket for the build status
[01:17:11.420] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:11.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:85:0) after 1m0s
[01:18:11.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:85:0)
[01:18:11.478] Timer tick, asking Buildbucket for the build status
[01:18:11.578] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:11.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:86:0) after 1m0s
[01:19:11.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:86:0)
[01:19:11.581] Timer tick, asking Buildbucket for the build status
[01:19:11.870] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:11.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:87:0) after 1m0s
[01:20:11.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:87:0)
[01:20:11.915] Timer tick, asking Buildbucket for the build status
[01:20:12.056] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:12.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:88:0) after 1m0s
[01:21:12.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:88:0)
[01:21:12.076] Timer tick, asking Buildbucket for the build status
[01:21:12.165] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:12.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:89:0) after 1m0s
[01:22:12.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:89:0)
[01:22:12.216] Timer tick, asking Buildbucket for the build status
[01:22:12.326] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:12.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:90:0) after 1m0s
[01:23:12.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:90:0)
[01:23:12.431] Timer tick, asking Buildbucket for the build status
[01:23:12.632] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:12.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:91:0) after 1m0s
[01:24:12.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:91:0)
[01:24:12.655] Timer tick, asking Buildbucket for the build status
[01:24:12.732] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:12.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:92:0) after 1m0s
[01:25:12.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:92:0)
[01:25:12.770] Timer tick, asking Buildbucket for the build status
[01:25:12.882] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:12.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:93:0) after 1m0s
[01:26:12.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:93:0)
[01:26:12.905] Timer tick, asking Buildbucket for the build status
[01:26:12.970] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:12.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:94:0) after 1m0s
[01:27:13.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:94:0)
[01:27:13.059] Timer tick, asking Buildbucket for the build status
[01:27:13.177] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:13.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:95:0) after 1m0s
[01:28:13.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:95:0)
[01:28:13.311] Timer tick, asking Buildbucket for the build status
[01:28:13.479] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:13.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:96:0) after 1m0s
[01:29:13.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:96:0)
[01:29:13.601] Timer tick, asking Buildbucket for the build status
[01:29:13.868] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:13.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:97:0) after 1m0s
[01:30:13.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:97:0)
[01:30:13.938] Timer tick, asking Buildbucket for the build status
[01:30:14.440] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:14.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:98:0) after 1m0s
[01:31:14.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:98:0)
[01:31:14.508] Timer tick, asking Buildbucket for the build status
[01:31:14.593] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:14.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:99:0) after 1m0s
[01:32:14.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:99:0)
[01:32:14.596] Timer tick, asking Buildbucket for the build status
[01:32:14.717] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:14.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:100:0) after 1m0s
[01:33:14.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:100:0)
[01:33:14.772] Timer tick, asking Buildbucket for the build status
[01:33:14.863] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:14.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:101:0) after 1m0s
[01:34:14.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:101:0)
[01:34:14.911] Timer tick, asking Buildbucket for the build status
[01:34:15.033] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:15.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:102:0) after 1m0s
[01:35:15.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:102:0)
[01:35:15.038] Timer tick, asking Buildbucket for the build status
[01:35:15.191] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:15.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:103:0) after 1m0s
[01:36:15.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:103:0)
[01:36:15.292] Timer tick, asking Buildbucket for the build status
[01:36:15.360] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:15.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:104:0) after 1m0s
[01:37:15.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:104:0)
[01:37:15.411] Timer tick, asking Buildbucket for the build status
[01:37:15.548] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:15.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:105:0) after 1m0s
[01:38:15.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:105:0)
[01:38:15.580] Timer tick, asking Buildbucket for the build status
[01:38:15.660] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:15.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:106:0) after 1m0s
[01:39:15.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:106:0)
[01:39:15.780] Timer tick, asking Buildbucket for the build status
[01:39:15.848] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:15.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:107:0) after 1m0s
[01:40:15.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:107:0)
[01:40:15.900] Timer tick, asking Buildbucket for the build status
[01:40:16.232] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:16.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:108:0) after 1m0s
[01:41:16.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:108:0)
[01:41:16.264] Timer tick, asking Buildbucket for the build status
[01:41:16.346] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:16.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:109:0) after 1m0s
[01:42:16.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:109:0)
[01:42:16.484] Timer tick, asking Buildbucket for the build status
[01:42:16.641] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:16.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:110:0) after 1m0s
[01:43:16.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:110:0)
[01:43:16.715] Timer tick, asking Buildbucket for the build status
[01:43:16.792] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:16.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:111:0) after 1m0s
[01:44:16.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:111:0)
[01:44:16.900] Timer tick, asking Buildbucket for the build status
[01:44:17.069] Build 8858719574463602576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:17.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9024149636454306048:112:0) after 1m0s
[01:45:08.802] Received PubSub notification, asking Buildbucket for the build status
[01:45:08.946] Build 8858719574463602576: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:45:08.946] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610070307300690",
"created_by": "project:chromium",
"created_ts": "1610063687796156",
"failure_reason": "BUILD_FAILURE",
"id": "8858719574463602576",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- PowerButtonControllerTest.ClamshellRepeatedPowerButtonReleasesCancelledAnimation\\n\\n- ArcNotificationViewTest.PressBackspaceKeyOnEditBox\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-65-a7fn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22107, \"got_angle_revision\": \"a7158eb524ddc3015c54340d3c0593d6641d41ad\", \"got_dawn_revision\": \"00fcab636a055c78036ee20bb1a89339b0a28bad\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d\", \"got_revision_cp\": \"refs/heads/master@{#841257}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"937395c1ed5d20af5a5fd999d7e2deddf1b78fe3\", \"got_v8_revision\": \"edfb3b4d60eb7b778b92afc495b0cf11afa7a66a\", \"got_v8_revision_cp\": \"refs/heads/8.9.215@{#1}\", \"got_webrtc_revision\": \"ec9b281bbc14fe9c3cd2b27241059f52b7eb654a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32920}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d\", \"swarm_hashes_refs/heads/master(at){#841257}_without_patch\": {\"absl_hardening_tests\": \"5fe47bbe9c902a104ce542e52462ef3eac4c2d17\", \"accessibility_unittests\": \"6a6bcbcbe08da356a5b3ed437624f7ae420a23aa\", \"angle_unittests\": \"c194501fb2ed827e4993302cf5b650cfd0f3a34a\", \"app_list_unittests\": \"c5ac002a587d9466097cd19de92cdd6f7de45602\", \"app_shell_unittests\": \"55f513c6a234f4d96e6bd2e754dab48b63448b51\", \"ash_unittests\": \"ee3231b6814b0ee63660663c32bb93f562d94137\", \"aura_unittests\": \"ffc1a8f25ec9d307112dcf4ec4f4760b3f6b2b14\", \"base_unittests\": \"4f078b2427240adde8f28f02934c4cbd938d140e\", \"base_util_unittests\": \"877d22926f28e5a4316f0da3ef0e741fa4b617f4\", \"blink_common_unittests\": \"1c5de318b247ca642b4ff4814fe4a738457c43dd\", \"blink_fuzzer_unittests\": \"630ecceaa6a07f3bde7049d8d5cb549c68f24592\", \"blink_heap_unittests\": \"abc19927333a5c9ea6b376c37d67ae9a79000a5c\", \"blink_platform_unittests\": \"175f98591cd8aceadcb0ccc6495ee6bdddeba530\", \"blink_unittests\": \"3fd1a309975fa1a8ba2d91c5c1d80071f906b0d4\", \"boringssl_crypto_tests\": \"987b3dede631dc75904bfd01a2861b452f34b655\", \"boringssl_ssl_tests\": \"b60ba52f50f51a999fa150206ac6c692d4238051\", \"browser_tests\": \"ff5a16a7beebd27237bcb00ebef14cb2d7fb34dc\", \"capture_unittests\": \"66ffb9466858f87b87ad25be7653f11ed00aa0c3\", \"cast_unittests\": \"a2d4404e94078fa82ab2766ce843adb2111c9575\", \"cc_unittests\": \"eb6ff26d957d829d9cab14bc9a536d2aa09975cd\", \"chrome_app_unittests\": \"7c54e390ab2225d626b929ba4649f6decca8c64a\", \"chromedriver_unittests\": \"71492a54bee59351545ee18bf0238fe0ccd9eec8\", \"chromeos_components_unittests\": \"bca6cb1b80576bf92c20b90ad50a1e11c8915f83\", \"chromeos_unittests\": \"d0d4ba885a9f034fe961a54ef403856dff23ce92\", \"components_browsertests\": \"8f930d50b5e49d9b5e4410c8b02709d40ee184a7\", \"components_unittests\": \"6df4f08873ff4e4cf5be0c7a4c36d86436673841\", \"compositor_unittests\": \"ec4c8a2eb3523648b74ea953a727f88ad7f8f5b6\", \"content_browsertests\": \"73504238ab0840cdd7fa88b01c5340dcefa7665c\", \"content_unittests\": \"5f2dc66c5692498ad9910218e26771108366ed61\", \"crashpad_tests\": \"7096201324bf62634539020acc6796ab30d534df\", \"crypto_unittests\": \"9c6207b302cdc964d1b6ca7c28c3df5af2707f7c\", \"dbus_unittests\": \"d35f0b4b2a772907d9f585c750e0e6b7817fde38\", \"device_unittests\": \"4518ed5843690c0a251e550610ee1196f31caefc\", \"display_unittests\": \"58b97d30c89ea782dfebc44c3fc0e5b05047054a\", \"events_unittests\": \"67e971e87a94d2ad5ae43266c11e97b0141a7204\", \"exo_unittests\": \"14f9066ce981c312ce748414ddd0f2fbfb569aa2\", \"extensions_browsertests\": \"77fae973d29652b788bb40bef7ef8bb845bdd26e\", \"extensions_unittests\": \"711e99a024c4bdc27fdad9492a805320ab04a157\", \"filesystem_service_unittests\": \"44ca33d679623ba8f8ace2689bc899e049a9bcb2\", \"gcm_unit_tests\": \"fea8cb0034528ca937902c42097e45b778776257\", \"gfx_unittests\": \"c7c28fac358a23d0af32507b2c59448d76ec573a\", \"gin_unittests\": \"1d798866b0e520757178c57fce46c3e56a46cbaa\", \"gl_unittests_ozone\": \"98d9c8b5636ee370968558d8624f4a01d1a86316\", \"google_apis_unittests\": \"25cfc5b427fa12b6c46a4992dc7127e688806d0b\", \"gpu_unittests\": \"a0b24440b5a0b3df74e45c25061c9125b0c5dcbb\", \"gwp_asan_unittests\": \"75c4b484f1d7e6899e5232b2fa31b8220bec2f8c\", \"interactive_ui_tests\": \"9bb3218870fc34bfea5a874def310064e23c4f5f\", \"ipc_tests\": \"8b6194a4fd63ba46aeb5cfb90a0cf1ae9ad6fc97\", \"jingle_unittests\": \"c7cffbaafee5376de1e77e81a5fb208bab571581\", \"keyboard_unittests\": \"95269cf139e2cc91c0cb33b1597585da6ea920f5\", \"latency_unittests\": \"db9fc94c537bfb31090a08066ceab3597c78e370\", \"libjingle_xmpp_unittests\": \"5bd5dc2e776928787106d5234d34d84d7131d194\", \"liburlpattern_unittests\": \"3671243669eab78eec5b7c1c9836cc9c871c4dac\", \"media_blink_unittests\": \"59899f3abfd6420443af5f06b92a7403371e4a97\", \"media_unittests\": \"0fe28aec10dc02e3df46ffab60ad2e7eb69a69a9\", \"message_center_unittests\": \"a4ef78ff4e58b2b478ad30d83b51f7fef1016b61\", \"midi_unittests\": \"2bf98aa3a505d3017b4948cb6d433302f3db8cd1\", \"mojo_core_unittests\": \"d0d3ebcffdc605a0a5b5d802477699f40eab3603\", \"mojo_unittests\": \"e9c99eab8531f9ab09158cd5b4691fdd1cbfce8c\", \"nacl_helper_nonsfi_unittests\": \"728099975788cd11ce7ef58a6c9305ead65cea1b\", \"nacl_loader_unittests\": \"850b523300e758b6e4ac259b275c166c75d762a8\", \"native_theme_unittests\": \"be5c6af81e98f83952ee3f853a8f2f80ae4e17dd\", \"net_unittests\": \"f85dcd08a6100997af246326b6270a0f1547888f\", \"ozone_gl_unittests\": \"df6ff524e57b518774cb123fa552f69c17b9674a\", \"ozone_unittests\": \"80dc54d18c868b537daddf1f3d8acba7e1c64a56\", \"ozone_x11_unittests\": \"19b2fe550ae4f05e05f8f8aa21b5cb83b60b62da\", \"pdf_unittests\": \"c6c928da5e443c3c6c7ea129b7c4a1a021de8165\", \"perfetto_unittests\": \"3b01792bf85103398323b9bfd2a8e82701baa8d9\", \"ppapi_unittests\": \"deb53906e1289b0758dbc72390db3b9b4b895761\", \"printing_unittests\": \"e8a95182d22263ea9563e26ecea0db905170a393\", \"remoting_unittests\": \"572c3d6412b54b56eecf74f6da625c7983d67bef\", \"sandbox_linux_unittests\": \"09f0d42764a28d84bcc31f4a72f93b1cb94db868\", \"service_manager_unittests\": \"2430bc7a4a639258c7274f505dffcd34c5e9c26e\", \"services_unittests\": \"5b83713fe6ab2bb5e16b4397cfab47f20f42a3c2\", \"shell_dialogs_unittests\": \"b690f9b8ff3600f6fd41aae6fc12664eedad6932\", \"shell_encryption_unittests\": \"8b0f8e99011a3d12dfb55cc8a051048230ea8182\", \"skia_unittests\": \"328bf2fd9bca7ed923fd6b0cbcf88aa67862f941\", \"snapshot_unittests\": \"7e6469f60fff8cc749befa1f1489332371f5f0b4\", \"sql_unittests\": \"4f35728b6dbcb25723ca2ca1d75b54dda2e3f922\", \"storage_unittests\": \"01c1c1566fde79ca652a68427796f48c05d232ab\", \"sync_integration_tests\": \"50f203bfc5e8005fe316069357886df82f5636e7\", \"traffic_annotation_auditor_unittests\": \"d4509887a01ef4362cee9cc08b0dd81fa93ffd34\", \"ui_base_unittests\": \"5e7632cae96093fa0494bf630b39b06db5f677ec\", \"ui_chromeos_unittests\": \"3f1ca9e41af480d10cf97d0f8376dd4d9d2fef51\", \"ui_touch_selection_unittests\": \"ded2039489d33ff5c8aa759e48c748f88b28f5f9\", \"unit_tests\": \"1f4df191c5c96ac6dda29b4d8ea306cdbf7ec7ae\", \"url_unittests\": \"51eccd24262ddcdc5839b6c7d8b8b0fc136e27d1\", \"usage_time_limit_unittests\": \"e68b507d5155af513524a22cfba7258a031770c5\", \"views_unittests\": \"78fb812e67bc2b4db9a8beb80364d3ca2de6ba2b\", \"viz_unittests\": \"d3e456347a29c0fc54d6225137ff3e2e5edc0a68\", \"wm_unittests\": \"9ebfbd01fc8bafa95f460886c83a5ea637a1ca2b\", \"wtf_unittests\": \"5b3bd5f127ce68452db6cd0b7ba60b65b325d68d\", \"zlib_unittests\": \"f1589dedae54672cbccaffb6cafb354c486ce69c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-65-a7fn\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- PowerButtonControllerTest.ClamshellRepeatedPowerButtonReleasesCancelledAnimation\\n\\n- ArcNotificationViewTest.PressBackspaceKeyOnEditBox\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610063691407325",
"status": "COMPLETED",
"status_changed_ts": "1610063691903007",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22107",
"builder:linux-chromeos-dbg",
"buildset:commit/git/b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b8e99dbb10a5a88e66b79267f16b8f2a8d195e2d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024149636454306048",
"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/8858719574463602576/+/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:50f817bc9079fc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610070308349057",
"url": "https://ci.chromium.org/b/8858719574463602576",
"utcnow_ts": "1610070308902632"
}
}
[01:45:08.946] Invocation finished in 1h50m22.43185688s with status FAILED