[02:36:22.734] New invocation is queued and will start shortly
[02:36:23.889] Starting the invocation (attempt 1)
[02:36:23.958] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:36:23.958] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:36:23.958] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021240366323434304",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1a306bbd8ee23d3ed62d3ecf6333b475ce363648\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021240366323434304",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/1a306bbd8ee23d3ed62d3ecf6333b475ce363648",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a306bbd8ee23d3ed62d3ecf6333b475ce363648",
"gitiles_ref:refs/heads/master"
]
}
[02:36:24.695] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612838184121326",
"id": "8855810304205602176",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a306bbd8ee23d3ed62d3ecf6333b475ce363648\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612838184542651",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22542",
"builder:linux-chromeos-dbg",
"buildset:commit/git/1a306bbd8ee23d3ed62d3ecf6333b475ce363648",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a306bbd8ee23d3ed62d3ecf6333b475ce363648",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021240366323434304",
"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/8855810304205602176/+/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": "1612838184542764",
"url": "https://ci.chromium.org/b/8855810304205602176",
"utcnow_ts": "1612838184670230"
}
}
[02:36:24.695] Task URL: https://ci.chromium.org/b/8855810304205602176
[02:36:24.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:2:0) after 1m0s
[02:37:24.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:2:0)
[02:37:24.719] Timer tick, asking Buildbucket for the build status
[02:37:25.123] Build 8855810304205602176: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:25.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:3:0) after 1m0s
[02:38:17.759] Received PubSub notification, asking Buildbucket for the build status
[02:38:18.002] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:25.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:3:0)
[02:38:25.142] Timer tick, asking Buildbucket for the build status
[02:38:25.329] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:25.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:5:0) after 1m0s
[02:39:25.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:5:0)
[02:39:25.455] Timer tick, asking Buildbucket for the build status
[02:39:25.571] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:25.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:6:0) after 1m0s
[02:40:25.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:6:0)
[02:40:25.596] Timer tick, asking Buildbucket for the build status
[02:40:25.655] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:25.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:7:0) after 1m0s
[02:41:25.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:7:0)
[02:41:25.753] Timer tick, asking Buildbucket for the build status
[02:41:26.000] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:26.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:8:0) after 1m0s
[02:42:26.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:8:0)
[02:42:26.144] Timer tick, asking Buildbucket for the build status
[02:42:26.279] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:26.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:9:0) after 1m0s
[02:43:26.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:9:0)
[02:43:26.421] Timer tick, asking Buildbucket for the build status
[02:43:26.792] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:26.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:10:0) after 1m0s
[02:44:26.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:10:0)
[02:44:26.858] Timer tick, asking Buildbucket for the build status
[02:44:26.975] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:26.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:11:0) after 1m0s
[02:45:26.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:11:0)
[02:45:26.993] Timer tick, asking Buildbucket for the build status
[02:45:27.141] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:27.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:12:0) after 1m0s
[02:46:27.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:12:0)
[02:46:27.233] Timer tick, asking Buildbucket for the build status
[02:46:27.353] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:27.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:13:0) after 1m0s
[02:47:27.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:13:0)
[02:47:27.367] Timer tick, asking Buildbucket for the build status
[02:47:27.430] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:27.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:14:0) after 1m0s
[02:48:27.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:14:0)
[02:48:27.499] Timer tick, asking Buildbucket for the build status
[02:48:27.642] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:27.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:15:0) after 1m0s
[02:49:27.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:15:0)
[02:49:27.664] Timer tick, asking Buildbucket for the build status
[02:49:27.723] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:27.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:16:0) after 1m0s
[02:50:27.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:16:0)
[02:50:27.752] Timer tick, asking Buildbucket for the build status
[02:50:27.810] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:27.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:17:0) after 1m0s
[02:51:27.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:17:0)
[02:51:27.851] Timer tick, asking Buildbucket for the build status
[02:51:27.972] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:27.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:18:0) after 1m0s
[02:52:28.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:18:0)
[02:52:28.039] Timer tick, asking Buildbucket for the build status
[02:52:28.503] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:28.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:19:0) after 1m0s
[02:53:28.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:19:0)
[02:53:28.551] Timer tick, asking Buildbucket for the build status
[02:53:28.917] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:28.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:20:0) after 1m0s
[02:54:28.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:20:0)
[02:54:28.953] Timer tick, asking Buildbucket for the build status
[02:54:29.017] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:29.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:21:0) after 1m0s
[02:55:29.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:21:0)
[02:55:29.029] Timer tick, asking Buildbucket for the build status
[02:55:29.228] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:29.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:22:0) after 1m0s
[02:56:29.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:22:0)
[02:56:29.324] Timer tick, asking Buildbucket for the build status
[02:56:29.466] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:29.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:23:0) after 1m0s
[02:57:29.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:23:0)
[02:57:29.490] Timer tick, asking Buildbucket for the build status
[02:57:29.610] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:29.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:24:0) after 1m0s
[02:58:29.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:24:0)
[02:58:29.640] Timer tick, asking Buildbucket for the build status
[02:58:29.738] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:29.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:25:0) after 1m0s
[02:59:29.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:25:0)
[02:59:29.755] Timer tick, asking Buildbucket for the build status
[02:59:29.837] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:29.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:26:0) after 1m0s
[03:00:29.854] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:26:0)
[03:00:29.854] Timer tick, asking Buildbucket for the build status
[03:00:29.970] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:29.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:27:0) after 1m0s
[03:01:30.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:27:0)
[03:01:30.021] Timer tick, asking Buildbucket for the build status
[03:01:30.192] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:30.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:28:0) after 1m0s
[03:02:30.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:28:0)
[03:02:30.280] Timer tick, asking Buildbucket for the build status
[03:02:30.390] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:30.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:29:0) after 1m0s
[03:03:30.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:29:0)
[03:03:30.416] Timer tick, asking Buildbucket for the build status
[03:03:30.711] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:30.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:30:0) after 1m0s
[03:04:30.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:30:0)
[03:04:30.733] Timer tick, asking Buildbucket for the build status
[03:04:30.785] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:30.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:31:0) after 1m0s
[03:05:30.789] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:31:0)
[03:05:30.789] Timer tick, asking Buildbucket for the build status
[03:05:31.006] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:31.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:32:0) after 1m0s
[03:06:31.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:32:0)
[03:06:31.027] Timer tick, asking Buildbucket for the build status
[03:06:31.220] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:31.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:33:0) after 1m0s
[03:07:31.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:33:0)
[03:07:31.330] Timer tick, asking Buildbucket for the build status
[03:07:31.511] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:31.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:34:0) after 1m0s
[03:08:31.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:34:0)
[03:08:31.597] Timer tick, asking Buildbucket for the build status
[03:08:32.201] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:32.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:35:0) after 1m0s
[03:09:32.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:35:0)
[03:09:32.287] Timer tick, asking Buildbucket for the build status
[03:09:32.417] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:32.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:36:0) after 1m0s
[03:10:32.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:36:0)
[03:10:32.442] Timer tick, asking Buildbucket for the build status
[03:10:32.505] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:32.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:37:0) after 1m0s
[03:11:32.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:37:0)
[03:11:32.546] Timer tick, asking Buildbucket for the build status
[03:11:32.656] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:32.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:38:0) after 1m0s
[03:12:32.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:38:0)
[03:12:32.687] Timer tick, asking Buildbucket for the build status
[03:12:32.940] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:32.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:39:0) after 1m0s
[03:13:32.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:39:0)
[03:13:32.951] Timer tick, asking Buildbucket for the build status
[03:13:33.015] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:33.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:40:0) after 1m0s
[03:14:33.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:40:0)
[03:14:33.104] Timer tick, asking Buildbucket for the build status
[03:14:33.167] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:33.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:41:0) after 1m0s
[03:15:33.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:41:0)
[03:15:33.191] Timer tick, asking Buildbucket for the build status
[03:15:33.344] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:33.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:42:0) after 1m0s
[03:16:33.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:42:0)
[03:16:33.462] Timer tick, asking Buildbucket for the build status
[03:16:33.810] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:33.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:43:0) after 1m0s
[03:17:33.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:43:0)
[03:17:33.878] Timer tick, asking Buildbucket for the build status
[03:17:34.193] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:34.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:44:0) after 1m0s
[03:18:34.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:44:0)
[03:18:34.209] Timer tick, asking Buildbucket for the build status
[03:18:34.324] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:34.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:45:0) after 1m0s
[03:19:34.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:45:0)
[03:19:34.343] Timer tick, asking Buildbucket for the build status
[03:19:34.499] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:34.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:46:0) after 1m0s
[03:20:34.590] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:46:0)
[03:20:34.590] Timer tick, asking Buildbucket for the build status
[03:20:34.650] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:34.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:47:0) after 1m0s
[03:21:34.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:47:0)
[03:21:34.857] Timer tick, asking Buildbucket for the build status
[03:21:34.979] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:34.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:48:0) after 1m0s
[03:22:35.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:48:0)
[03:22:35.105] Timer tick, asking Buildbucket for the build status
[03:22:35.344] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:35.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:49:0) after 1m0s
[03:23:35.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:49:0)
[03:23:35.403] Timer tick, asking Buildbucket for the build status
[03:23:35.487] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:35.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:50:0) after 1m0s
[03:24:35.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:50:0)
[03:24:35.698] Timer tick, asking Buildbucket for the build status
[03:24:35.826] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:35.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:51:0) after 1m0s
[03:25:35.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:51:0)
[03:25:35.994] Timer tick, asking Buildbucket for the build status
[03:25:36.543] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:36.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:52:0) after 1m0s
[03:26:36.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:52:0)
[03:26:36.647] Timer tick, asking Buildbucket for the build status
[03:26:37.075] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:37.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:53:0) after 1m0s
[03:27:37.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:53:0)
[03:27:37.107] Timer tick, asking Buildbucket for the build status
[03:27:37.242] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:37.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:54:0) after 1m0s
[03:28:37.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:54:0)
[03:28:37.324] Timer tick, asking Buildbucket for the build status
[03:28:37.440] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:37.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:55:0) after 1m0s
[03:29:37.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:55:0)
[03:29:37.698] Timer tick, asking Buildbucket for the build status
[03:29:37.841] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:37.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:56:0) after 1m0s
[03:30:37.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:56:0)
[03:30:37.893] Timer tick, asking Buildbucket for the build status
[03:30:37.968] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:37.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:57:0) after 1m0s
[03:31:38.060] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:57:0)
[03:31:38.060] Timer tick, asking Buildbucket for the build status
[03:31:38.195] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:38.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:58:0) after 1m0s
[03:32:38.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:58:0)
[03:32:38.244] Timer tick, asking Buildbucket for the build status
[03:32:38.749] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:38.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:59:0) after 1m0s
[03:33:38.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:59:0)
[03:33:38.727] Timer tick, asking Buildbucket for the build status
[03:33:38.812] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:38.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:60:0) after 1m0s
[03:34:38.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:60:0)
[03:34:38.864] Timer tick, asking Buildbucket for the build status
[03:34:38.936] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:38.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:61:0) after 1m0s
[03:35:38.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:61:0)
[03:35:38.986] Timer tick, asking Buildbucket for the build status
[03:35:39.054] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:39.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:62:0) after 1m0s
[03:36:39.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:62:0)
[03:36:39.124] Timer tick, asking Buildbucket for the build status
[03:36:39.242] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:39.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:63:0) after 1m0s
[03:37:39.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:63:0)
[03:37:39.256] Timer tick, asking Buildbucket for the build status
[03:37:39.336] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:39.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:64:0) after 1m0s
[03:38:39.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:64:0)
[03:38:39.399] Timer tick, asking Buildbucket for the build status
[03:38:39.492] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:39.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:65:0) after 1m0s
[03:39:39.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:65:0)
[03:39:39.535] Timer tick, asking Buildbucket for the build status
[03:39:39.685] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:39.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:66:0) after 1m0s
[03:40:39.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:66:0)
[03:40:39.707] Timer tick, asking Buildbucket for the build status
[03:40:39.829] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:39.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:67:0) after 1m0s
[03:41:39.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:67:0)
[03:41:39.927] Timer tick, asking Buildbucket for the build status
[03:41:40.042] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:68:0) after 1m0s
[03:42:40.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:68:0)
[03:42:40.149] Timer tick, asking Buildbucket for the build status
[03:42:40.611] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:40.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:69:0) after 1m0s
[03:43:40.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:69:0)
[03:43:40.786] Timer tick, asking Buildbucket for the build status
[03:43:41.306] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:41.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:70:0) after 1m0s
[03:44:41.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:70:0)
[03:44:41.333] Timer tick, asking Buildbucket for the build status
[03:44:41.436] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:41.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:71:0) after 1m0s
[03:45:41.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:71:0)
[03:45:41.658] Timer tick, asking Buildbucket for the build status
[03:45:41.774] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:41.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:72:0) after 1m0s
[03:46:41.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:72:0)
[03:46:41.878] Timer tick, asking Buildbucket for the build status
[03:46:41.992] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:41.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:73:0) after 1m0s
[03:47:42.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:73:0)
[03:47:42.067] Timer tick, asking Buildbucket for the build status
[03:47:42.404] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:42.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:74:0) after 1m0s
[03:48:42.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:74:0)
[03:48:42.440] Timer tick, asking Buildbucket for the build status
[03:48:42.511] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:42.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:75:0) after 1m0s
[03:49:42.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:75:0)
[03:49:42.535] Timer tick, asking Buildbucket for the build status
[03:49:42.644] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:42.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:76:0) after 1m0s
[03:50:42.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:76:0)
[03:50:42.741] Timer tick, asking Buildbucket for the build status
[03:50:42.823] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:42.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:77:0) after 1m0s
[03:51:42.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:77:0)
[03:51:42.919] Timer tick, asking Buildbucket for the build status
[03:51:43.103] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:43.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:78:0) after 1m0s
[03:52:43.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:78:0)
[03:52:43.142] Timer tick, asking Buildbucket for the build status
[03:52:43.257] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:43.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:79:0) after 1m0s
[03:53:43.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:79:0)
[03:53:43.261] Timer tick, asking Buildbucket for the build status
[03:53:43.500] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:43.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:80:0) after 1m0s
[03:54:43.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:80:0)
[03:54:43.484] Timer tick, asking Buildbucket for the build status
[03:54:43.675] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:43.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:81:0) after 1m0s
[03:55:43.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:81:0)
[03:55:43.753] Timer tick, asking Buildbucket for the build status
[03:55:43.836] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:43.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:82:0) after 1m0s
[03:56:43.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:82:0)
[03:56:43.892] Timer tick, asking Buildbucket for the build status
[03:56:44.131] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:44.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:83:0) after 1m0s
[03:57:44.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:83:0)
[03:57:44.208] Timer tick, asking Buildbucket for the build status
[03:57:44.336] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:44.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:84:0) after 1m0s
[03:58:44.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:84:0)
[03:58:44.396] Timer tick, asking Buildbucket for the build status
[03:58:44.552] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:44.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:85:0) after 1m0s
[03:59:44.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:85:0)
[03:59:44.852] Timer tick, asking Buildbucket for the build status
[03:59:44.979] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:44.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:86:0) after 1m0s
[04:00:45.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:86:0)
[04:00:45.095] Timer tick, asking Buildbucket for the build status
[04:00:45.245] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:45.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:87:0) after 1m0s
[04:01:45.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:87:0)
[04:01:45.494] Timer tick, asking Buildbucket for the build status
[04:01:46.348] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:46.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:88:0) after 1m0s
[04:02:46.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:88:0)
[04:02:46.480] Timer tick, asking Buildbucket for the build status
[04:02:46.928] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:46.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:89:0) after 1m0s
[04:03:46.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:89:0)
[04:03:46.921] Timer tick, asking Buildbucket for the build status
[04:03:47.513] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:47.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:90:0) after 1m0s
[04:04:47.653] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:90:0)
[04:04:47.653] Timer tick, asking Buildbucket for the build status
[04:04:47.968] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:47.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:91:0) after 1m0s
[04:05:48.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:91:0)
[04:05:48.057] Timer tick, asking Buildbucket for the build status
[04:05:48.648] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:48.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:92:0) after 1m0s
[04:06:48.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:92:0)
[04:06:48.640] Timer tick, asking Buildbucket for the build status
[04:06:49.034] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:49.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:93:0) after 1m0s
[04:07:49.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:93:0)
[04:07:49.184] Timer tick, asking Buildbucket for the build status
[04:07:49.293] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:49.293] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:94:0) after 1m0s
[04:08:49.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:94:0)
[04:08:49.388] Timer tick, asking Buildbucket for the build status
[04:08:49.667] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:49.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:95:0) after 1m0s
[04:09:49.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:95:0)
[04:09:49.686] Timer tick, asking Buildbucket for the build status
[04:09:49.813] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:49.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:96:0) after 1m0s
[04:10:49.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:96:0)
[04:10:49.839] Timer tick, asking Buildbucket for the build status
[04:10:50.251] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:50.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:97:0) after 1m0s
[04:11:50.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:97:0)
[04:11:50.287] Timer tick, asking Buildbucket for the build status
[04:11:50.790] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:50.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:98:0) after 1m0s
[04:12:50.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:98:0)
[04:12:50.836] Timer tick, asking Buildbucket for the build status
[04:12:50.944] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:50.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:99:0) after 1m0s
[04:13:50.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:99:0)
[04:13:50.974] Timer tick, asking Buildbucket for the build status
[04:13:51.075] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:51.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:100:0) after 1m0s
[04:14:51.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:100:0)
[04:14:51.115] Timer tick, asking Buildbucket for the build status
[04:14:51.249] Build 8855810304205602176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:51.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021240366323434304:101:0) after 1m0s
[04:15:27.124] Received PubSub notification, asking Buildbucket for the build status
[04:15:27.543] Build 8855810304205602176: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:15:27.543] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612844126220914",
"created_by": "project:chromium",
"created_ts": "1612838184121326",
"failure_reason": "BUILD_FAILURE",
"id": "8855810304205602176",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a306bbd8ee23d3ed62d3ecf6333b475ce363648\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- InteractiveWindowCycleControllerTest.TapSelect\"}, \"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\": \"1a306bbd8ee23d3ed62d3ecf6333b475ce363648\", \"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-77-cvdy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22542, \"got_angle_revision\": \"281c65523a0a57d27caaabe4d1c7209026e12862\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"1a306bbd8ee23d3ed62d3ecf6333b475ce363648\", \"got_revision_cp\": \"refs/heads/master@{#852042}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"9d35d544ce966098fdcb36107835d57a4dc1f32d\", \"got_v8_revision\": \"8b2245e5e701ee15ccd821b4de221baa617fff33\", \"got_v8_revision_cp\": \"refs/heads/9.0.143@{#1}\", \"got_webrtc_revision\": \"54ea85c2cd3a2cb1b8f440a71aa9676a9b88135c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33190}\", \"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\": \"1a306bbd8ee23d3ed62d3ecf6333b475ce363648\", \"swarm_hashes_refs/heads/master(at){#852042}_without_patch\": {\"absl_hardening_tests\": \"721a15259c8331981864c4a1c9e0dbf14e13a0c4\", \"accessibility_unittests\": \"5229842cd8509a37fbcc7c8424aee5a7f3b31078\", \"angle_unittests\": \"0762091518db564376a747722b57b2202509b7db\", \"app_list_unittests\": \"a0d6788f7c270fb98558d84d990a3e27caf720f7\", \"app_shell_unittests\": \"c994dddcb015317f7004b4240ddf4204f27efbcc\", \"ash_unittests\": \"8e555d2c101dd631d11640174703b8fbd3226636\", \"aura_unittests\": \"c1f7881b2ceb536112bb9b5e7b302eddd8f9ac93\", \"base_unittests\": \"96fd443ae7ce10e33846185c8a010d6c5e531fe8\", \"base_util_unittests\": \"45e5e9bf4c019b895f99ae6dce25d8d18db9a62b\", \"blink_common_unittests\": \"8864522a32f9a22b6ac13c318f0bfbf40af86dee\", \"blink_fuzzer_unittests\": \"863f1d3c1e697fea49ab725e5a9a8455306d4f05\", \"blink_heap_unittests\": \"5b3ff8b6dbe7e4969669b25a0212043e34faad86\", \"blink_platform_unittests\": \"a7e070d53d4e6f91c5a4d61bd79e7f25cfc079c7\", \"blink_unittests\": \"08b766e29926e797cb2b1f5d20029ae8e38e03ff\", \"boringssl_crypto_tests\": \"480f916337bab1f2cceb9da1515723f38793e3c3\", \"boringssl_ssl_tests\": \"919afa0aada63c769124012a5e167ce725e5dab4\", \"browser_tests\": \"82fcaa81423022b7987e5c4dc5315543c6e4cdde\", \"capture_unittests\": \"bb84fec49357074fb20b3eb68c2a53aee9a0a024\", \"cast_unittests\": \"8fccdaf3b6e7c101b9381f48858a18155aebc43a\", \"cc_unittests\": \"2bc8505baa04e680038f431398fb55269b3f06ce\", \"chrome_app_unittests\": \"ac41ddf2e9d60b668887bd675656e18752651519\", \"chromedriver_unittests\": \"26b98138b6dd10bb66b0ef7de96f15365a6d4571\", \"chromeos_components_unittests\": \"7001befe52de068a49b678a6f91e38754f1ec846\", \"chromeos_unittests\": \"e6e3eb38f4283500374d191eaf072fb961191c2a\", \"components_browsertests\": \"8ed3ba9eb1577fc280ade768e054b2cd9f6e5a4f\", \"components_unittests\": \"de47d7abca713cdd64232a7c935a076805681b52\", \"compositor_unittests\": \"04860143c5ed4c3b844a8c3fba4cdcf222c22e89\", \"content_browsertests\": \"582d1dde5fa66360af36511c68eb9dc1dbbc78b2\", \"content_unittests\": \"4f4c37b7730150184af7ed24fc302a9d73f3ad8d\", \"crashpad_tests\": \"6c11e83f57d495d5e5704f50e067076b14f43574\", \"crypto_unittests\": \"199545b02b0c6183155379f2fa89b476501ec777\", \"dbus_unittests\": \"fa183491819f3608af7d4cb20c36192f07899c5f\", \"device_unittests\": \"9edbeaf66933394e17ab3ececde56addc260942c\", \"display_unittests\": \"3d56cd01f646a49491062bb8f67b2cc779d4ab7f\", \"events_unittests\": \"c500bd2fda9e69426c86ca9b33e803551d33c385\", \"exo_unittests\": \"04319a84cc6304725b0ada71f3021b6c30f035b7\", \"extensions_browsertests\": \"10286bfa8931b3eb15e9dea112723481cb536425\", \"extensions_unittests\": \"5049708c49241260a1f3be76061be7f27b687173\", \"filesystem_service_unittests\": \"c303b4b78387f5ce204fb263384a8a916446645f\", \"gcm_unit_tests\": \"93c0eda2f38b778b46df72b14fac9b68121837e9\", \"gfx_unittests\": \"86f2bd8a8b35713785bc29564665f7a155fb2471\", \"gin_unittests\": \"9016c613b4b70250f873cbca796e4f21efead9b0\", \"gl_unittests_ozone\": \"c3e5bd850eafd7024f97a16bc5d81c70b9c9af8c\", \"google_apis_unittests\": \"b546c71b6b2982061e0a874de03418ab0f489158\", \"gpu_unittests\": \"19951bdc1cc04288c81443ba420eb7d1ca56868c\", \"gwp_asan_unittests\": \"8109630b08d2854a7f167ea5ec056ac97a0fbbdb\", \"interactive_ui_tests\": \"069390951e2c437db6641172ee30632edfd41157\", \"ipc_tests\": \"c1c07478225bab52c102117f56ee172a7daf4f91\", \"jingle_unittests\": \"dfe4c8e98874b76a06d3260138901f5151a0ba1d\", \"keyboard_unittests\": \"52c3c19abc4918e31551f13a01935f512f2aa85f\", \"latency_unittests\": \"d3437d47a0c2e29395e7da40758eb2fafdf6d04e\", \"libjingle_xmpp_unittests\": \"637f8f89ff016f7fe04966f0deae74ad83e0d379\", \"liburlpattern_unittests\": \"189732d2fe2d4e7e64c43598c14c4b214b98fd68\", \"media_blink_unittests\": \"ed3fcfee06015c4c77e47666759938ff9c23c092\", \"media_unittests\": \"9324f34650adee5d70e03a619d65649926a569d3\", \"message_center_unittests\": \"575c919565a9134d766b5eb473494f38ac4e561c\", \"midi_unittests\": \"355f78e65f4862de2d437cd708255b1791273fce\", \"mojo_core_unittests\": \"6e8669f5aa4fa3762173c56787822a7fb34ff69b\", \"mojo_unittests\": \"f9104db2e16ceab573773d1247b53953b7f63a27\", \"nacl_helper_nonsfi_unittests\": \"159c4a182cb734e0b9185b708a1afdc44548e561\", \"nacl_loader_unittests\": \"0f9d87f5f6ae5e5c0cddd0b89daea20f2b537629\", \"native_theme_unittests\": \"1b495288ab50251dee55f577f88fc7665dd0a6c6\", \"net_unittests\": \"0e09e05b4f7733cd1233a6eabf50334a34c39bda\", \"ozone_gl_unittests\": \"e47d90601c04efed98007789bff22656618e4401\", \"ozone_unittests\": \"db40e9403a5bd1842bdf2fd7dd1f65cbff04500d\", \"ozone_x11_unittests\": \"07d853ede14b8d01f51f0bc6a3bf9161d8b14062\", \"pdf_unittests\": \"199530bbdd15e8a816699f998a8489c3f77f7f41\", \"perfetto_unittests\": \"603ceba33f3880842d2252af93701ff33dcc9494\", \"ppapi_unittests\": \"bd074084532459195bf7e895182b7c347f139f25\", \"printing_unittests\": \"ba61c3268e8f5430ccfefcfa7a07284eefe92900\", \"remoting_unittests\": \"43b17c2ddb96f61589f1d8c1676125b472ad4c6c\", \"sandbox_linux_unittests\": \"c27b557f844a8cf0d2cf256e75af39f21efcdd08\", \"service_manager_unittests\": \"8b86ce052de407d1c7db112edb5a67173ac8ef12\", \"services_unittests\": \"0b23162ac7a2f7dec85d7721249cdfd4ec779176\", \"shell_dialogs_unittests\": \"d7911c0ca871055e00195cc3fb7277de76539eb6\", \"shell_encryption_unittests\": \"49bbf0d2ad3eb5cc174cc7dedf40c923a12ab77a\", \"skia_unittests\": \"d247dcd27a7bc20176a9716ad4830d9172c188bf\", \"snapshot_unittests\": \"068907105a86d0dba5a9297e7b6b39e3ab2ebfc0\", \"sql_unittests\": \"bca9a23804cf0e5cd92f930403cc9a814bda5605\", \"storage_unittests\": \"e61f3d5211702f42001dc13f5f8ec84c450f0806\", \"sync_integration_tests\": \"f48b82b820cb95677812527270cf6a5be50972d5\", \"traffic_annotation_auditor_unittests\": \"dc1ea565ef5bcdec47bf5cb871ba6e7beb723235\", \"ui_base_unittests\": \"a94851ccea8824af53a6889ca683d6bc02bbba1e\", \"ui_chromeos_unittests\": \"20012ec9788f625cd80b1889c700730078107833\", \"ui_touch_selection_unittests\": \"30114311657e332009afbcc45ab9b0582ad72ebe\", \"unit_tests\": \"aedcb91c728bfafe714813b369622d63e3a57bd3\", \"url_unittests\": \"639cd07a6fd2d93555af5139a499cebcfc4789fb\", \"usage_time_limit_unittests\": \"71c49ccb85c45989ee67196b949c30f6c3a46289\", \"views_unittests\": \"6aa0e6ad7126bf0734f23181c5a8bcdae11dae6b\", \"viz_unittests\": \"60a9f58c80af2fb178b0a3bcc43fbfc15964b798\", \"wm_unittests\": \"97fe5bb193562accd6485b6d6620b2c6fa37f336\", \"wtf_unittests\": \"46f4664f908a887b238930bc7aed144ac5bb7e08\", \"zlib_unittests\": \"0b7ae0d53b5ed8b24fd39e553efe16989de4e496\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"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-east1-d-8-77-cvdy\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- InteractiveWindowCycleControllerTest.TapSelect\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612838297005999",
"status": "COMPLETED",
"status_changed_ts": "1612838297338164",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22542",
"builder:linux-chromeos-dbg",
"buildset:commit/git/1a306bbd8ee23d3ed62d3ecf6333b475ce363648",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a306bbd8ee23d3ed62d3ecf6333b475ce363648",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021240366323434304",
"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/8855810304205602176/+/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:519d772f4b04dd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612844126704913",
"url": "https://ci.chromium.org/b/8855810304205602176",
"utcnow_ts": "1612844127517007"
}
}
[04:15:27.543] Invocation finished in 1h39m4.824404291s with status FAILED