[18:45:40.595] New invocation is queued and will start shortly
[18:45:41.656] Starting the invocation (attempt 1)
[18:45:41.699] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:45:41.699] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:45:41.699] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9080701590311822864",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"c62e8ff6f78ae030431846b9d70e2bbbc9913edf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9080701590311822864",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/c62e8ff6f78ae030431846b9d70e2bbbc9913edf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c62e8ff6f78ae030431846b9d70e2bbbc9913edf",
"gitiles_ref:refs/heads/master"
]
}
[18:45:42.489] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1556131541873936",
"id": "8915271528306798128",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c62e8ff6f78ae030431846b9d70e2bbbc9913edf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556131542304103",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12044",
"builder:linux-chromeos-dbg",
"buildset:commit/git/c62e8ff6f78ae030431846b9d70e2bbbc9913edf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c62e8ff6f78ae030431846b9d70e2bbbc9913edf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080701590311822864",
"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/8915271528306798128/+/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": "1556131542304318",
"url": "https://ci.chromium.org/b/8915271528306798128",
"utcnow_ts": "1556131542440344"
}
}
[18:45:42.489] Task URL: https://ci.chromium.org/b/8915271528306798128
[18:45:42.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:2:0) after 1m0s
[18:46:42.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:2:0)
[18:46:42.511] Timer tick, asking Buildbucket for the build status
[18:46:42.905] Build 8915271528306798128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:42.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:3:0) after 1m0s
[18:47:42.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:3:0)
[18:47:42.920] Timer tick, asking Buildbucket for the build status
[18:47:43.276] Build 8915271528306798128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:43.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:4:0) after 1m0s
[18:48:43.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:4:0)
[18:48:43.298] Timer tick, asking Buildbucket for the build status
[18:48:43.448] Build 8915271528306798128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:43.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:5:0) after 1m0s
[18:49:43.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:5:0)
[18:49:43.440] Timer tick, asking Buildbucket for the build status
[18:49:43.641] Build 8915271528306798128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:43.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:6:0) after 1m0s
[18:50:43.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:6:0)
[18:50:43.666] Timer tick, asking Buildbucket for the build status
[18:50:44.542] Build 8915271528306798128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:44.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:7:0) after 1m0s
[18:51:44.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:7:0)
[18:51:44.582] Timer tick, asking Buildbucket for the build status
[18:51:44.698] Build 8915271528306798128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:44.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:8:0) after 1m0s
[18:52:36.176] Received PubSub notification, asking Buildbucket for the build status
[18:52:36.605] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:44.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:8:0)
[18:52:44.717] Timer tick, asking Buildbucket for the build status
[18:52:44.861] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:44.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:10:0) after 1m0s
[18:53:44.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:10:0)
[18:53:44.881] Timer tick, asking Buildbucket for the build status
[18:53:45.010] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:45.010] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:11:0) after 1m0s
[18:54:45.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:11:0)
[18:54:45.019] Timer tick, asking Buildbucket for the build status
[18:54:45.156] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:45.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:12:0) after 1m0s
[18:55:45.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:12:0)
[18:55:45.179] Timer tick, asking Buildbucket for the build status
[18:55:45.326] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:13:0) after 1m0s
[18:56:45.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:13:0)
[18:56:45.317] Timer tick, asking Buildbucket for the build status
[18:56:46.387] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:46.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:14:0) after 1m0s
[18:57:46.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:14:0)
[18:57:46.453] Timer tick, asking Buildbucket for the build status
[18:57:46.875] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:46.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:15:0) after 1m0s
[18:58:46.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:15:0)
[18:58:46.891] Timer tick, asking Buildbucket for the build status
[18:58:47.110] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:47.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:16:0) after 1m0s
[18:59:47.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:16:0)
[18:59:47.153] Timer tick, asking Buildbucket for the build status
[18:59:47.333] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:47.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:17:0) after 1m0s
[19:00:47.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:17:0)
[19:00:47.355] Timer tick, asking Buildbucket for the build status
[19:00:47.499] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:47.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:18:0) after 1m0s
[19:01:47.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:18:0)
[19:01:47.495] Timer tick, asking Buildbucket for the build status
[19:01:47.707] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:47.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:19:0) after 1m0s
[19:02:47.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:19:0)
[19:02:47.683] Timer tick, asking Buildbucket for the build status
[19:02:48.282] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:48.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:20:0) after 1m0s
[19:03:48.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:20:0)
[19:03:48.312] Timer tick, asking Buildbucket for the build status
[19:03:48.408] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:48.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:21:0) after 1m0s
[19:04:48.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:21:0)
[19:04:48.402] Timer tick, asking Buildbucket for the build status
[19:04:48.670] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:48.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:22:0) after 1m0s
[19:05:48.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:22:0)
[19:05:48.654] Timer tick, asking Buildbucket for the build status
[19:05:48.744] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:48.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:23:0) after 1m0s
[19:06:48.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:23:0)
[19:06:48.727] Timer tick, asking Buildbucket for the build status
[19:06:49.058] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:49.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:24:0) after 1m0s
[19:07:49.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:24:0)
[19:07:49.078] Timer tick, asking Buildbucket for the build status
[19:07:49.497] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:25:0) after 1m0s
[19:08:49.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:25:0)
[19:08:49.538] Timer tick, asking Buildbucket for the build status
[19:08:49.667] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:49.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:26:0) after 1m0s
[19:09:49.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:26:0)
[19:09:49.672] Timer tick, asking Buildbucket for the build status
[19:09:50.015] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:50.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:27:0) after 1m0s
[19:10:50.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:27:0)
[19:10:50.010] Timer tick, asking Buildbucket for the build status
[19:10:50.336] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:50.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:28:0) after 1m0s
[19:11:50.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:28:0)
[19:11:50.621] Timer tick, asking Buildbucket for the build status
[19:11:50.718] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:50.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:29:0) after 1m0s
[19:12:50.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:29:0)
[19:12:50.774] Timer tick, asking Buildbucket for the build status
[19:12:50.958] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:50.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:30:0) after 1m0s
[19:13:50.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:30:0)
[19:13:50.982] Timer tick, asking Buildbucket for the build status
[19:13:51.416] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:51.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:31:0) after 1m0s
[19:14:51.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:31:0)
[19:14:51.442] Timer tick, asking Buildbucket for the build status
[19:14:51.549] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:51.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:32:0) after 1m0s
[19:15:51.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:32:0)
[19:15:51.543] Timer tick, asking Buildbucket for the build status
[19:15:51.649] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:51.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:33:0) after 1m0s
[19:16:51.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:33:0)
[19:16:51.800] Timer tick, asking Buildbucket for the build status
[19:16:51.917] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:51.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:34:0) after 1m0s
[19:17:51.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:34:0)
[19:17:51.924] Timer tick, asking Buildbucket for the build status
[19:17:52.181] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:52.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:35:0) after 1m0s
[19:18:52.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:35:0)
[19:18:52.211] Timer tick, asking Buildbucket for the build status
[19:18:52.747] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:52.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:36:0) after 1m0s
[19:19:52.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:36:0)
[19:19:52.770] Timer tick, asking Buildbucket for the build status
[19:19:53.597] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:53.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:37:0) after 1m0s
[19:20:53.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:37:0)
[19:20:53.611] Timer tick, asking Buildbucket for the build status
[19:20:53.866] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:53.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:38:0) after 1m0s
[19:21:54.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:38:0)
[19:21:54.274] Timer tick, asking Buildbucket for the build status
[19:21:54.385] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:54.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:39:0) after 1m0s
[19:22:54.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:39:0)
[19:22:54.365] Timer tick, asking Buildbucket for the build status
[19:22:54.523] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:54.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:40:0) after 1m0s
[19:23:54.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:40:0)
[19:23:54.523] Timer tick, asking Buildbucket for the build status
[19:23:54.634] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:54.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:41:0) after 1m0s
[19:24:54.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:41:0)
[19:24:54.657] Timer tick, asking Buildbucket for the build status
[19:24:54.995] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:54.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:42:0) after 1m0s
[19:25:55.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:42:0)
[19:25:55.027] Timer tick, asking Buildbucket for the build status
[19:25:55.272] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:55.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:43:0) after 1m0s
[19:26:55.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:43:0)
[19:26:55.297] Timer tick, asking Buildbucket for the build status
[19:26:55.392] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:55.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:44:0) after 1m0s
[19:27:55.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:44:0)
[19:27:55.366] Timer tick, asking Buildbucket for the build status
[19:27:55.914] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:55.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:45:0) after 1m0s
[19:28:55.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:45:0)
[19:28:55.914] Timer tick, asking Buildbucket for the build status
[19:29:01.223] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:01.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:46:0) after 1m0s
[19:30:01.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:46:0)
[19:30:01.249] Timer tick, asking Buildbucket for the build status
[19:30:01.673] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:01.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:47:0) after 1m0s
[19:31:01.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:47:0)
[19:31:01.661] Timer tick, asking Buildbucket for the build status
[19:31:03.067] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:03.067] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:48:0) after 1m0s
[19:32:03.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:48:0)
[19:32:03.085] Timer tick, asking Buildbucket for the build status
[19:32:03.299] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:03.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:49:0) after 1m0s
[19:33:03.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:49:0)
[19:33:03.322] Timer tick, asking Buildbucket for the build status
[19:33:03.463] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:03.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:50:0) after 1m0s
[19:34:03.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:50:0)
[19:34:03.496] Timer tick, asking Buildbucket for the build status
[19:34:03.678] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:03.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:51:0) after 1m0s
[19:35:03.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:51:0)
[19:35:03.721] Timer tick, asking Buildbucket for the build status
[19:35:05.172] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:05.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:52:0) after 1m0s
[19:36:05.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:52:0)
[19:36:05.177] Timer tick, asking Buildbucket for the build status
[19:36:05.347] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:05.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:53:0) after 1m0s
[19:37:05.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:53:0)
[19:37:05.342] Timer tick, asking Buildbucket for the build status
[19:37:05.494] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:05.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:54:0) after 1m0s
[19:38:05.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:54:0)
[19:38:05.469] Timer tick, asking Buildbucket for the build status
[19:38:05.890] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:05.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:55:0) after 1m0s
[19:39:05.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:55:0)
[19:39:05.874] Timer tick, asking Buildbucket for the build status
[19:39:06.098] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:06.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:56:0) after 1m0s
[19:40:06.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:56:0)
[19:40:06.122] Timer tick, asking Buildbucket for the build status
[19:40:06.675] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:06.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:57:0) after 1m0s
[19:41:06.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:57:0)
[19:41:06.759] Timer tick, asking Buildbucket for the build status
[19:41:06.990] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:06.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:58:0) after 1m0s
[19:42:07.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:58:0)
[19:42:07.008] Timer tick, asking Buildbucket for the build status
[19:42:07.666] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:07.666] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:59:0) after 1m0s
[19:43:07.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:59:0)
[19:43:07.685] Timer tick, asking Buildbucket for the build status
[19:43:08.193] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:08.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:60:0) after 1m0s
[19:44:08.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:60:0)
[19:44:08.233] Timer tick, asking Buildbucket for the build status
[19:44:08.649] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:08.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:61:0) after 1m0s
[19:45:08.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:61:0)
[19:45:08.642] Timer tick, asking Buildbucket for the build status
[19:45:08.884] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:08.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:62:0) after 1m0s
[19:46:08.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:62:0)
[19:46:08.906] Timer tick, asking Buildbucket for the build status
[19:46:09.262] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:09.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:63:0) after 1m0s
[19:47:09.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:63:0)
[19:47:09.290] Timer tick, asking Buildbucket for the build status
[19:47:09.676] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:09.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:64:0) after 1m0s
[19:48:09.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:64:0)
[19:48:09.701] Timer tick, asking Buildbucket for the build status
[19:48:10.289] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:10.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:65:0) after 1m0s
[19:49:10.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:65:0)
[19:49:10.295] Timer tick, asking Buildbucket for the build status
[19:49:10.864] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:10.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:66:0) after 1m0s
[19:50:10.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:66:0)
[19:50:10.870] Timer tick, asking Buildbucket for the build status
[19:50:10.966] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:10.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:67:0) after 1m0s
[19:51:10.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:67:0)
[19:51:10.974] Timer tick, asking Buildbucket for the build status
[19:51:11.219] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:11.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:68:0) after 1m0s
[19:52:11.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:68:0)
[19:52:11.256] Timer tick, asking Buildbucket for the build status
[19:52:11.408] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:11.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:69:0) after 1m0s
[19:53:11.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:69:0)
[19:53:11.435] Timer tick, asking Buildbucket for the build status
[19:53:11.596] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:11.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:70:0) after 1m0s
[19:54:11.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:70:0)
[19:54:11.652] Timer tick, asking Buildbucket for the build status
[19:54:11.780] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:11.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:71:0) after 1m0s
[19:55:11.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:71:0)
[19:55:11.781] Timer tick, asking Buildbucket for the build status
[19:55:12.189] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:12.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:72:0) after 1m0s
[19:56:12.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:72:0)
[19:56:12.208] Timer tick, asking Buildbucket for the build status
[19:56:12.749] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:12.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:73:0) after 1m0s
[19:57:12.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:73:0)
[19:57:12.745] Timer tick, asking Buildbucket for the build status
[19:57:12.899] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:12.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:74:0) after 1m0s
[19:58:12.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:74:0)
[19:58:12.892] Timer tick, asking Buildbucket for the build status
[19:58:13.825] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:13.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:75:0) after 1m0s
[19:59:13.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:75:0)
[19:59:13.858] Timer tick, asking Buildbucket for the build status
[19:59:13.997] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:13.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:76:0) after 1m0s
[20:00:14.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:76:0)
[20:00:14.022] Timer tick, asking Buildbucket for the build status
[20:00:14.327] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:14.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:77:0) after 1m0s
[20:01:14.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:77:0)
[20:01:14.425] Timer tick, asking Buildbucket for the build status
[20:01:14.696] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:14.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:78:0) after 1m0s
[20:02:14.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:78:0)
[20:02:14.684] Timer tick, asking Buildbucket for the build status
[20:02:15.063] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:15.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:79:0) after 1m0s
[20:03:15.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:79:0)
[20:03:15.100] Timer tick, asking Buildbucket for the build status
[20:03:15.270] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:15.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:80:0) after 1m0s
[20:04:15.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:80:0)
[20:04:15.280] Timer tick, asking Buildbucket for the build status
[20:04:15.438] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:15.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:81:0) after 1m0s
[20:05:15.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:81:0)
[20:05:15.465] Timer tick, asking Buildbucket for the build status
[20:05:15.611] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:15.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:82:0) after 1m0s
[20:06:15.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:82:0)
[20:06:15.635] Timer tick, asking Buildbucket for the build status
[20:06:15.789] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:15.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:83:0) after 1m0s
[20:07:15.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:83:0)
[20:07:15.812] Timer tick, asking Buildbucket for the build status
[20:07:16.868] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:16.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:84:0) after 1m0s
[20:08:16.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:84:0)
[20:08:16.923] Timer tick, asking Buildbucket for the build status
[20:08:17.284] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:17.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:85:0) after 1m0s
[20:09:17.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:85:0)
[20:09:17.331] Timer tick, asking Buildbucket for the build status
[20:09:17.557] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:17.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:86:0) after 1m0s
[20:10:17.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:86:0)
[20:10:17.553] Timer tick, asking Buildbucket for the build status
[20:10:17.685] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:17.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:87:0) after 1m0s
[20:11:17.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:87:0)
[20:11:17.707] Timer tick, asking Buildbucket for the build status
[20:11:17.879] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:17.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:88:0) after 1m0s
[20:12:17.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:88:0)
[20:12:17.908] Timer tick, asking Buildbucket for the build status
[20:12:18.238] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:18.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:89:0) after 1m0s
[20:13:18.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:89:0)
[20:13:18.281] Timer tick, asking Buildbucket for the build status
[20:13:18.531] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:18.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:90:0) after 1m0s
[20:14:18.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:90:0)
[20:14:18.554] Timer tick, asking Buildbucket for the build status
[20:14:19.084] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:19.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:91:0) after 1m0s
[20:15:19.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:91:0)
[20:15:19.082] Timer tick, asking Buildbucket for the build status
[20:15:19.748] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:19.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:92:0) after 1m0s
[20:16:19.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:92:0)
[20:16:19.792] Timer tick, asking Buildbucket for the build status
[20:16:19.908] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:19.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:93:0) after 1m0s
[20:17:19.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:93:0)
[20:17:19.931] Timer tick, asking Buildbucket for the build status
[20:17:20.393] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:20.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:94:0) after 1m0s
[20:18:20.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:94:0)
[20:18:20.418] Timer tick, asking Buildbucket for the build status
[20:18:20.703] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:20.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:95:0) after 1m0s
[20:19:20.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:95:0)
[20:19:20.770] Timer tick, asking Buildbucket for the build status
[20:19:21.396] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:21.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:96:0) after 1m0s
[20:20:21.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:96:0)
[20:20:21.431] Timer tick, asking Buildbucket for the build status
[20:20:21.889] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:21.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:97:0) after 1m0s
[20:21:21.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:97:0)
[20:21:21.897] Timer tick, asking Buildbucket for the build status
[20:21:22.024] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:22.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:98:0) after 1m0s
[20:22:22.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:98:0)
[20:22:22.059] Timer tick, asking Buildbucket for the build status
[20:22:22.306] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:22.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:99:0) after 1m0s
[20:23:22.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:99:0)
[20:23:22.333] Timer tick, asking Buildbucket for the build status
[20:23:22.590] Build 8915271528306798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:22.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080701590311822864:100:0) after 1m0s
[20:24:22.425] Received PubSub notification, asking Buildbucket for the build status
[20:24:22.570] Build 8915271528306798128: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:24:22.570] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1556137461172797",
"created_by": "project:chromium",
"created_ts": "1556131541873936",
"failure_reason": "BUILD_FAILURE",
"id": "8915271528306798128",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c62e8ff6f78ae030431846b9d70e2bbbc9913edf\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['ash_unittests', 'non_single_process_mash_ash_unittests']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 12044, \"got_angle_revision\": \"c9a9cfcca1763743d89e0954c7710e77f339bfd2\", \"got_dawn_revision\": \"22cee9ae36b23a8908525d668ef4728a26c931c4\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"c62e8ff6f78ae030431846b9d70e2bbbc9913edf\", \"got_revision_cp\": \"refs/heads/master@{#653683}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"b80da604362f132504ddab67085232980e29aa02\", \"got_v8_revision_cp\": \"refs/heads/7.6.21@{#1}\", \"got_webrtc_revision\": \"852bffb6063aa36814c89a58390581e59643f1fb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27678}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c62e8ff6f78ae030431846b9d70e2bbbc9913edf\", \"swarm_hashes\": {\"accessibility_unittests\": \"2ecd4fe8b4fa9d3f45b10b1057bf549390daa3ed\", \"angle_unittests\": \"f65970527eeb42b992d92adc8cdeb3fa0af53eed\", \"app_list_unittests\": \"37ec5604b31cfd07be341381babde97d9d8477d4\", \"app_shell_unittests\": \"7e5fc6414bf8811ad426a9d7b34e084d97300fd7\", \"ash_unittests\": \"f5e8d8fa7e6cb563669c81000e2e706f5b24e31c\", \"aura_unittests\": \"b1e9948a25f66771c6527777fa4af22a28774689\", \"base_unittests\": \"e3a6ed910d063f5761d724a89f214141e42132b7\", \"blink_common_unittests\": \"7a8cf4ec74f8a19c34e48fa88da00534671d3a8a\", \"blink_fuzzer_unittests\": \"d35ca36bf30f48098e44376c8bb0ee0362093d41\", \"blink_heap_unittests\": \"75e960c49f48dd14ac135179959eca966b1c0506\", \"blink_platform_unittests\": \"8c5d3b8633ece02ed2ee68c2e6ae5f64e104f687\", \"blink_unittests\": \"f3ab6d7ee7eace03625477ab81bfdd4b1b2e5896\", \"boringssl_crypto_tests\": \"804fa5a176f00ca6efcd65a667f8fd7d72daa674\", \"boringssl_ssl_tests\": \"f4420aff232b33b46921caccbb325013c7d31b20\", \"browser_tests\": \"1f65e65dd7e7481e2377f301bae6dfde8a5a2458\", \"cacheinvalidation_unittests\": \"b3bb70166a71c7d2459aa0a92b3f078e8d97e621\", \"capture_unittests\": \"dedd49d86a62bbed70ba00dcf61d64694eb7bcf0\", \"cast_unittests\": \"7290e7f6af726f0b0339b5a1f3dcb037005fb658\", \"cc_unittests\": \"ebe9d25814309c991201d588b7c2a45087c8fd54\", \"chrome_app_unittests\": \"eab324249f38fde141354ce4ba0ffce425cf69dd\", \"chromedriver_unittests\": \"fbce78eaf3d2c53ffd5638023babe73483429387\", \"chromeos_components_unittests\": \"ae7ab63287ef52b13f1686a48b927edbc2d920af\", \"chromeos_unittests\": \"7e70c0fd191686c59c579d027ffcdbc345501b0f\", \"components_browsertests\": \"ec5a7d60ce4c82a214507d0570dcae8bcefaf744\", \"components_unittests\": \"8c0d209c6425fa26d4762cb53db4889239aed9c7\", \"compositor_unittests\": \"6a6672b22b583296132a12fd5e023c0906bfbe77\", \"content_browsertests\": \"619e515dfd7fbe1226b3b9f277829742d5cf48e5\", \"content_unittests\": \"395bd704f34e1bb14c5f869d2dc34a411a1f0735\", \"crypto_unittests\": \"11ae3175c8db2def34d8ee6196484c425f42067c\", \"dbus_unittests\": \"5a62133d2c5cfcc9ce9f1ca5afe283d38e14166d\", \"device_unittests\": \"41e1f6ca2caf2bd8be7bf6a6f000500415c4d495\", \"display_unittests\": \"7e7f0bf9ded31a94581f0a7f1e21840a1c08a409\", \"events_unittests\": \"21360a65d169a97961913fd55209bf09f0ea4b42\", \"exo_unittests\": \"022fed30282531e01b0de0dc75b73dbe9265f68d\", \"extensions_browsertests\": \"a479b55c26ef86c95d9152ec993165566553a795\", \"extensions_unittests\": \"85aa78e1af2d34cae852695beab9b60e88836b41\", \"filesystem_service_unittests\": \"e37b48625bea0538d3dd027bd8184a5ef05dc74c\", \"gcm_unit_tests\": \"09b1127c2829a929a9cbff561255b24d0c1067c4\", \"gfx_unittests\": \"3cb2fe488c3fad3ee69d14c4ed198ad6fe5b2b5d\", \"gin_unittests\": \"3a9f46b785c73a0d3c9fb2007a560ad689dd08e0\", \"gl_unittests_ozone\": \"fd239e4df7ad6b225ec8ce6449a58e8c5865a424\", \"google_apis_unittests\": \"6dce7b8d7a3f09ba56422e5ab471c5889f49761b\", \"gpu_unittests\": \"1a595998ab0e0038f674e8db60b84d6b3c8cd987\", \"interactive_ui_tests\": \"0270305da3ebca569185d7a3e4c0019cca7bf1dd\", \"ipc_tests\": \"b5dbe5746cf2d5cca9942c44856d5f319c637dff\", \"jingle_unittests\": \"91e607ec334a8160df2b12217ff8c1b376db7ed7\", \"keyboard_unittests\": \"e1f43aa4975dc95ac707300729948bc4cbe68fa7\", \"latency_unittests\": \"0754027f72957b6a93922ebd4128ca214136d2a8\", \"leveldb_service_unittests\": \"2300faadb745c3bb2775fc63318c92c14e4cb67f\", \"libjingle_xmpp_unittests\": \"78a70acf04dd6826334d3e6d3f7fc7c1ac845387\", \"media_blink_unittests\": \"c50b9f88f474f9b9c40400f607edb209be916272\", \"media_service_unittests\": \"f1e148f5156bf5aa78130b5112b144bd17b5ffa4\", \"media_unittests\": \"2a057c56f22a89e91b8d5d67c5f50b799ea00455\", \"message_center_unittests\": \"a2fd4c1c630e152831ed88630f1eca7786096a7e\", \"midi_unittests\": \"09ca34b78d720d9904d3e7a26277527339b48c8a\", \"mojo_core_unittests\": \"40ecaf339931a3528a1b449b0d01a79ef35b783c\", \"mojo_unittests\": \"56a64d9325ea52cc71f1d2729562d9968407dd3c\", \"nacl_helper_nonsfi_unittests\": \"4fa9a9b6ef551ea55ff1b7d7056c224768526d85\", \"nacl_loader_unittests\": \"f5f1044da60d806be390c097788f2327ae1720a4\", \"native_theme_unittests\": \"c5abe90f7526a7faccd6aaa743f45c1ce1e16a0c\", \"net_unittests\": \"b35fff3de1b60c955749ff2fea7b32e601511826\", \"ozone_gl_unittests\": \"d8e84a8ece44bb201a9123c05574bacb0c8d4b67\", \"ozone_unittests\": \"5cbc02de2347d68ef34d5a2257eb1e13bce75203\", \"ozone_x11_unittests\": \"5d592b72339143a95647758c92af9e9e200ba311\", \"pdf_unittests\": \"cbe397b50491c49fa3fb723bb8137d5bb41beac3\", \"perfetto_unittests\": \"7f2c2e7a55fb109f46028171c5b67df4dfd5339f\", \"ppapi_unittests\": \"f9feaa824d282affd4a3948637f9f365163343bf\", \"printing_unittests\": \"cacb668369253344b2204fb5a1eeb345e84c47c1\", \"remoting_unittests\": \"24798ba5789f96ba6d542647fc12369911a2cee2\", \"sandbox_linux_unittests\": \"08e1e4629ebc38444ce6e42e0d52b61b2e1d0f8f\", \"service_manager_unittests\": \"465134508da3273f86d6cbd6d11a3459c8bc464a\", \"services_unittests\": \"e51e4476469682a5a19baf8107e9de5ebba5d976\", \"shell_dialogs_unittests\": \"44c627fc2e0b40dd75399a9fb8a2f985e20b81ef\", \"skia_unittests\": \"b8aff417927d1bba7b28466d7e36fba278b0c083\", \"snapshot_unittests\": \"ec0cb0d55bb9cd40aba679392bfa245f25261944\", \"sql_unittests\": \"3e4b35faa060304cf874a4a01b089d963e243d71\", \"storage_unittests\": \"c9d2845554b72e1c63a96c082b3a53f0b2bdb781\", \"sync_integration_tests\": \"b8a0e2104ec9cf06dd98b68707d008be1f8a13dc\", \"traffic_annotation_auditor_unittests\": \"17625505c52c255398e192e53f8148ec1e252ac9\", \"ui_base_unittests\": \"b7a72739d89b4dda5d9449188cd19bfe9aef2699\", \"ui_chromeos_unittests\": \"14612cc7d0ce358c7ba7bc01c65307ab062fad86\", \"ui_touch_selection_unittests\": \"081bd3f9eaf275b178abcc64e7a9fda184b51093\", \"unit_tests\": \"3660bc1c784fd7a0719890fd968071fcb2c8e703\", \"url_unittests\": \"bbac221c18eb90ef45fdac9aad6af8a3fa83998c\", \"usage_time_limit_unittests\": \"e799d46850d99b764e075e6934fd27d6d7f1704d\", \"views_unittests\": \"d145d2346eb2ef40b738790e09ce79c177ccd91c\", \"viz_unittests\": \"4747177f59408bb2995778df272ef6d6d2dfd7b6\", \"wm_unittests\": \"e77a05ccfc39c266c828a2e825bcfc8cdde9eab2\", \"wtf_unittests\": \"1991e2fe1a873f59b5159bff93e4fffb70c1609d\"}, \"swarm_hashes_refs/heads/master(at){#653683}_without_patch\": {\"accessibility_unittests\": \"2ecd4fe8b4fa9d3f45b10b1057bf549390daa3ed\", \"angle_unittests\": \"f65970527eeb42b992d92adc8cdeb3fa0af53eed\", \"app_list_unittests\": \"37ec5604b31cfd07be341381babde97d9d8477d4\", \"app_shell_unittests\": \"7e5fc6414bf8811ad426a9d7b34e084d97300fd7\", \"ash_unittests\": \"f5e8d8fa7e6cb563669c81000e2e706f5b24e31c\", \"aura_unittests\": \"b1e9948a25f66771c6527777fa4af22a28774689\", \"base_unittests\": \"e3a6ed910d063f5761d724a89f214141e42132b7\", \"blink_common_unittests\": \"7a8cf4ec74f8a19c34e48fa88da00534671d3a8a\", \"blink_fuzzer_unittests\": \"d35ca36bf30f48098e44376c8bb0ee0362093d41\", \"blink_heap_unittests\": \"75e960c49f48dd14ac135179959eca966b1c0506\", \"blink_platform_unittests\": \"8c5d3b8633ece02ed2ee68c2e6ae5f64e104f687\", \"blink_unittests\": \"f3ab6d7ee7eace03625477ab81bfdd4b1b2e5896\", \"boringssl_crypto_tests\": \"804fa5a176f00ca6efcd65a667f8fd7d72daa674\", \"boringssl_ssl_tests\": \"f4420aff232b33b46921caccbb325013c7d31b20\", \"browser_tests\": \"1f65e65dd7e7481e2377f301bae6dfde8a5a2458\", \"cacheinvalidation_unittests\": \"b3bb70166a71c7d2459aa0a92b3f078e8d97e621\", \"capture_unittests\": \"dedd49d86a62bbed70ba00dcf61d64694eb7bcf0\", \"cast_unittests\": \"7290e7f6af726f0b0339b5a1f3dcb037005fb658\", \"cc_unittests\": \"ebe9d25814309c991201d588b7c2a45087c8fd54\", \"chrome_app_unittests\": \"eab324249f38fde141354ce4ba0ffce425cf69dd\", \"chromedriver_unittests\": \"fbce78eaf3d2c53ffd5638023babe73483429387\", \"chromeos_components_unittests\": \"ae7ab63287ef52b13f1686a48b927edbc2d920af\", \"chromeos_unittests\": \"7e70c0fd191686c59c579d027ffcdbc345501b0f\", \"components_browsertests\": \"ec5a7d60ce4c82a214507d0570dcae8bcefaf744\", \"components_unittests\": \"8c0d209c6425fa26d4762cb53db4889239aed9c7\", \"compositor_unittests\": \"6a6672b22b583296132a12fd5e023c0906bfbe77\", \"content_browsertests\": \"619e515dfd7fbe1226b3b9f277829742d5cf48e5\", \"content_unittests\": \"395bd704f34e1bb14c5f869d2dc34a411a1f0735\", \"crypto_unittests\": \"11ae3175c8db2def34d8ee6196484c425f42067c\", \"dbus_unittests\": \"5a62133d2c5cfcc9ce9f1ca5afe283d38e14166d\", \"device_unittests\": \"41e1f6ca2caf2bd8be7bf6a6f000500415c4d495\", \"display_unittests\": \"7e7f0bf9ded31a94581f0a7f1e21840a1c08a409\", \"events_unittests\": \"21360a65d169a97961913fd55209bf09f0ea4b42\", \"exo_unittests\": \"022fed30282531e01b0de0dc75b73dbe9265f68d\", \"extensions_browsertests\": \"a479b55c26ef86c95d9152ec993165566553a795\", \"extensions_unittests\": \"85aa78e1af2d34cae852695beab9b60e88836b41\", \"filesystem_service_unittests\": \"e37b48625bea0538d3dd027bd8184a5ef05dc74c\", \"gcm_unit_tests\": \"09b1127c2829a929a9cbff561255b24d0c1067c4\", \"gfx_unittests\": \"3cb2fe488c3fad3ee69d14c4ed198ad6fe5b2b5d\", \"gin_unittests\": \"3a9f46b785c73a0d3c9fb2007a560ad689dd08e0\", \"gl_unittests_ozone\": \"fd239e4df7ad6b225ec8ce6449a58e8c5865a424\", \"google_apis_unittests\": \"6dce7b8d7a3f09ba56422e5ab471c5889f49761b\", \"gpu_unittests\": \"1a595998ab0e0038f674e8db60b84d6b3c8cd987\", \"interactive_ui_tests\": \"0270305da3ebca569185d7a3e4c0019cca7bf1dd\", \"ipc_tests\": \"b5dbe5746cf2d5cca9942c44856d5f319c637dff\", \"jingle_unittests\": \"91e607ec334a8160df2b12217ff8c1b376db7ed7\", \"keyboard_unittests\": \"e1f43aa4975dc95ac707300729948bc4cbe68fa7\", \"latency_unittests\": \"0754027f72957b6a93922ebd4128ca214136d2a8\", \"leveldb_service_unittests\": \"2300faadb745c3bb2775fc63318c92c14e4cb67f\", \"libjingle_xmpp_unittests\": \"78a70acf04dd6826334d3e6d3f7fc7c1ac845387\", \"media_blink_unittests\": \"c50b9f88f474f9b9c40400f607edb209be916272\", \"media_service_unittests\": \"f1e148f5156bf5aa78130b5112b144bd17b5ffa4\", \"media_unittests\": \"2a057c56f22a89e91b8d5d67c5f50b799ea00455\", \"message_center_unittests\": \"a2fd4c1c630e152831ed88630f1eca7786096a7e\", \"midi_unittests\": \"09ca34b78d720d9904d3e7a26277527339b48c8a\", \"mojo_core_unittests\": \"40ecaf339931a3528a1b449b0d01a79ef35b783c\", \"mojo_unittests\": \"56a64d9325ea52cc71f1d2729562d9968407dd3c\", \"nacl_helper_nonsfi_unittests\": \"4fa9a9b6ef551ea55ff1b7d7056c224768526d85\", \"nacl_loader_unittests\": \"f5f1044da60d806be390c097788f2327ae1720a4\", \"native_theme_unittests\": \"c5abe90f7526a7faccd6aaa743f45c1ce1e16a0c\", \"net_unittests\": \"b35fff3de1b60c955749ff2fea7b32e601511826\", \"ozone_gl_unittests\": \"d8e84a8ece44bb201a9123c05574bacb0c8d4b67\", \"ozone_unittests\": \"5cbc02de2347d68ef34d5a2257eb1e13bce75203\", \"ozone_x11_unittests\": \"5d592b72339143a95647758c92af9e9e200ba311\", \"pdf_unittests\": \"cbe397b50491c49fa3fb723bb8137d5bb41beac3\", \"perfetto_unittests\": \"7f2c2e7a55fb109f46028171c5b67df4dfd5339f\", \"ppapi_unittests\": \"f9feaa824d282affd4a3948637f9f365163343bf\", \"printing_unittests\": \"cacb668369253344b2204fb5a1eeb345e84c47c1\", \"remoting_unittests\": \"24798ba5789f96ba6d542647fc12369911a2cee2\", \"sandbox_linux_unittests\": \"08e1e4629ebc38444ce6e42e0d52b61b2e1d0f8f\", \"service_manager_unittests\": \"465134508da3273f86d6cbd6d11a3459c8bc464a\", \"services_unittests\": \"e51e4476469682a5a19baf8107e9de5ebba5d976\", \"shell_dialogs_unittests\": \"44c627fc2e0b40dd75399a9fb8a2f985e20b81ef\", \"skia_unittests\": \"b8aff417927d1bba7b28466d7e36fba278b0c083\", \"snapshot_unittests\": \"ec0cb0d55bb9cd40aba679392bfa245f25261944\", \"sql_unittests\": \"3e4b35faa060304cf874a4a01b089d963e243d71\", \"storage_unittests\": \"c9d2845554b72e1c63a96c082b3a53f0b2bdb781\", \"sync_integration_tests\": \"b8a0e2104ec9cf06dd98b68707d008be1f8a13dc\", \"traffic_annotation_auditor_unittests\": \"17625505c52c255398e192e53f8148ec1e252ac9\", \"ui_base_unittests\": \"b7a72739d89b4dda5d9449188cd19bfe9aef2699\", \"ui_chromeos_unittests\": \"14612cc7d0ce358c7ba7bc01c65307ab062fad86\", \"ui_touch_selection_unittests\": \"081bd3f9eaf275b178abcc64e7a9fda184b51093\", \"unit_tests\": \"3660bc1c784fd7a0719890fd968071fcb2c8e703\", \"url_unittests\": \"bbac221c18eb90ef45fdac9aad6af8a3fa83998c\", \"usage_time_limit_unittests\": \"e799d46850d99b764e075e6934fd27d6d7f1704d\", \"views_unittests\": \"d145d2346eb2ef40b738790e09ce79c177ccd91c\", \"viz_unittests\": \"4747177f59408bb2995778df272ef6d6d2dfd7b6\", \"wm_unittests\": \"e77a05ccfc39c266c828a2e825bcfc8cdde9eab2\", \"wtf_unittests\": \"1991e2fe1a873f59b5159bff93e4fffb70c1609d\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4274-a0a5f8c\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 tests failed: ['ash_unittests', 'non_single_process_mash_ash_unittests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1556131955555783",
"status": "COMPLETED",
"status_changed_ts": "1556131955866555",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12044",
"builder:linux-chromeos-dbg",
"buildset:commit/git/c62e8ff6f78ae030431846b9d70e2bbbc9913edf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c62e8ff6f78ae030431846b9d70e2bbbc9913edf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080701590311822864",
"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/8915271528306798128/+/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:44697c7e179fa910",
"user_agent:luci-scheduler"
],
"updated_ts": "1556137461886843",
"url": "https://ci.chromium.org/b/8915271528306798128",
"utcnow_ts": "1556137462551931"
}
}
[20:24:22.570] Invocation finished in 1h38m41.991436545s with status FAILED