[03:55:02.296] New invocation is queued and will start shortly
[03:55:03.367] Starting the invocation (attempt 1)
[03:55:03.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:55:03.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:55:03.406] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083203742522614224",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"eb8d3bc0b60753501c920029eb894f3b3b5dd7e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083203742522614224",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/eb8d3bc0b60753501c920029eb894f3b3b5dd7e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb8d3bc0b60753501c920029eb894f3b3b5dd7e5",
"gitiles_ref:refs/heads/master"
]
}
[03:55:04.211] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553745303520993",
"id": "8917773680574572864",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"eb8d3bc0b60753501c920029eb894f3b3b5dd7e5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553745304078663",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11577",
"builder:linux-chromeos-dbg",
"buildset:commit/git/eb8d3bc0b60753501c920029eb894f3b3b5dd7e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb8d3bc0b60753501c920029eb894f3b3b5dd7e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083203742522614224",
"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/8917773680574572864/+/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": "1553745304078924",
"url": "https://ci.chromium.org/b/8917773680574572864",
"utcnow_ts": "1553745304199934"
}
}
[03:55:04.211] Task URL: https://ci.chromium.org/b/8917773680574572864
[03:55:04.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:2:0) after 1m0s
[03:56:04.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:2:0)
[03:56:04.230] Timer tick, asking Buildbucket for the build status
[03:56:04.356] Build 8917773680574572864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:04.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:3:0) after 1m0s
[03:57:04.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:3:0)
[03:57:04.379] Timer tick, asking Buildbucket for the build status
[03:57:04.478] Build 8917773680574572864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:04.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:4:0) after 1m0s
[03:58:04.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:4:0)
[03:58:04.495] Timer tick, asking Buildbucket for the build status
[03:58:04.870] Build 8917773680574572864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:04.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:5:0) after 1m0s
[03:59:04.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:5:0)
[03:59:04.912] Timer tick, asking Buildbucket for the build status
[03:59:05.040] Build 8917773680574572864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:05.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:6:0) after 1m0s
[03:59:20.244] Received PubSub notification, asking Buildbucket for the build status
[03:59:20.815] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:05.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:6:0)
[04:00:05.061] Timer tick, asking Buildbucket for the build status
[04:00:05.209] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:05.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:8:0) after 1m0s
[04:01:05.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:8:0)
[04:01:05.215] Timer tick, asking Buildbucket for the build status
[04:01:05.503] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:05.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:9:0) after 1m0s
[04:02:05.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:9:0)
[04:02:05.523] Timer tick, asking Buildbucket for the build status
[04:02:05.630] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:05.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:10:0) after 1m0s
[04:03:05.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:10:0)
[04:03:05.643] Timer tick, asking Buildbucket for the build status
[04:03:06.033] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:06.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:11:0) after 1m0s
[04:04:06.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:11:0)
[04:04:06.039] Timer tick, asking Buildbucket for the build status
[04:04:06.171] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:06.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:12:0) after 1m0s
[04:05:06.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:12:0)
[04:05:06.184] Timer tick, asking Buildbucket for the build status
[04:05:06.317] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:06.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:13:0) after 1m0s
[04:06:06.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:13:0)
[04:06:06.335] Timer tick, asking Buildbucket for the build status
[04:06:06.465] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:06.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:14:0) after 1m0s
[04:07:06.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:14:0)
[04:07:06.483] Timer tick, asking Buildbucket for the build status
[04:07:06.590] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:06.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:15:0) after 1m0s
[04:08:06.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:15:0)
[04:08:06.607] Timer tick, asking Buildbucket for the build status
[04:08:06.837] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:06.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:16:0) after 1m0s
[04:09:06.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:16:0)
[04:09:06.869] Timer tick, asking Buildbucket for the build status
[04:09:06.987] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:06.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:17:0) after 1m0s
[04:10:07.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:17:0)
[04:10:07.006] Timer tick, asking Buildbucket for the build status
[04:10:07.389] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:07.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:18:0) after 1m0s
[04:11:07.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:18:0)
[04:11:07.419] Timer tick, asking Buildbucket for the build status
[04:11:07.532] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:07.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:19:0) after 1m0s
[04:12:07.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:19:0)
[04:12:07.555] Timer tick, asking Buildbucket for the build status
[04:12:07.657] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:07.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:20:0) after 1m0s
[04:13:07.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:20:0)
[04:13:07.670] Timer tick, asking Buildbucket for the build status
[04:13:07.755] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:07.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:21:0) after 1m0s
[04:14:07.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:21:0)
[04:14:07.776] Timer tick, asking Buildbucket for the build status
[04:14:08.494] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:08.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:22:0) after 1m0s
[04:15:08.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:22:0)
[04:15:08.565] Timer tick, asking Buildbucket for the build status
[04:15:08.660] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:08.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:23:0) after 1m0s
[04:16:08.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:23:0)
[04:16:08.745] Timer tick, asking Buildbucket for the build status
[04:16:09.102] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:09.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:24:0) after 1m0s
[04:17:09.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:24:0)
[04:17:09.111] Timer tick, asking Buildbucket for the build status
[04:17:09.247] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:09.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:25:0) after 1m0s
[04:18:09.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:25:0)
[04:18:09.261] Timer tick, asking Buildbucket for the build status
[04:18:09.370] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:09.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:26:0) after 1m0s
[04:19:09.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:26:0)
[04:19:09.362] Timer tick, asking Buildbucket for the build status
[04:19:09.458] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:09.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:27:0) after 1m0s
[04:20:09.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:27:0)
[04:20:09.464] Timer tick, asking Buildbucket for the build status
[04:20:09.768] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:09.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:28:0) after 1m0s
[04:21:09.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:28:0)
[04:21:09.801] Timer tick, asking Buildbucket for the build status
[04:21:09.932] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:09.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:29:0) after 1m0s
[04:22:09.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:29:0)
[04:22:09.951] Timer tick, asking Buildbucket for the build status
[04:22:10.122] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:10.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:30:0) after 1m0s
[04:23:10.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:30:0)
[04:23:10.141] Timer tick, asking Buildbucket for the build status
[04:23:10.283] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:10.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:31:0) after 1m0s
[04:24:10.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:31:0)
[04:24:10.276] Timer tick, asking Buildbucket for the build status
[04:24:10.567] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:10.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:32:0) after 1m0s
[04:25:10.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:32:0)
[04:25:10.546] Timer tick, asking Buildbucket for the build status
[04:25:10.633] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:10.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:33:0) after 1m0s
[04:26:10.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:33:0)
[04:26:10.619] Timer tick, asking Buildbucket for the build status
[04:26:11.169] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:11.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:34:0) after 1m0s
[04:27:11.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:34:0)
[04:27:11.144] Timer tick, asking Buildbucket for the build status
[04:27:11.266] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:11.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:35:0) after 1m0s
[04:28:11.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:35:0)
[04:28:11.294] Timer tick, asking Buildbucket for the build status
[04:28:11.390] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:11.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:36:0) after 1m0s
[04:29:11.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:36:0)
[04:29:11.399] Timer tick, asking Buildbucket for the build status
[04:29:12.270] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:12.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:37:0) after 1m0s
[04:30:12.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:37:0)
[04:30:12.293] Timer tick, asking Buildbucket for the build status
[04:30:12.440] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:12.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:38:0) after 1m0s
[04:31:12.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:38:0)
[04:31:12.465] Timer tick, asking Buildbucket for the build status
[04:31:13.163] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:13.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:39:0) after 1m0s
[04:32:13.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:39:0)
[04:32:13.184] Timer tick, asking Buildbucket for the build status
[04:32:13.404] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:13.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:40:0) after 1m0s
[04:33:13.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:40:0)
[04:33:13.407] Timer tick, asking Buildbucket for the build status
[04:33:13.601] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:13.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:41:0) after 1m0s
[04:34:13.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:41:0)
[04:34:13.620] Timer tick, asking Buildbucket for the build status
[04:34:13.859] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:13.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:42:0) after 1m0s
[04:35:13.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:42:0)
[04:35:13.878] Timer tick, asking Buildbucket for the build status
[04:35:14.069] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:14.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:43:0) after 1m0s
[04:36:14.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:43:0)
[04:36:14.056] Timer tick, asking Buildbucket for the build status
[04:36:14.220] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:14.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:44:0) after 1m0s
[04:37:14.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:44:0)
[04:37:14.235] Timer tick, asking Buildbucket for the build status
[04:37:14.404] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:14.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:45:0) after 1m0s
[04:38:14.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:45:0)
[04:38:14.397] Timer tick, asking Buildbucket for the build status
[04:38:14.535] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:14.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:46:0) after 1m0s
[04:39:14.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:46:0)
[04:39:14.555] Timer tick, asking Buildbucket for the build status
[04:39:14.772] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:14.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:47:0) after 1m0s
[04:40:14.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:47:0)
[04:40:14.808] Timer tick, asking Buildbucket for the build status
[04:40:14.899] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:14.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:48:0) after 1m0s
[04:41:14.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:48:0)
[04:41:14.918] Timer tick, asking Buildbucket for the build status
[04:41:15.103] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:15.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:49:0) after 1m0s
[04:42:15.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:49:0)
[04:42:15.108] Timer tick, asking Buildbucket for the build status
[04:42:15.377] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:15.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:50:0) after 1m0s
[04:43:15.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:50:0)
[04:43:15.398] Timer tick, asking Buildbucket for the build status
[04:43:15.665] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:15.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:51:0) after 1m0s
[04:44:15.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:51:0)
[04:44:15.686] Timer tick, asking Buildbucket for the build status
[04:44:15.783] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:15.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:52:0) after 1m0s
[04:45:15.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:52:0)
[04:45:15.804] Timer tick, asking Buildbucket for the build status
[04:45:15.894] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:15.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:53:0) after 1m0s
[04:46:16.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:53:0)
[04:46:16.013] Timer tick, asking Buildbucket for the build status
[04:46:16.306] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:16.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:54:0) after 1m0s
[04:47:16.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:54:0)
[04:47:16.378] Timer tick, asking Buildbucket for the build status
[04:47:16.711] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:16.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:55:0) after 1m0s
[04:48:16.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:55:0)
[04:48:16.739] Timer tick, asking Buildbucket for the build status
[04:48:16.882] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:16.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:56:0) after 1m0s
[04:49:16.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:56:0)
[04:49:16.908] Timer tick, asking Buildbucket for the build status
[04:49:17.002] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:17.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:57:0) after 1m0s
[04:50:17.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:57:0)
[04:50:17.021] Timer tick, asking Buildbucket for the build status
[04:50:17.165] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:17.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:58:0) after 1m0s
[04:51:17.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:58:0)
[04:51:17.187] Timer tick, asking Buildbucket for the build status
[04:51:17.795] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:17.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:59:0) after 1m0s
[04:52:17.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:59:0)
[04:52:17.827] Timer tick, asking Buildbucket for the build status
[04:52:18.206] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:18.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:60:0) after 1m0s
[04:53:18.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:60:0)
[04:53:18.199] Timer tick, asking Buildbucket for the build status
[04:53:18.379] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:18.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:61:0) after 1m0s
[04:54:18.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:61:0)
[04:54:18.407] Timer tick, asking Buildbucket for the build status
[04:54:18.517] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:18.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:62:0) after 1m0s
[04:55:18.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:62:0)
[04:55:18.540] Timer tick, asking Buildbucket for the build status
[04:55:18.879] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:18.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:63:0) after 1m0s
[04:56:18.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:63:0)
[04:56:18.909] Timer tick, asking Buildbucket for the build status
[04:56:19.028] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:19.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:64:0) after 1m0s
[04:57:19.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:64:0)
[04:57:19.087] Timer tick, asking Buildbucket for the build status
[04:57:19.999] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:19.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:65:0) after 1m0s
[04:58:20.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:65:0)
[04:58:20.019] Timer tick, asking Buildbucket for the build status
[04:58:20.145] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:20.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:66:0) after 1m0s
[04:59:20.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:66:0)
[04:59:20.163] Timer tick, asking Buildbucket for the build status
[04:59:20.291] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:20.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:67:0) after 1m0s
[05:00:20.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:67:0)
[05:00:20.311] Timer tick, asking Buildbucket for the build status
[05:00:20.475] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:20.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:68:0) after 1m0s
[05:01:20.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:68:0)
[05:01:20.481] Timer tick, asking Buildbucket for the build status
[05:01:20.577] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:20.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:69:0) after 1m0s
[05:02:20.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:69:0)
[05:02:20.596] Timer tick, asking Buildbucket for the build status
[05:02:20.724] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:20.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:70:0) after 1m0s
[05:03:20.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:70:0)
[05:03:20.731] Timer tick, asking Buildbucket for the build status
[05:03:20.887] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:20.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:71:0) after 1m0s
[05:04:20.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:71:0)
[05:04:20.918] Timer tick, asking Buildbucket for the build status
[05:04:21.076] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:21.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:72:0) after 1m0s
[05:05:21.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:72:0)
[05:05:21.094] Timer tick, asking Buildbucket for the build status
[05:05:21.474] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:21.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:73:0) after 1m0s
[05:06:21.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:73:0)
[05:06:21.499] Timer tick, asking Buildbucket for the build status
[05:06:21.666] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:21.666] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:74:0) after 1m0s
[05:07:21.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:74:0)
[05:07:21.656] Timer tick, asking Buildbucket for the build status
[05:07:21.953] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:21.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:75:0) after 1m0s
[05:08:21.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:75:0)
[05:08:21.975] Timer tick, asking Buildbucket for the build status
[05:08:22.756] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:22.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:76:0) after 1m0s
[05:09:22.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:76:0)
[05:09:22.779] Timer tick, asking Buildbucket for the build status
[05:09:23.069] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:23.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:77:0) after 1m0s
[05:10:23.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:77:0)
[05:10:23.093] Timer tick, asking Buildbucket for the build status
[05:10:23.488] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:23.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:78:0) after 1m0s
[05:11:23.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:78:0)
[05:11:23.480] Timer tick, asking Buildbucket for the build status
[05:11:23.621] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:23.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:79:0) after 1m0s
[05:12:23.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:79:0)
[05:12:23.641] Timer tick, asking Buildbucket for the build status
[05:12:23.786] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:23.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:80:0) after 1m0s
[05:13:23.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:80:0)
[05:13:23.808] Timer tick, asking Buildbucket for the build status
[05:13:24.143] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:24.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:81:0) after 1m0s
[05:14:24.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:81:0)
[05:14:24.165] Timer tick, asking Buildbucket for the build status
[05:14:24.474] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:24.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:82:0) after 1m0s
[05:15:24.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:82:0)
[05:15:24.523] Timer tick, asking Buildbucket for the build status
[05:15:24.721] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:24.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:83:0) after 1m0s
[05:16:24.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:83:0)
[05:16:24.703] Timer tick, asking Buildbucket for the build status
[05:16:24.861] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:24.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:84:0) after 1m0s
[05:17:24.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:84:0)
[05:17:24.838] Timer tick, asking Buildbucket for the build status
[05:17:25.026] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:25.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:85:0) after 1m0s
[05:18:25.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:85:0)
[05:18:25.050] Timer tick, asking Buildbucket for the build status
[05:18:25.806] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:25.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:86:0) after 1m0s
[05:19:25.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:86:0)
[05:19:25.826] Timer tick, asking Buildbucket for the build status
[05:19:26.124] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:26.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:87:0) after 1m0s
[05:20:26.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:87:0)
[05:20:26.108] Timer tick, asking Buildbucket for the build status
[05:20:26.221] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:26.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:88:0) after 1m0s
[05:21:26.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:88:0)
[05:21:26.239] Timer tick, asking Buildbucket for the build status
[05:21:26.857] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:26.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:89:0) after 1m0s
[05:22:26.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:89:0)
[05:22:26.832] Timer tick, asking Buildbucket for the build status
[05:22:27.105] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:27.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:90:0) after 1m0s
[05:23:27.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:90:0)
[05:23:27.104] Timer tick, asking Buildbucket for the build status
[05:23:27.316] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:27.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:91:0) after 1m0s
[05:24:27.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:91:0)
[05:24:27.310] Timer tick, asking Buildbucket for the build status
[05:24:27.998] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:27.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:92:0) after 1m0s
[05:25:27.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:92:0)
[05:25:27.996] Timer tick, asking Buildbucket for the build status
[05:25:28.108] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:28.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:93:0) after 1m0s
[05:26:28.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:93:0)
[05:26:28.087] Timer tick, asking Buildbucket for the build status
[05:26:29.064] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:29.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:94:0) after 1m0s
[05:27:29.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:94:0)
[05:27:29.079] Timer tick, asking Buildbucket for the build status
[05:27:29.185] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:29.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:95:0) after 1m0s
[05:28:29.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:95:0)
[05:28:29.172] Timer tick, asking Buildbucket for the build status
[05:28:29.279] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:29.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:96:0) after 1m0s
[05:29:29.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:96:0)
[05:29:29.259] Timer tick, asking Buildbucket for the build status
[05:29:29.371] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:29.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:97:0) after 1m0s
[05:30:29.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:97:0)
[05:30:29.378] Timer tick, asking Buildbucket for the build status
[05:30:29.969] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:29.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:98:0) after 1m0s
[05:31:29.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:98:0)
[05:31:29.968] Timer tick, asking Buildbucket for the build status
[05:31:30.375] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:30.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:99:0) after 1m0s
[05:32:30.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:99:0)
[05:32:30.350] Timer tick, asking Buildbucket for the build status
[05:32:30.957] Build 8917773680574572864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:30.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083203742522614224:100:0) after 1m0s
[05:32:34.584] Received PubSub notification, asking Buildbucket for the build status
[05:32:34.979] Build 8917773680574572864: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:32:34.979] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553751153793109",
"created_by": "project:chromium",
"created_ts": "1553745303520993",
"failure_reason": "BUILD_FAILURE",
"id": "8917773680574572864",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"eb8d3bc0b60753501c920029eb894f3b3b5dd7e5\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}, \"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\": 11577, \"got_angle_revision\": \"e18ff25d261d7e99ccd5ce6b796832c5ba1281f4\", \"got_dawn_revision\": \"889d743baa3d150e25cb2ef27b6b0366ce5b6d84\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"eb8d3bc0b60753501c920029eb894f3b3b5dd7e5\", \"got_revision_cp\": \"refs/heads/master@{#645171}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"1b0ea7c9fc13c2808ca6626d8fa11c779bf08e7d\", \"got_v8_revision_cp\": \"refs/heads/7.5.128@{#1}\", \"got_webrtc_revision\": \"b3ae979ecd92180109fcf2c6c6e200b97d25d62c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27326}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"eb8d3bc0b60753501c920029eb894f3b3b5dd7e5\", \"swarm_hashes\": {\"accessibility_unittests\": \"ada00d7463c07515a7a9a8cdb08a372e1de11a87\", \"angle_unittests\": \"ffeaa27b8ce588426a9d4f5f030d6495a2796b4f\", \"app_list_unittests\": \"56deb8c23aecc0b6a0d592fd7a23a6fb5939a53f\", \"app_shell_unittests\": \"bea9ef6a71571344f5ec17448a24a7befd2c6707\", \"ash_unittests\": \"fa9e7f55657a58ba69af4a493073fabc7aa6f737\", \"aura_unittests\": \"b3ed8c3d1a637f9bbf9c1bd01bc0fbc033c00f39\", \"base_unittests\": \"d58aa93f68f72ddde4d06865997ca98e24a4d728\", \"blink_common_unittests\": \"27baa7ee0ed9453f1806b2b33b2e25c08ed7b2f9\", \"blink_fuzzer_unittests\": \"76f25ede9a4618cc13f178901003d0f793e588c1\", \"blink_heap_unittests\": \"0b60982391881d7e730079c7e1268877b1922fab\", \"blink_platform_unittests\": \"74f48f86e970785e7d9192c4c8ca3c1cb574b41b\", \"blink_unittests\": \"0b105599356f3044e55c7f2ca875fea9a2a68a68\", \"boringssl_crypto_tests\": \"114cc5aa8a379d0eef5d13d7e44d4e683de08dc5\", \"boringssl_ssl_tests\": \"294d6f00e233a5248644263a48bf2e729a4fa735\", \"browser_tests\": \"b6074a53a9b17c08e167ac31ceb5b851d5e81da2\", \"cacheinvalidation_unittests\": \"e9e9d02f07b81f41659fbd04e64e410a9a7a69e7\", \"capture_unittests\": \"61a1cde2e925ddcd78a8ca3fe967813512a15293\", \"cast_unittests\": \"b80ca57b09b8998814869a76c63490d2fd74cf7e\", \"cc_unittests\": \"90e774736f77d435bfeafb1d4ddb4a4265e56081\", \"chrome_app_unittests\": \"cd702b28b0a279904c184ad2df43989a70e13fc4\", \"chromedriver_unittests\": \"e1d88e0d73ecb23816688f8259f9d92167b5cad5\", \"chromeos_components_unittests\": \"31633516fe4c90927f96a0161eb8e3959991e098\", \"chromeos_unittests\": \"77a04d1db9a40be0e208d225e9ff811a4a48f803\", \"components_browsertests\": \"fa9adfe779be29d5309af1f42d252e6113df3914\", \"components_unittests\": \"fccc26267566e4d51eb261df740658520115f2e3\", \"compositor_unittests\": \"6afc120d0bdd95a28fe50e5e30b15f8e6856edef\", \"content_browsertests\": \"10bf20665644a1b384d58ecd0f7ad8b6f3ea694e\", \"content_unittests\": \"ff62bca378b89bba620c9b882ed1db6f3feee821\", \"crypto_unittests\": \"a37566a163967a0764f271f3e36c84cdb1ca0114\", \"dbus_unittests\": \"56567ce1b8dc1071de6713046cbc56860dfa25c9\", \"device_unittests\": \"edb589681f49d24ebbed04e98debce2a5e92a02a\", \"display_unittests\": \"13999694c9c9f5717a36e3fa21c7e4d814c26b51\", \"events_unittests\": \"7fa3c0608a9437bb7ca362ec162257e42fa04840\", \"exo_unittests\": \"3eebbd3a46ec4000a191c34142d588d0dc35df82\", \"extensions_browsertests\": \"d21d31f5c9e678e8d5319afc80bfd7f0cc6aa492\", \"extensions_unittests\": \"ebd4795a8481af785a6d9b80f15c20f1f16a7c55\", \"filesystem_service_unittests\": \"ad1f72ea081822ffe3fa32f4a2200030925a1dc2\", \"gcm_unit_tests\": \"cfdc6e477e2537a3dee5466be6d0a553dc9536e7\", \"gfx_unittests\": \"b55547baf682fadb5272eaa227b5aea7777a78db\", \"gin_unittests\": \"897e9e7ba50dce6681fb47f474c46418437b3af6\", \"gl_unittests_ozone\": \"1310b8e75def9674c0f0da55e6bde9f7891dc8e3\", \"google_apis_unittests\": \"562298e09c2fb37ded4bebe3dcbabece3ee678ab\", \"gpu_unittests\": \"34aa0ee6d719aca0c551ed12e80fff128c7678ab\", \"interactive_ui_tests\": \"7768db0d9e507dfdb22d092a1dcfab9b2c722531\", \"ipc_tests\": \"6a93108f5d5e7d6c8e72aac0973eda5cd9cfa571\", \"jingle_unittests\": \"c99163b665a3d60547245a8fe4aab2f6b9025f05\", \"keyboard_unittests\": \"bc0ca95de044ea34d5bc155138eb03fceed142a7\", \"latency_unittests\": \"5caf36c82afb0bd003a95b46e0f2e640de1fa8e1\", \"leveldb_service_unittests\": \"8adf5ca0f83e8da1ec68fccbdc56850d02357e27\", \"libjingle_xmpp_unittests\": \"29932605e3226fb865102d9c5489546aaaef6d5a\", \"media_blink_unittests\": \"9122c3ec3c300d5ceaee613b00e982f48e6ace25\", \"media_service_unittests\": \"e25434f3f651d36e57ebffd021cf4ee1dae3ed17\", \"media_unittests\": \"14ee6d8d2b623bddb7ae038125bbae9d97d72ba5\", \"message_center_unittests\": \"8f92ed626c6f53f5cca180a2541da2ee7e437dea\", \"midi_unittests\": \"0197ec1686d1aa01bb8d04b7f3e8896aecc73ac7\", \"mojo_core_unittests\": \"f670b749acb46e7429291129cb289b750a64f08e\", \"mojo_unittests\": \"53cd03d21a08b725f11155704cf7883f39e4e25f\", \"nacl_helper_nonsfi_unittests\": \"4d0ea0e128624ed500045b530f2711be15548ace\", \"nacl_loader_unittests\": \"b72c1932e7fbce32be0f937a6885f256799b1531\", \"native_theme_unittests\": \"d2211a49d948ee8d3e7cb330dfd67abb44ba0eee\", \"net_unittests\": \"d9fb8b313e309e2ab982f02883bc7729b1cebd94\", \"ozone_gl_unittests\": \"08600dc489e432fce6876ed9a37fc319c03f3174\", \"ozone_unittests\": \"585620ccb0d818b1a6207af6a58912b4f7f79c43\", \"ozone_x11_unittests\": \"0efa1e9b85bea581ff48f9e9fc2e310dceb84a7c\", \"pdf_unittests\": \"c8efcb24d0d91a21cb924f3c104af2266787cf68\", \"perfetto_unittests\": \"88330a8bb9b45946edbdd375aba2e1bdc39455c8\", \"ppapi_unittests\": \"56e800f9f23490320be34fa71d13e02b9b692a5c\", \"printing_unittests\": \"cf60be0afc151d5eda45b84acb0bedb815e94ac0\", \"remoting_unittests\": \"3f2b772e9323068754a6e9efe3b19c77f182bd21\", \"sandbox_linux_unittests\": \"250ea983ede38de2a228e32265b0a670bdec580e\", \"service_manager_unittests\": \"049b1459ed36c2e0fba22fb79b4f7fcfe6b889ca\", \"services_unittests\": \"96d55666a96e3a2c5662baf6446fadaf7133abe0\", \"shell_dialogs_unittests\": \"1bace3fb0b3b5f44697302601feb8a0de7293638\", \"skia_unittests\": \"0a615af219e2ff588c6de3fc35268e8cd9e6c9fc\", \"snapshot_unittests\": \"5efd10d1b2e63a9fb6ceaa901badc4f31905e000\", \"sql_unittests\": \"930871529fc73e20925462574ddeac340e84c79b\", \"storage_unittests\": \"643cfe213fc94ec38c27a10f7d45e0d305d6f283\", \"sync_integration_tests\": \"16edef258b4b9f86ebf25d1ac54b02d2001d85f1\", \"traffic_annotation_auditor_unittests\": \"16f162a0312db1f347799794b83151f5a9c43978\", \"ui_base_unittests\": \"3f0ee4fda8d20d871a9b83be6f13a4fb41a26508\", \"ui_chromeos_unittests\": \"749e7113a87fbca9e32494a07bee4368f010ccad\", \"ui_touch_selection_unittests\": \"a2d0b2aff4f6442430a7de05fc7055dea4c22508\", \"unit_tests\": \"3a06992c515cb2824cb270a16a069ed926582e48\", \"url_unittests\": \"9616026e731c5b15a1426124dded34ad709dec74\", \"usage_time_limit_unittests\": \"9cee574458bb39689c7c97b035c4ff587ce131c0\", \"views_unittests\": \"feff3ca7cac3d3d6f3a0f7533d1e6da3ef85ed34\", \"viz_unittests\": \"eaef31dcd90330efc41a3a3bc72bc08ad9ab2a1b\", \"wm_unittests\": \"f8ac985cae159f1adff9fd95cf1896feeda6aeb3\", \"wtf_unittests\": \"875de2dd755ea49602ec270b428178edf01d5f59\"}, \"swarm_hashes_refs/heads/master(at){#645171}_without_patch\": {\"accessibility_unittests\": \"ada00d7463c07515a7a9a8cdb08a372e1de11a87\", \"angle_unittests\": \"ffeaa27b8ce588426a9d4f5f030d6495a2796b4f\", \"app_list_unittests\": \"56deb8c23aecc0b6a0d592fd7a23a6fb5939a53f\", \"app_shell_unittests\": \"bea9ef6a71571344f5ec17448a24a7befd2c6707\", \"ash_unittests\": \"fa9e7f55657a58ba69af4a493073fabc7aa6f737\", \"aura_unittests\": \"b3ed8c3d1a637f9bbf9c1bd01bc0fbc033c00f39\", \"base_unittests\": \"d58aa93f68f72ddde4d06865997ca98e24a4d728\", \"blink_common_unittests\": \"27baa7ee0ed9453f1806b2b33b2e25c08ed7b2f9\", \"blink_fuzzer_unittests\": \"76f25ede9a4618cc13f178901003d0f793e588c1\", \"blink_heap_unittests\": \"0b60982391881d7e730079c7e1268877b1922fab\", \"blink_platform_unittests\": \"74f48f86e970785e7d9192c4c8ca3c1cb574b41b\", \"blink_unittests\": \"0b105599356f3044e55c7f2ca875fea9a2a68a68\", \"boringssl_crypto_tests\": \"114cc5aa8a379d0eef5d13d7e44d4e683de08dc5\", \"boringssl_ssl_tests\": \"294d6f00e233a5248644263a48bf2e729a4fa735\", \"browser_tests\": \"b6074a53a9b17c08e167ac31ceb5b851d5e81da2\", \"cacheinvalidation_unittests\": \"e9e9d02f07b81f41659fbd04e64e410a9a7a69e7\", \"capture_unittests\": \"61a1cde2e925ddcd78a8ca3fe967813512a15293\", \"cast_unittests\": \"b80ca57b09b8998814869a76c63490d2fd74cf7e\", \"cc_unittests\": \"90e774736f77d435bfeafb1d4ddb4a4265e56081\", \"chrome_app_unittests\": \"cd702b28b0a279904c184ad2df43989a70e13fc4\", \"chromedriver_unittests\": \"e1d88e0d73ecb23816688f8259f9d92167b5cad5\", \"chromeos_components_unittests\": \"31633516fe4c90927f96a0161eb8e3959991e098\", \"chromeos_unittests\": \"77a04d1db9a40be0e208d225e9ff811a4a48f803\", \"components_browsertests\": \"fa9adfe779be29d5309af1f42d252e6113df3914\", \"components_unittests\": \"fccc26267566e4d51eb261df740658520115f2e3\", \"compositor_unittests\": \"6afc120d0bdd95a28fe50e5e30b15f8e6856edef\", \"content_browsertests\": \"10bf20665644a1b384d58ecd0f7ad8b6f3ea694e\", \"content_unittests\": \"ff62bca378b89bba620c9b882ed1db6f3feee821\", \"crypto_unittests\": \"a37566a163967a0764f271f3e36c84cdb1ca0114\", \"dbus_unittests\": \"56567ce1b8dc1071de6713046cbc56860dfa25c9\", \"device_unittests\": \"edb589681f49d24ebbed04e98debce2a5e92a02a\", \"display_unittests\": \"13999694c9c9f5717a36e3fa21c7e4d814c26b51\", \"events_unittests\": \"7fa3c0608a9437bb7ca362ec162257e42fa04840\", \"exo_unittests\": \"3eebbd3a46ec4000a191c34142d588d0dc35df82\", \"extensions_browsertests\": \"d21d31f5c9e678e8d5319afc80bfd7f0cc6aa492\", \"extensions_unittests\": \"ebd4795a8481af785a6d9b80f15c20f1f16a7c55\", \"filesystem_service_unittests\": \"ad1f72ea081822ffe3fa32f4a2200030925a1dc2\", \"gcm_unit_tests\": \"cfdc6e477e2537a3dee5466be6d0a553dc9536e7\", \"gfx_unittests\": \"b55547baf682fadb5272eaa227b5aea7777a78db\", \"gin_unittests\": \"897e9e7ba50dce6681fb47f474c46418437b3af6\", \"gl_unittests_ozone\": \"1310b8e75def9674c0f0da55e6bde9f7891dc8e3\", \"google_apis_unittests\": \"562298e09c2fb37ded4bebe3dcbabece3ee678ab\", \"gpu_unittests\": \"34aa0ee6d719aca0c551ed12e80fff128c7678ab\", \"interactive_ui_tests\": \"7768db0d9e507dfdb22d092a1dcfab9b2c722531\", \"ipc_tests\": \"6a93108f5d5e7d6c8e72aac0973eda5cd9cfa571\", \"jingle_unittests\": \"c99163b665a3d60547245a8fe4aab2f6b9025f05\", \"keyboard_unittests\": \"bc0ca95de044ea34d5bc155138eb03fceed142a7\", \"latency_unittests\": \"5caf36c82afb0bd003a95b46e0f2e640de1fa8e1\", \"leveldb_service_unittests\": \"8adf5ca0f83e8da1ec68fccbdc56850d02357e27\", \"libjingle_xmpp_unittests\": \"29932605e3226fb865102d9c5489546aaaef6d5a\", \"media_blink_unittests\": \"9122c3ec3c300d5ceaee613b00e982f48e6ace25\", \"media_service_unittests\": \"e25434f3f651d36e57ebffd021cf4ee1dae3ed17\", \"media_unittests\": \"14ee6d8d2b623bddb7ae038125bbae9d97d72ba5\", \"message_center_unittests\": \"8f92ed626c6f53f5cca180a2541da2ee7e437dea\", \"midi_unittests\": \"0197ec1686d1aa01bb8d04b7f3e8896aecc73ac7\", \"mojo_core_unittests\": \"f670b749acb46e7429291129cb289b750a64f08e\", \"mojo_unittests\": \"53cd03d21a08b725f11155704cf7883f39e4e25f\", \"nacl_helper_nonsfi_unittests\": \"4d0ea0e128624ed500045b530f2711be15548ace\", \"nacl_loader_unittests\": \"b72c1932e7fbce32be0f937a6885f256799b1531\", \"native_theme_unittests\": \"d2211a49d948ee8d3e7cb330dfd67abb44ba0eee\", \"net_unittests\": \"d9fb8b313e309e2ab982f02883bc7729b1cebd94\", \"ozone_gl_unittests\": \"08600dc489e432fce6876ed9a37fc319c03f3174\", \"ozone_unittests\": \"585620ccb0d818b1a6207af6a58912b4f7f79c43\", \"ozone_x11_unittests\": \"0efa1e9b85bea581ff48f9e9fc2e310dceb84a7c\", \"pdf_unittests\": \"c8efcb24d0d91a21cb924f3c104af2266787cf68\", \"perfetto_unittests\": \"88330a8bb9b45946edbdd375aba2e1bdc39455c8\", \"ppapi_unittests\": \"56e800f9f23490320be34fa71d13e02b9b692a5c\", \"printing_unittests\": \"cf60be0afc151d5eda45b84acb0bedb815e94ac0\", \"remoting_unittests\": \"3f2b772e9323068754a6e9efe3b19c77f182bd21\", \"sandbox_linux_unittests\": \"250ea983ede38de2a228e32265b0a670bdec580e\", \"service_manager_unittests\": \"049b1459ed36c2e0fba22fb79b4f7fcfe6b889ca\", \"services_unittests\": \"96d55666a96e3a2c5662baf6446fadaf7133abe0\", \"shell_dialogs_unittests\": \"1bace3fb0b3b5f44697302601feb8a0de7293638\", \"skia_unittests\": \"0a615af219e2ff588c6de3fc35268e8cd9e6c9fc\", \"snapshot_unittests\": \"5efd10d1b2e63a9fb6ceaa901badc4f31905e000\", \"sql_unittests\": \"930871529fc73e20925462574ddeac340e84c79b\", \"storage_unittests\": \"643cfe213fc94ec38c27a10f7d45e0d305d6f283\", \"sync_integration_tests\": \"16edef258b4b9f86ebf25d1ac54b02d2001d85f1\", \"traffic_annotation_auditor_unittests\": \"16f162a0312db1f347799794b83151f5a9c43978\", \"ui_base_unittests\": \"3f0ee4fda8d20d871a9b83be6f13a4fb41a26508\", \"ui_chromeos_unittests\": \"749e7113a87fbca9e32494a07bee4368f010ccad\", \"ui_touch_selection_unittests\": \"a2d0b2aff4f6442430a7de05fc7055dea4c22508\", \"unit_tests\": \"3a06992c515cb2824cb270a16a069ed926582e48\", \"url_unittests\": \"9616026e731c5b15a1426124dded34ad709dec74\", \"usage_time_limit_unittests\": \"9cee574458bb39689c7c97b035c4ff587ce131c0\", \"views_unittests\": \"feff3ca7cac3d3d6f3a0f7533d1e6da3ef85ed34\", \"viz_unittests\": \"eaef31dcd90330efc41a3a3bc72bc08ad9ab2a1b\", \"wm_unittests\": \"f8ac985cae159f1adff9fd95cf1896feeda6aeb3\", \"wtf_unittests\": \"875de2dd755ea49602ec270b428178edf01d5f59\"}}, \"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\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"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\": [\"4224-5f5030f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 tests failed: ['non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553745559535833",
"status": "COMPLETED",
"status_changed_ts": "1553745559963837",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11577",
"builder:linux-chromeos-dbg",
"buildset:commit/git/eb8d3bc0b60753501c920029eb894f3b3b5dd7e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb8d3bc0b60753501c920029eb894f3b3b5dd7e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083203742522614224",
"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/8917773680574572864/+/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:43db416313712a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553751154242727",
"url": "https://ci.chromium.org/b/8917773680574572864",
"utcnow_ts": "1553751154968954"
}
}
[05:32:34.979] Invocation finished in 1h37m32.69979347s with status FAILED