[17:58:06.212] New invocation is queued and will start shortly
[17:58:09.354] Starting the invocation (attempt 1)
[17:58:09.430] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:58:09.430] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:58:09.432] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022088346086142192",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022088346086142192",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45",
"gitiles_ref:refs/heads/master"
]
}
[17:58:10.217] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612029489578925",
"id": "8856658281894654096",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612029490040163",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22409",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022088346086142192",
"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/8856658281894654096/+/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": "1612029490040273",
"url": "https://ci.chromium.org/b/8856658281894654096",
"utcnow_ts": "1612029490191436"
}
}
[17:58:10.217] Task URL: https://ci.chromium.org/b/8856658281894654096
[17:58:10.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:2:0) after 1m0s
[17:59:35.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:2:0)
[17:59:35.838] Timer tick, asking Buildbucket for the build status
[17:59:36.120] Build 8856658281894654096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:59:36.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:3:0) after 1m0s
[18:00:10.171] Received PubSub notification, asking Buildbucket for the build status
[18:00:10.262] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:36.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:3:0)
[18:00:36.193] Timer tick, asking Buildbucket for the build status
[18:00:36.310] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:36.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:5:0) after 1m0s
[18:01:36.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:5:0)
[18:01:36.328] Timer tick, asking Buildbucket for the build status
[18:01:36.445] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:36.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:6:0) after 1m0s
[18:02:36.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:6:0)
[18:02:36.455] Timer tick, asking Buildbucket for the build status
[18:02:36.683] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:36.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:7:0) after 1m0s
[18:03:36.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:7:0)
[18:03:36.705] Timer tick, asking Buildbucket for the build status
[18:03:37.026] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:37.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:8:0) after 1m0s
[18:04:37.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:8:0)
[18:04:37.084] Timer tick, asking Buildbucket for the build status
[18:04:37.249] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:37.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:9:0) after 1m0s
[18:05:37.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:9:0)
[18:05:37.231] Timer tick, asking Buildbucket for the build status
[18:05:37.414] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:37.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:10:0) after 1m0s
[18:06:37.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:10:0)
[18:06:37.443] Timer tick, asking Buildbucket for the build status
[18:06:37.602] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:37.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:11:0) after 1m0s
[18:07:37.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:11:0)
[18:07:37.621] Timer tick, asking Buildbucket for the build status
[18:07:37.695] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:37.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:12:0) after 1m0s
[18:08:37.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:12:0)
[18:08:37.711] Timer tick, asking Buildbucket for the build status
[18:08:37.771] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:37.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:13:0) after 1m0s
[18:09:37.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:13:0)
[18:09:37.791] Timer tick, asking Buildbucket for the build status
[18:09:37.962] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:37.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:14:0) after 1m0s
[18:10:37.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:14:0)
[18:10:37.983] Timer tick, asking Buildbucket for the build status
[18:10:39.333] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:39.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:15:0) after 1m0s
[18:11:39.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:15:0)
[18:11:39.456] Timer tick, asking Buildbucket for the build status
[18:11:39.517] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:39.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:16:0) after 1m0s
[18:12:39.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:16:0)
[18:12:39.535] Timer tick, asking Buildbucket for the build status
[18:12:39.815] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:39.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:17:0) after 1m0s
[18:13:39.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:17:0)
[18:13:39.836] Timer tick, asking Buildbucket for the build status
[18:13:40.037] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:40.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:18:0) after 1m0s
[18:14:40.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:18:0)
[18:14:40.111] Timer tick, asking Buildbucket for the build status
[18:14:40.321] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:40.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:19:0) after 1m0s
[18:15:40.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:19:0)
[18:15:40.359] Timer tick, asking Buildbucket for the build status
[18:15:40.414] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:40.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:20:0) after 1m0s
[18:16:40.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:20:0)
[18:16:40.531] Timer tick, asking Buildbucket for the build status
[18:16:40.601] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:40.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:21:0) after 1m0s
[18:17:40.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:21:0)
[18:17:40.685] Timer tick, asking Buildbucket for the build status
[18:17:40.924] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:40.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:22:0) after 1m0s
[18:18:40.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:22:0)
[18:18:40.942] Timer tick, asking Buildbucket for the build status
[18:18:41.003] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:41.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:23:0) after 1m0s
[18:19:41.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:23:0)
[18:19:41.034] Timer tick, asking Buildbucket for the build status
[18:19:41.126] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:41.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:24:0) after 1m0s
[18:20:41.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:24:0)
[18:20:41.126] Timer tick, asking Buildbucket for the build status
[18:20:41.195] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:41.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:25:0) after 1m0s
[18:21:41.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:25:0)
[18:21:41.227] Timer tick, asking Buildbucket for the build status
[18:21:41.278] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:41.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:26:0) after 1m0s
[18:22:41.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:26:0)
[18:22:41.322] Timer tick, asking Buildbucket for the build status
[18:22:41.376] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:41.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:27:0) after 1m0s
[18:23:41.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:27:0)
[18:23:41.418] Timer tick, asking Buildbucket for the build status
[18:23:41.608] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:41.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:28:0) after 1m0s
[18:24:41.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:28:0)
[18:24:41.680] Timer tick, asking Buildbucket for the build status
[18:24:42.294] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:42.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:29:0) after 1m0s
[18:25:42.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:29:0)
[18:25:42.328] Timer tick, asking Buildbucket for the build status
[18:25:42.755] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:42.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:30:0) after 1m0s
[18:26:42.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:30:0)
[18:26:42.774] Timer tick, asking Buildbucket for the build status
[18:26:42.824] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:42.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:31:0) after 1m0s
[18:27:42.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:31:0)
[18:27:42.843] Timer tick, asking Buildbucket for the build status
[18:27:42.929] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:42.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:32:0) after 1m0s
[18:28:42.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:32:0)
[18:28:42.973] Timer tick, asking Buildbucket for the build status
[18:28:43.117] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:43.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:33:0) after 1m0s
[18:29:43.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:33:0)
[18:29:43.106] Timer tick, asking Buildbucket for the build status
[18:29:43.207] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:43.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:34:0) after 1m0s
[18:30:43.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:34:0)
[18:30:43.232] Timer tick, asking Buildbucket for the build status
[18:30:43.311] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:43.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:35:0) after 1m0s
[18:31:43.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:35:0)
[18:31:43.340] Timer tick, asking Buildbucket for the build status
[18:31:43.509] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:43.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:36:0) after 1m0s
[18:32:43.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:36:0)
[18:32:43.574] Timer tick, asking Buildbucket for the build status
[18:32:43.775] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:43.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:37:0) after 1m0s
[18:33:43.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:37:0)
[18:33:43.857] Timer tick, asking Buildbucket for the build status
[18:33:43.975] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:43.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:38:0) after 1m0s
[18:34:44.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:38:0)
[18:34:44.003] Timer tick, asking Buildbucket for the build status
[18:34:44.116] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:44.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:39:0) after 1m0s
[18:35:44.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:39:0)
[18:35:44.136] Timer tick, asking Buildbucket for the build status
[18:35:44.201] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:44.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:40:0) after 1m0s
[18:36:44.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:40:0)
[18:36:44.249] Timer tick, asking Buildbucket for the build status
[18:36:44.301] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:44.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:41:0) after 1m0s
[18:37:44.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:41:0)
[18:37:44.334] Timer tick, asking Buildbucket for the build status
[18:37:44.739] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:44.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:42:0) after 1m0s
[18:38:44.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:42:0)
[18:38:44.836] Timer tick, asking Buildbucket for the build status
[18:38:44.957] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:44.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:43:0) after 1m0s
[18:39:45.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:43:0)
[18:39:45.002] Timer tick, asking Buildbucket for the build status
[18:39:45.114] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:45.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:44:0) after 1m0s
[18:40:45.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:44:0)
[18:40:45.106] Timer tick, asking Buildbucket for the build status
[18:40:45.232] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:45.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:45:0) after 1m0s
[18:41:45.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:45:0)
[18:41:45.248] Timer tick, asking Buildbucket for the build status
[18:41:45.364] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:45.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:46:0) after 1m0s
[18:42:45.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:46:0)
[18:42:45.382] Timer tick, asking Buildbucket for the build status
[18:42:45.485] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:45.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:47:0) after 1m0s
[18:43:45.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:47:0)
[18:43:45.484] Timer tick, asking Buildbucket for the build status
[18:43:45.752] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:45.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:48:0) after 1m0s
[18:44:45.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:48:0)
[18:44:45.761] Timer tick, asking Buildbucket for the build status
[18:44:45.848] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:45.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:49:0) after 1m0s
[18:45:45.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:49:0)
[18:45:45.912] Timer tick, asking Buildbucket for the build status
[18:45:46.414] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:46.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:50:0) after 1m0s
[18:46:46.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:50:0)
[18:46:46.512] Timer tick, asking Buildbucket for the build status
[18:46:46.594] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:46.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:51:0) after 1m0s
[18:47:46.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:51:0)
[18:47:46.646] Timer tick, asking Buildbucket for the build status
[18:47:46.718] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:46.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:52:0) after 1m0s
[18:48:46.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:52:0)
[18:48:46.759] Timer tick, asking Buildbucket for the build status
[18:48:47.120] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:47.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:53:0) after 1m0s
[18:49:47.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:53:0)
[18:49:47.163] Timer tick, asking Buildbucket for the build status
[18:49:47.268] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:47.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:54:0) after 1m0s
[18:50:47.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:54:0)
[18:50:47.366] Timer tick, asking Buildbucket for the build status
[18:50:47.609] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:47.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:55:0) after 1m0s
[18:51:47.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:55:0)
[18:51:47.708] Timer tick, asking Buildbucket for the build status
[18:51:47.785] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:47.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:56:0) after 1m0s
[18:52:47.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:56:0)
[18:52:47.991] Timer tick, asking Buildbucket for the build status
[18:52:48.403] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:48.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:57:0) after 1m0s
[18:53:48.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:57:0)
[18:53:48.613] Timer tick, asking Buildbucket for the build status
[18:53:48.801] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:48.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:58:0) after 1m0s
[18:54:48.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:58:0)
[18:54:48.822] Timer tick, asking Buildbucket for the build status
[18:54:48.974] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:48.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:59:0) after 1m0s
[18:55:49.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:59:0)
[18:55:49.005] Timer tick, asking Buildbucket for the build status
[18:55:49.230] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:49.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:60:0) after 1m0s
[18:56:49.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:60:0)
[18:56:49.315] Timer tick, asking Buildbucket for the build status
[18:56:49.526] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:49.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:61:0) after 1m0s
[18:57:49.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:61:0)
[18:57:49.718] Timer tick, asking Buildbucket for the build status
[18:57:49.831] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:49.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:62:0) after 1m0s
[18:58:49.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:62:0)
[18:58:49.837] Timer tick, asking Buildbucket for the build status
[18:58:49.948] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:49.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:63:0) after 1m0s
[18:59:49.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:63:0)
[18:59:49.970] Timer tick, asking Buildbucket for the build status
[18:59:50.089] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:50.089] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:64:0) after 1m0s
[19:00:50.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:64:0)
[19:00:50.141] Timer tick, asking Buildbucket for the build status
[19:00:50.643] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:50.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:65:0) after 1m0s
[19:01:50.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:65:0)
[19:01:50.821] Timer tick, asking Buildbucket for the build status
[19:01:51.419] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:51.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:66:0) after 1m0s
[19:02:51.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:66:0)
[19:02:51.528] Timer tick, asking Buildbucket for the build status
[19:02:51.629] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:51.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:67:0) after 1m0s
[19:03:51.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:67:0)
[19:03:51.726] Timer tick, asking Buildbucket for the build status
[19:03:52.097] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:52.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:68:0) after 1m0s
[19:04:52.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:68:0)
[19:04:52.131] Timer tick, asking Buildbucket for the build status
[19:04:52.236] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:52.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:69:0) after 1m0s
[19:05:52.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:69:0)
[19:05:52.340] Timer tick, asking Buildbucket for the build status
[19:05:52.699] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:52.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:70:0) after 1m0s
[19:06:52.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:70:0)
[19:06:52.727] Timer tick, asking Buildbucket for the build status
[19:06:52.930] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:52.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:71:0) after 1m0s
[19:07:52.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:71:0)
[19:07:52.951] Timer tick, asking Buildbucket for the build status
[19:07:53.112] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:53.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:72:0) after 1m0s
[19:08:53.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:72:0)
[19:08:53.138] Timer tick, asking Buildbucket for the build status
[19:08:53.200] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:53.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:73:0) after 1m0s
[19:09:53.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:73:0)
[19:09:53.222] Timer tick, asking Buildbucket for the build status
[19:09:53.684] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:53.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:74:0) after 1m0s
[19:10:53.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:74:0)
[19:10:53.771] Timer tick, asking Buildbucket for the build status
[19:10:54.730] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:54.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:75:0) after 1m0s
[19:11:54.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:75:0)
[19:11:54.749] Timer tick, asking Buildbucket for the build status
[19:11:55.333] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:55.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:76:0) after 1m0s
[19:12:55.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:76:0)
[19:12:55.338] Timer tick, asking Buildbucket for the build status
[19:12:56.004] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:56.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:77:0) after 1m0s
[19:13:56.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:77:0)
[19:13:56.070] Timer tick, asking Buildbucket for the build status
[19:13:56.268] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:56.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:78:0) after 1m0s
[19:14:56.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:78:0)
[19:14:56.288] Timer tick, asking Buildbucket for the build status
[19:14:56.483] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:56.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:79:0) after 1m0s
[19:15:56.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:79:0)
[19:15:56.509] Timer tick, asking Buildbucket for the build status
[19:15:56.907] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:56.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:80:0) after 1m0s
[19:16:56.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:80:0)
[19:16:56.990] Timer tick, asking Buildbucket for the build status
[19:16:57.301] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:57.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:81:0) after 1m0s
[19:17:57.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:81:0)
[19:17:57.330] Timer tick, asking Buildbucket for the build status
[19:17:57.394] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:57.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:82:0) after 1m0s
[19:18:57.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:82:0)
[19:18:57.449] Timer tick, asking Buildbucket for the build status
[19:18:57.545] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:57.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:83:0) after 1m0s
[19:19:57.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:83:0)
[19:19:57.599] Timer tick, asking Buildbucket for the build status
[19:19:57.712] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:57.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:84:0) after 1m0s
[19:20:57.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:84:0)
[19:20:57.792] Timer tick, asking Buildbucket for the build status
[19:20:58.022] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:58.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:85:0) after 1m0s
[19:21:58.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:85:0)
[19:21:58.043] Timer tick, asking Buildbucket for the build status
[19:21:58.138] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:58.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:86:0) after 1m0s
[19:22:58.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:86:0)
[19:22:58.165] Timer tick, asking Buildbucket for the build status
[19:22:58.235] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:58.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:87:0) after 1m0s
[19:23:58.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:87:0)
[19:23:58.295] Timer tick, asking Buildbucket for the build status
[19:23:58.365] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:58.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:88:0) after 1m0s
[19:24:58.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:88:0)
[19:24:58.406] Timer tick, asking Buildbucket for the build status
[19:24:58.808] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:58.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:89:0) after 1m0s
[19:25:58.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:89:0)
[19:25:58.929] Timer tick, asking Buildbucket for the build status
[19:25:59.018] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:59.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:90:0) after 1m0s
[19:26:59.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:90:0)
[19:26:59.029] Timer tick, asking Buildbucket for the build status
[19:26:59.092] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:59.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:91:0) after 1m0s
[19:27:59.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:91:0)
[19:27:59.165] Timer tick, asking Buildbucket for the build status
[19:27:59.238] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:59.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:92:0) after 1m0s
[19:28:59.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:92:0)
[19:28:59.408] Timer tick, asking Buildbucket for the build status
[19:28:59.464] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:59.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:93:0) after 1m0s
[19:29:59.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:93:0)
[19:29:59.603] Timer tick, asking Buildbucket for the build status
[19:29:59.717] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:59.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:94:0) after 1m0s
[19:30:59.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:94:0)
[19:30:59.759] Timer tick, asking Buildbucket for the build status
[19:31:00.159] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:00.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:95:0) after 1m0s
[19:32:00.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:95:0)
[19:32:00.319] Timer tick, asking Buildbucket for the build status
[19:32:00.532] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:00.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:96:0) after 1m0s
[19:33:00.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:96:0)
[19:33:00.618] Timer tick, asking Buildbucket for the build status
[19:33:00.687] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:00.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:97:0) after 1m0s
[19:34:00.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:97:0)
[19:34:00.712] Timer tick, asking Buildbucket for the build status
[19:34:00.813] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:00.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:98:0) after 1m0s
[19:35:00.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:98:0)
[19:35:00.885] Timer tick, asking Buildbucket for the build status
[19:35:01.012] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:01.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:99:0) after 1m0s
[19:36:01.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:99:0)
[19:36:01.496] Timer tick, asking Buildbucket for the build status
[19:36:01.564] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:01.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:100:0) after 1m0s
[19:37:01.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:100:0)
[19:37:01.597] Timer tick, asking Buildbucket for the build status
[19:37:02.108] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:02.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:101:0) after 1m0s
[19:38:02.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:101:0)
[19:38:02.129] Timer tick, asking Buildbucket for the build status
[19:38:02.274] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:02.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:102:0) after 1m0s
[19:39:02.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:102:0)
[19:39:02.339] Timer tick, asking Buildbucket for the build status
[19:39:03.094] Build 8856658281894654096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:03.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022088346086142192:103:0) after 1m0s
[19:39:43.630] Received PubSub notification, asking Buildbucket for the build status
[19:39:43.853] Build 8856658281894654096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:39:43.853] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612035582443088",
"created_by": "project:chromium",
"created_ts": "1612029489578925",
"id": "8856658281894654096",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45\", \"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-east1-d-8-61-5x80\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22409, \"got_angle_revision\": \"74f7be6d1fd020a432dee63222fa090e4d8df651\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45\", \"got_revision_cp\": \"refs/heads/master@{#848872}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"cbbe339c1ca9754b3123fe2bdb83c0f58ee5d728\", \"got_v8_revision_cp\": \"refs/heads/9.0.101@{#1}\", \"got_webrtc_revision\": \"f9a6148e0498abdbe74eb6edf6aac78667d29499\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33109}\", \"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\": \"5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45\", \"swarm_hashes_refs/heads/master(at){#848872}_without_patch\": {\"absl_hardening_tests\": \"ebf8df651c660dfcfaaaa5413b6b5066df304bd2\", \"accessibility_unittests\": \"e4011ba987fbe403a4b994b02a3e4306a1f45efa\", \"angle_unittests\": \"ffb5f108145a7bc3394abcbc64a90b7f26567453\", \"app_list_unittests\": \"e5900f4ffa94012a036c5bb4968f0e1919ac674c\", \"app_shell_unittests\": \"c71e0fff0ca7b796af13041f7e7ccf210c225faf\", \"ash_unittests\": \"71da75d2b86faa668629fdb8d580dbb8f96c2bda\", \"aura_unittests\": \"c81fffd345024061c1e157dbeb1b876f1e24a699\", \"base_unittests\": \"567d9a0cec1a5626251a7f597b695316fd0a9c73\", \"base_util_unittests\": \"2a3029ff80f83554f165b76dbe5134b47627ffd9\", \"blink_common_unittests\": \"f579b1b89a0645dcd3c25c9a6ab8ffd8d26ff8d6\", \"blink_fuzzer_unittests\": \"f712ba9f260b558ab1ca997f25f6062d4211ff38\", \"blink_heap_unittests\": \"72f66e1e913e4a3f8c98184564cc0063accb4595\", \"blink_platform_unittests\": \"181c592065572965e3f446eaab66a32b9ed06e00\", \"blink_unittests\": \"fe4881c4bbba594beb5fa483606b9f2add8a9fe7\", \"boringssl_crypto_tests\": \"14896789cc439f669fa943c82ecc31d09e483be4\", \"boringssl_ssl_tests\": \"eefe7cd34913b9bd3b6c39262ca860e9ad718862\", \"browser_tests\": \"39766245bf1687f7e39a7ce660723b8ca1e16c6a\", \"capture_unittests\": \"01d1b466e1d4d7d3be9429a79a80d2c81c54f184\", \"cast_unittests\": \"8da12d21f7987a24412897114e2d07f5e8a66ec3\", \"cc_unittests\": \"21cdda2afa0241f1c2cedf2d355f2f9489862087\", \"chrome_app_unittests\": \"ed2aec549e783ca382700a4844a58dfb4157b57f\", \"chromedriver_unittests\": \"3ad05d7dd2b2d20d80b6219cdce4925a387aeb52\", \"chromeos_components_unittests\": \"a6c866b3037c57a8328e964afba8dd6fb955fd22\", \"chromeos_unittests\": \"c24f704e662b0bb42dcdd79f2899bd08e6bb28c4\", \"components_browsertests\": \"8c508d0552f89eb74d9f5104fa791d7e7ef9ad3c\", \"components_unittests\": \"6f4c55369719e7f49c86f92c00fa8a56958dd580\", \"compositor_unittests\": \"ad35efecf8aaef2a3adedef41faeef883f2b6421\", \"content_browsertests\": \"17d275527859eca3f8d3cc6efd16dfa0bd9d118c\", \"content_unittests\": \"c41c882b210dd54679ba579a99192f2494d9abb2\", \"crashpad_tests\": \"817204909c2eae8979770fea84c84bb3b5540558\", \"crypto_unittests\": \"88d461dfce1e467aa7e80da91cb94ef5446f159b\", \"dbus_unittests\": \"8f5b8148f8aeb386ef850ecd44b2044592adb9c3\", \"device_unittests\": \"ec13391b74e5352e8ac19847e044bb52e31e461d\", \"display_unittests\": \"8e23e7a0407cec945c5dc2fb5229bdb9524e86c2\", \"events_unittests\": \"10aa70bdadecbc03715e528a387c8db87d5b5438\", \"exo_unittests\": \"e129eddbd382fb1a8c233b491a43d5b7e18dd1d8\", \"extensions_browsertests\": \"99cdefe772012169c3dac944979294714e3df80d\", \"extensions_unittests\": \"7a0342c2108c68a52b4b4b6ae8275c81be48af2b\", \"filesystem_service_unittests\": \"5b82b07312b73e00ee11538bcb96e6329a7a0dd0\", \"gcm_unit_tests\": \"0f8e242791e3616d160554c74387388bc51e4eb2\", \"gfx_unittests\": \"ab14b6be1a2b5f8d3cb9759b7e36280c999f9939\", \"gin_unittests\": \"2cb01eccb54065a9c144ff7827b5487b6d7c0b3a\", \"gl_unittests_ozone\": \"8a6b548fcc3120311597221820c6de3b95a5e27a\", \"google_apis_unittests\": \"a85ad1240edb1c80f16e3f2f82ea351905faef01\", \"gpu_unittests\": \"2e2d4ba99bc045575dc616b39ba65803edc94b99\", \"gwp_asan_unittests\": \"be19e3d043cd2724f2d7a633d6c1d5be1f5da025\", \"interactive_ui_tests\": \"591bf84a47ec63f22a844f481dacbbcc666d4be4\", \"ipc_tests\": \"d15ebc3e7d0e30985432d592391997cfbe8f13d1\", \"jingle_unittests\": \"09ffc0a76b265ca95ce1a3a7830d0a0c4d13cba6\", \"keyboard_unittests\": \"eab0c22ed1ae34ada8cca35584519df9c8ae39b4\", \"latency_unittests\": \"4b04f91391d758476ec07c6235e3530ff9e9b489\", \"libjingle_xmpp_unittests\": \"05d18dc18821895d58f99be585d4c3f29c5b3993\", \"liburlpattern_unittests\": \"d8ee6d4550ce95b9544819ea0dc2928d9497c8ac\", \"media_blink_unittests\": \"b82b17baabbc8f0e5f1039147f34f96f3c9a067e\", \"media_unittests\": \"8215a970296d47b9d2d12d9009f71f4161737101\", \"message_center_unittests\": \"9a1a7c0d8e3c0993687990e70ddf468fb567f82f\", \"midi_unittests\": \"482f01ad25a9dd81fa13d8d648c8638dd76aa715\", \"mojo_core_unittests\": \"890983ff1c79e931ea96a0a788e5ebcec82d68d8\", \"mojo_unittests\": \"18fd01cf7809d54880be9f5be2835874dca0eda9\", \"nacl_helper_nonsfi_unittests\": \"971d2c10f92157b50416d2097da0ed9de8a47704\", \"nacl_loader_unittests\": \"6b9b03c37de7c09997e237fd03109f483ca70415\", \"native_theme_unittests\": \"ad7965ce0095388866dadb14c953ae046f12e673\", \"net_unittests\": \"519a861d95fb64cfa3c3163cb7a55589279e5be0\", \"ozone_gl_unittests\": \"14fbe38c893735ef0a0a0aae787e09687e3486aa\", \"ozone_unittests\": \"34c0ed0299c3d355838abb5e876770d059f4b3d2\", \"ozone_x11_unittests\": \"cb34b17b10a0c2bba60a8fefb93fde4d30640e84\", \"pdf_unittests\": \"f3a2559d9b9d8539c29a689690399a4a290a1586\", \"perfetto_unittests\": \"54105638b0230aa8c4dda38a675770e7a24b40e2\", \"ppapi_unittests\": \"c93e6b6bf03a03723de0432912a1ef78e1d23773\", \"printing_unittests\": \"989e672d4e103814c3c56315c1a26d8f7bc70aec\", \"remoting_unittests\": \"867fab33fe726d7ad485dfb6f4b2ab04fcac9381\", \"sandbox_linux_unittests\": \"71525a408a4b6484fc8f54515c2ca696ea3cc4eb\", \"service_manager_unittests\": \"4b6efa3b4b2fa25d60be8ad076e33f5b5c0a2012\", \"services_unittests\": \"c6bbdceb076d9be0e93045d31a7944bc919ffbaf\", \"shell_dialogs_unittests\": \"0fc8da6d861776d3e6f864a8cc60a98b038354b7\", \"shell_encryption_unittests\": \"f642003b15ae8029d9543a28f6f6fced5c39311c\", \"skia_unittests\": \"d5fd3762a4720ea2e4a6993dc55e254c9f760425\", \"snapshot_unittests\": \"04667c0cf9a5d0ccf42670b4e5c7e7f772699378\", \"sql_unittests\": \"94a7f2e130ff2496755c7f2aa08fc7bead3acc3e\", \"storage_unittests\": \"aa1e49f941b3021c3a99699d5965746dd058c2c1\", \"sync_integration_tests\": \"8967a19e8ee3e7f4c80108028213ed617dfd9edb\", \"traffic_annotation_auditor_unittests\": \"5d6871bcf2b64f52e3851db5bfb99dec15b2102c\", \"ui_base_unittests\": \"3f59119e122e6f582663e9e8922ef9c425acbf67\", \"ui_chromeos_unittests\": \"eea73e46e4dec0b1af855043597afa21691f82ae\", \"ui_touch_selection_unittests\": \"1e20bff59f36c97187e44be2836682392b6ae78d\", \"unit_tests\": \"3d14b37d4096a81a8bd9359858ac0d21368c8fa5\", \"url_unittests\": \"0e2d40d8afad8fbfa87bae5d2edc63454d3d0176\", \"usage_time_limit_unittests\": \"e70ab63ee5cd4a339f6e00e548b427c29f1255db\", \"views_unittests\": \"ffba1938e95a2619e0ee9e9065641214cf41b1fe\", \"viz_unittests\": \"72c802a5573950524ab2baaf42b148af5b004904\", \"wm_unittests\": \"3eeff0c0f3f308a0e94d85167e6b18390cb33764\", \"wtf_unittests\": \"171a7643f05bec04e89ce671aa7932c764e604eb\", \"zlib_unittests\": \"ac0d6d27b43aed0bfee9f3dcae386d482b055658\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1bdcc2c1d38386cf72a342843f986ec12bfab33633905ef2709b97cea999d0f5_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_9b7d5777aa750248ebad695eb4fcd35fb9fc6d25bb624af41f2fa2acf3d9e522_v2\", \"builder_d1071779fa62103c126fbddd8eb4d0fa2c72f39bcf76fe56968d2607dc4a05dd_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-east1-d-8-61-5x80\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612029609222515",
"status": "COMPLETED",
"status_changed_ts": "1612035582742187",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22409",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b7ee3315f9d5a3c3a31da5f4c41d7989e6a1b45",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022088346086142192",
"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/8856658281894654096/+/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:516d4440971a7a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612035582774180",
"url": "https://ci.chromium.org/b/8856658281894654096",
"utcnow_ts": "1612035583841160"
}
}
[19:39:43.854] Invocation finished in 1h41m37.656151805s with status SUCCEEDED