[13:46:19.025] New invocation is queued and will start shortly
[13:46:20.186] Starting the invocation (attempt 1)
[13:46:20.226] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:46:20.226] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:46:20.226] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019205084196385232",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3302f0828ae546c38a3634799281bfae771ee550\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9019205084196385232",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/3302f0828ae546c38a3634799281bfae771ee550",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3302f0828ae546c38a3634799281bfae771ee550",
"gitiles_ref:refs/heads/master"
]
}
[13:46:21.643] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614779180362938",
"id": "8853775022131046384",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3302f0828ae546c38a3634799281bfae771ee550\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614779181407926",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22847",
"builder:linux-chromeos-dbg",
"buildset:commit/git/3302f0828ae546c38a3634799281bfae771ee550",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3302f0828ae546c38a3634799281bfae771ee550",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019205084196385232",
"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/8853775022131046384/+/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": "1614779181408134",
"url": "https://ci.chromium.org/b/8853775022131046384",
"utcnow_ts": "1614779181629419"
}
}
[13:46:21.643] Task URL: https://ci.chromium.org/b/8853775022131046384
[13:46:21.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:2:0) after 1m0s
[13:47:21.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:2:0)
[13:47:21.673] Timer tick, asking Buildbucket for the build status
[13:47:21.753] Build 8853775022131046384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:47:21.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:3:0) after 1m0s
[13:47:49.014] Received PubSub notification, asking Buildbucket for the build status
[13:47:49.076] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:21.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:3:0)
[13:48:21.893] Timer tick, asking Buildbucket for the build status
[13:48:21.971] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:21.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:5:0) after 1m0s
[13:49:22.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:5:0)
[13:49:22.298] Timer tick, asking Buildbucket for the build status
[13:49:22.375] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:22.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:6:0) after 1m0s
[13:50:22.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:6:0)
[13:50:22.569] Timer tick, asking Buildbucket for the build status
[13:50:22.630] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:22.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:7:0) after 1m0s
[13:51:22.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:7:0)
[13:51:22.712] Timer tick, asking Buildbucket for the build status
[13:51:22.778] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:22.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:8:0) after 1m0s
[13:52:24.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:8:0)
[13:52:24.038] Timer tick, asking Buildbucket for the build status
[13:52:24.105] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:24.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:9:0) after 1m0s
[13:53:24.202] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:9:0)
[13:53:24.202] Timer tick, asking Buildbucket for the build status
[13:53:24.405] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:24.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:10:0) after 1m0s
[13:54:24.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:10:0)
[13:54:24.497] Timer tick, asking Buildbucket for the build status
[13:54:24.560] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:24.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:11:0) after 1m0s
[13:55:24.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:11:0)
[13:55:24.612] Timer tick, asking Buildbucket for the build status
[13:55:24.755] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:24.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:12:0) after 1m0s
[13:56:24.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:12:0)
[13:56:24.802] Timer tick, asking Buildbucket for the build status
[13:56:25.333] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:25.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:13:0) after 1m0s
[13:57:25.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:13:0)
[13:57:25.323] Timer tick, asking Buildbucket for the build status
[13:57:25.433] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:25.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:14:0) after 1m0s
[13:58:25.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:14:0)
[13:58:25.484] Timer tick, asking Buildbucket for the build status
[13:58:25.653] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:25.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:15:0) after 1m0s
[13:59:25.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:15:0)
[13:59:25.669] Timer tick, asking Buildbucket for the build status
[13:59:25.742] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:25.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:16:0) after 1m0s
[14:00:25.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:16:0)
[14:00:25.847] Timer tick, asking Buildbucket for the build status
[14:00:26.044] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:26.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:17:0) after 1m0s
[14:01:26.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:17:0)
[14:01:26.031] Timer tick, asking Buildbucket for the build status
[14:01:26.140] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:26.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:18:0) after 1m0s
[14:02:26.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:18:0)
[14:02:26.167] Timer tick, asking Buildbucket for the build status
[14:02:26.254] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:26.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:19:0) after 1m0s
[14:03:26.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:19:0)
[14:03:26.337] Timer tick, asking Buildbucket for the build status
[14:03:26.400] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:26.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:20:0) after 1m0s
[14:04:26.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:20:0)
[14:04:26.525] Timer tick, asking Buildbucket for the build status
[14:04:26.600] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:26.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:21:0) after 1m0s
[14:05:26.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:21:0)
[14:05:26.664] Timer tick, asking Buildbucket for the build status
[14:05:26.755] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:26.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:22:0) after 1m0s
[14:06:26.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:22:0)
[14:06:26.806] Timer tick, asking Buildbucket for the build status
[14:06:26.881] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:26.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:23:0) after 1m0s
[14:07:26.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:23:0)
[14:07:26.940] Timer tick, asking Buildbucket for the build status
[14:07:27.065] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:27.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:24:0) after 1m0s
[14:08:27.080] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:24:0)
[14:08:27.080] Timer tick, asking Buildbucket for the build status
[14:08:27.172] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:27.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:25:0) after 1m0s
[14:09:27.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:25:0)
[14:09:27.235] Timer tick, asking Buildbucket for the build status
[14:09:27.428] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:27.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:26:0) after 1m0s
[14:10:27.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:26:0)
[14:10:27.493] Timer tick, asking Buildbucket for the build status
[14:10:27.720] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:27.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:27:0) after 1m0s
[14:11:27.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:27:0)
[14:11:27.764] Timer tick, asking Buildbucket for the build status
[14:11:28.321] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:28.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:28:0) after 1m0s
[14:12:28.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:28:0)
[14:12:28.400] Timer tick, asking Buildbucket for the build status
[14:12:28.500] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:28.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:29:0) after 1m0s
[14:13:28.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:29:0)
[14:13:28.547] Timer tick, asking Buildbucket for the build status
[14:13:28.618] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:28.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:30:0) after 1m0s
[14:14:28.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:30:0)
[14:14:28.691] Timer tick, asking Buildbucket for the build status
[14:14:28.824] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:28.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:31:0) after 1m0s
[14:15:28.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:31:0)
[14:15:28.882] Timer tick, asking Buildbucket for the build status
[14:15:28.971] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:28.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:32:0) after 1m0s
[14:16:29.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:32:0)
[14:16:29.073] Timer tick, asking Buildbucket for the build status
[14:16:29.413] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:29.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:33:0) after 1m0s
[14:17:29.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:33:0)
[14:17:29.428] Timer tick, asking Buildbucket for the build status
[14:17:29.559] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:29.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:34:0) after 1m0s
[14:18:29.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:34:0)
[14:18:29.577] Timer tick, asking Buildbucket for the build status
[14:18:29.677] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:29.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:35:0) after 1m0s
[14:19:29.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:35:0)
[14:19:29.758] Timer tick, asking Buildbucket for the build status
[14:19:29.952] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:29.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:36:0) after 1m0s
[14:20:29.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:36:0)
[14:20:29.970] Timer tick, asking Buildbucket for the build status
[14:20:30.082] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:30.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:37:0) after 1m0s
[14:21:30.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:37:0)
[14:21:30.143] Timer tick, asking Buildbucket for the build status
[14:21:30.252] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:30.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:38:0) after 1m0s
[14:22:30.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:38:0)
[14:22:30.244] Timer tick, asking Buildbucket for the build status
[14:22:30.384] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:30.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:39:0) after 1m0s
[14:23:30.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:39:0)
[14:23:30.469] Timer tick, asking Buildbucket for the build status
[14:23:30.550] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:30.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:40:0) after 1m0s
[14:24:30.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:40:0)
[14:24:30.994] Timer tick, asking Buildbucket for the build status
[14:24:31.065] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:31.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:41:0) after 1m0s
[14:25:31.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:41:0)
[14:25:31.049] Timer tick, asking Buildbucket for the build status
[14:25:31.211] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:31.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:42:0) after 1m0s
[14:26:31.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:42:0)
[14:26:31.256] Timer tick, asking Buildbucket for the build status
[14:26:31.357] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:31.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:43:0) after 1m0s
[14:27:31.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:43:0)
[14:27:31.355] Timer tick, asking Buildbucket for the build status
[14:27:31.490] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:31.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:44:0) after 1m0s
[14:28:31.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:44:0)
[14:28:31.724] Timer tick, asking Buildbucket for the build status
[14:28:32.168] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:32.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:45:0) after 1m0s
[14:29:32.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:45:0)
[14:29:32.199] Timer tick, asking Buildbucket for the build status
[14:29:32.288] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:32.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:46:0) after 1m0s
[14:30:32.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:46:0)
[14:30:32.334] Timer tick, asking Buildbucket for the build status
[14:30:33.217] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:33.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:47:0) after 1m0s
[14:31:33.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:47:0)
[14:31:33.426] Timer tick, asking Buildbucket for the build status
[14:31:33.529] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:33.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:48:0) after 1m0s
[14:32:33.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:48:0)
[14:32:33.588] Timer tick, asking Buildbucket for the build status
[14:32:33.707] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:33.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:49:0) after 1m0s
[14:33:33.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:49:0)
[14:33:33.754] Timer tick, asking Buildbucket for the build status
[14:33:34.035] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:34.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:50:0) after 1m0s
[14:34:34.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:50:0)
[14:34:34.163] Timer tick, asking Buildbucket for the build status
[14:34:34.237] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:34.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:51:0) after 1m0s
[14:35:34.279] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:51:0)
[14:35:34.279] Timer tick, asking Buildbucket for the build status
[14:35:35.146] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:35.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:52:0) after 1m0s
[14:36:35.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:52:0)
[14:36:35.234] Timer tick, asking Buildbucket for the build status
[14:36:35.361] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:35.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:53:0) after 1m0s
[14:37:35.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:53:0)
[14:37:35.375] Timer tick, asking Buildbucket for the build status
[14:37:35.483] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:35.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:54:0) after 1m0s
[14:38:35.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:54:0)
[14:38:35.527] Timer tick, asking Buildbucket for the build status
[14:38:36.360] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:36.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:55:0) after 1m0s
[14:39:36.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:55:0)
[14:39:36.360] Timer tick, asking Buildbucket for the build status
[14:39:36.940] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:36.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:56:0) after 1m0s
[14:40:36.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:56:0)
[14:40:36.964] Timer tick, asking Buildbucket for the build status
[14:40:37.165] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:37.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:57:0) after 1m0s
[14:41:37.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:57:0)
[14:41:37.219] Timer tick, asking Buildbucket for the build status
[14:41:37.301] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:37.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:58:0) after 1m0s
[14:42:37.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:58:0)
[14:42:37.316] Timer tick, asking Buildbucket for the build status
[14:42:37.449] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:37.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:59:0) after 1m0s
[14:43:37.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:59:0)
[14:43:37.466] Timer tick, asking Buildbucket for the build status
[14:43:37.545] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:37.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:60:0) after 1m0s
[14:44:37.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:60:0)
[14:44:37.598] Timer tick, asking Buildbucket for the build status
[14:44:37.687] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:37.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:61:0) after 1m0s
[14:45:37.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:61:0)
[14:45:37.712] Timer tick, asking Buildbucket for the build status
[14:45:37.821] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:37.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:62:0) after 1m0s
[14:46:37.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:62:0)
[14:46:37.834] Timer tick, asking Buildbucket for the build status
[14:46:38.060] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:38.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:63:0) after 1m0s
[14:47:38.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:63:0)
[14:47:38.075] Timer tick, asking Buildbucket for the build status
[14:47:38.164] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:38.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:64:0) after 1m0s
[14:48:38.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:64:0)
[14:48:38.168] Timer tick, asking Buildbucket for the build status
[14:48:38.263] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:38.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:65:0) after 1m0s
[14:49:38.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:65:0)
[14:49:38.291] Timer tick, asking Buildbucket for the build status
[14:49:38.373] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:38.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:66:0) after 1m0s
[14:50:38.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:66:0)
[14:50:38.393] Timer tick, asking Buildbucket for the build status
[14:50:38.464] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:38.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:67:0) after 1m0s
[14:51:38.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:67:0)
[14:51:38.490] Timer tick, asking Buildbucket for the build status
[14:51:38.628] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:38.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:68:0) after 1m0s
[14:52:38.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:68:0)
[14:52:38.676] Timer tick, asking Buildbucket for the build status
[14:52:39.394] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:39.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:69:0) after 1m0s
[14:53:39.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:69:0)
[14:53:39.536] Timer tick, asking Buildbucket for the build status
[14:53:39.889] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:39.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:70:0) after 1m0s
[14:54:39.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:70:0)
[14:54:39.928] Timer tick, asking Buildbucket for the build status
[14:54:40.139] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:40.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:71:0) after 1m0s
[14:55:40.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:71:0)
[14:55:40.156] Timer tick, asking Buildbucket for the build status
[14:55:40.219] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:40.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:72:0) after 1m0s
[14:56:40.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:72:0)
[14:56:40.238] Timer tick, asking Buildbucket for the build status
[14:56:40.311] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:40.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:73:0) after 1m0s
[14:57:40.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:73:0)
[14:57:40.329] Timer tick, asking Buildbucket for the build status
[14:57:40.741] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:40.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:74:0) after 1m0s
[14:58:40.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:74:0)
[14:58:40.751] Timer tick, asking Buildbucket for the build status
[14:58:40.851] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:40.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:75:0) after 1m0s
[14:59:40.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:75:0)
[14:59:40.841] Timer tick, asking Buildbucket for the build status
[14:59:41.181] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:41.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:76:0) after 1m0s
[15:00:41.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:76:0)
[15:00:41.276] Timer tick, asking Buildbucket for the build status
[15:00:41.426] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:41.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:77:0) after 1m0s
[15:01:41.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:77:0)
[15:01:41.512] Timer tick, asking Buildbucket for the build status
[15:01:41.603] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:41.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:78:0) after 1m0s
[15:02:41.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:78:0)
[15:02:41.613] Timer tick, asking Buildbucket for the build status
[15:02:41.720] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:41.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:79:0) after 1m0s
[15:03:41.789] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:79:0)
[15:03:41.789] Timer tick, asking Buildbucket for the build status
[15:03:42.043] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:42.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:80:0) after 1m0s
[15:04:42.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:80:0)
[15:04:42.280] Timer tick, asking Buildbucket for the build status
[15:04:46.362] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:46.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:81:0) after 1m0s
[15:05:46.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:81:0)
[15:05:46.406] Timer tick, asking Buildbucket for the build status
[15:05:46.509] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:46.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:82:0) after 1m0s
[15:06:46.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:82:0)
[15:06:46.561] Timer tick, asking Buildbucket for the build status
[15:06:46.658] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:46.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:83:0) after 1m0s
[15:07:46.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:83:0)
[15:07:46.674] Timer tick, asking Buildbucket for the build status
[15:07:47.647] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:47.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:84:0) after 1m0s
[15:08:47.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:84:0)
[15:08:47.722] Timer tick, asking Buildbucket for the build status
[15:08:47.813] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:47.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:85:0) after 1m0s
[15:09:47.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:85:0)
[15:09:47.971] Timer tick, asking Buildbucket for the build status
[15:09:48.271] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:48.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:86:0) after 1m0s
[15:10:48.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:86:0)
[15:10:48.313] Timer tick, asking Buildbucket for the build status
[15:10:48.421] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:48.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:87:0) after 1m0s
[15:11:48.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:87:0)
[15:11:48.584] Timer tick, asking Buildbucket for the build status
[15:11:49.016] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:49.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:88:0) after 1m0s
[15:12:49.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:88:0)
[15:12:49.273] Timer tick, asking Buildbucket for the build status
[15:12:49.360] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:49.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:89:0) after 1m0s
[15:13:49.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:89:0)
[15:13:49.542] Timer tick, asking Buildbucket for the build status
[15:13:49.856] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:49.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:90:0) after 1m0s
[15:14:50.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:90:0)
[15:14:50.210] Timer tick, asking Buildbucket for the build status
[15:14:50.289] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:50.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:91:0) after 1m0s
[15:15:50.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:91:0)
[15:15:50.387] Timer tick, asking Buildbucket for the build status
[15:15:50.545] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:50.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:92:0) after 1m0s
[15:16:50.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:92:0)
[15:16:50.717] Timer tick, asking Buildbucket for the build status
[15:16:50.808] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:50.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:93:0) after 1m0s
[15:17:50.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:93:0)
[15:17:50.892] Timer tick, asking Buildbucket for the build status
[15:17:50.977] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:50.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:94:0) after 1m0s
[15:18:51.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:94:0)
[15:18:51.312] Timer tick, asking Buildbucket for the build status
[15:18:51.604] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:51.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:95:0) after 1m0s
[15:19:51.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:95:0)
[15:19:51.791] Timer tick, asking Buildbucket for the build status
[15:19:51.867] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:51.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:96:0) after 1m0s
[15:20:51.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:96:0)
[15:20:51.915] Timer tick, asking Buildbucket for the build status
[15:20:52.905] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:52.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:97:0) after 1m0s
[15:21:53.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:97:0)
[15:21:53.306] Timer tick, asking Buildbucket for the build status
[15:21:53.443] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:53.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:98:0) after 1m0s
[15:22:53.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:98:0)
[15:22:53.479] Timer tick, asking Buildbucket for the build status
[15:22:53.595] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:53.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:99:0) after 1m0s
[15:23:53.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:99:0)
[15:23:53.616] Timer tick, asking Buildbucket for the build status
[15:23:53.702] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:53.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:100:0) after 1m0s
[15:24:53.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:100:0)
[15:24:53.787] Timer tick, asking Buildbucket for the build status
[15:24:53.939] Build 8853775022131046384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:53.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019205084196385232:101:0) after 1m0s
[15:25:20.311] Received PubSub notification, asking Buildbucket for the build status
[15:25:20.386] Build 8853775022131046384: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:25:20.386] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614785119794236",
"created_by": "project:chromium",
"created_ts": "1614779180362938",
"failure_reason": "BUILD_FAILURE",
"id": "8853775022131046384",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3302f0828ae546c38a3634799281bfae771ee550\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- All/AppLaunchHandlerSystemWebAppsBrowserTest.LaunchSWA/_Default\\n\\n- AdsMemoryMeasurementBrowserTest.SingleAdFrame_MaxMemoryBytesRecorded\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"3302f0828ae546c38a3634799281bfae771ee550\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-193-vl1d\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22847, \"got_angle_revision\": \"1de8b5a697e731c03083bda16b2df67c1d7a4c47\", \"got_dawn_revision\": \"9b5f442c78cb642b18bc4f18ab672f1b4426c934\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"3302f0828ae546c38a3634799281bfae771ee550\", \"got_revision_cp\": \"refs/heads/master@{#859351}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"e16fedf6eeb13d22f7f7fd236c8255446af9cd59\", \"got_v8_revision_cp\": \"refs/heads/9.1.30@{#1}\", \"got_webrtc_revision\": \"07d83c8a9af02a0afc473503f75f150f23e3e0ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33366}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3302f0828ae546c38a3634799281bfae771ee550\", \"swarm_hashes_refs/heads/master(at){#859351}_without_patch\": {\"absl_hardening_tests\": \"9a3739cd9e96a82df1cfc761af58514ec53a3d18\", \"accessibility_unittests\": \"6cb3015bc94e51dbc27cd0f5db6d51a2c3be220c\", \"angle_unittests\": \"a7c56da78341301015b473b9ada2e272f4387d98\", \"app_list_unittests\": \"e59da5a1710c855deb0511608504fdea3d71bb6f\", \"app_shell_unittests\": \"d9d06cc53b326fb3f688aa9332ab1f8cec63ab04\", \"ash_unittests\": \"115135e1222044dbb8a9871f80894f63edf61524\", \"aura_unittests\": \"5bb65fa0b04b72464256eabac746f1ab8689866f\", \"base_unittests\": \"72c0de016c010dece7bfce83f100cf6eb12bfb21\", \"base_util_unittests\": \"e0a564fb76033d7295d2763df02422dca94b3123\", \"blink_common_unittests\": \"022ee3b8b7e54a00d13b65483f81386af0780c83\", \"blink_fuzzer_unittests\": \"ea1a8f48c6f9bf3a44875d137433a18dd605e72e\", \"blink_heap_unittests\": \"8404e766dd4801be2ea0d4074ca6aa6ea1a1e676\", \"blink_platform_unittests\": \"d51b53c2466b344580037f4541ad9b05cb65004d\", \"blink_unittests\": \"93b4b0bd7dd1ed52d9054b122ba86a5533c27d56\", \"boringssl_crypto_tests\": \"c7de8fa94eb601220da47a8ccd3048539a003898\", \"boringssl_ssl_tests\": \"cc71f14d68b7e6d4d248ab4fc89d0f91b6d3d1e6\", \"browser_tests\": \"f382cf944ebe06ee16ad6db8ed9a4a4558386ecc\", \"capture_unittests\": \"b21e3ad192ca4d27e691222bf95d7ad853fe1573\", \"cast_unittests\": \"d7641624000c4dc7007f034743478e62cdab2bb2\", \"cc_unittests\": \"c1f9151b19357778e1ce13c396b432cbca646f02\", \"chrome_app_unittests\": \"e361f1b83694aac7b1838fa915082b29d71ce6d1\", \"chromedriver_unittests\": \"9cb657697b47f446f163b4cb6f35dd191cfb0919\", \"chromeos_components_unittests\": \"a92dea7f9437aa35a31b92f2f848820abd8c0a5c\", \"chromeos_unittests\": \"66e476098f0b5fc68ad9ffb2d72edbbd21073b2d\", \"color_unittests\": \"3f4de421ba3ef1143ce427cafe3790392f1d5e1e\", \"components_browsertests\": \"80d1190734aef1de57bfa0b21a0e6933221ff287\", \"components_unittests\": \"6c287d01c8b77c4f20890df9146b3fe797c14bf3\", \"compositor_unittests\": \"ca85414d17b937f90b39b418994e69766c799676\", \"content_browsertests\": \"35764dbf6428889049a3f9fbdc801b506467d695\", \"content_unittests\": \"61768635c8c10f97c75be8ccba5b116e4032ca38\", \"crashpad_tests\": \"59fd32d7423ced855272402ebd84112e0f9fcfea\", \"crypto_unittests\": \"8d8597a5382844f272c3dce177d6fee06cb7b9c4\", \"dbus_unittests\": \"1171314da847d2980fdbf82c23ebf48c45ae5c6f\", \"device_unittests\": \"466a1c8fcbdd65963b5926ca31ee19eb507237c6\", \"display_unittests\": \"49bba22788c2eb2238a94b4d2d3f8c398bcde690\", \"events_unittests\": \"4a3db60b6f9c9a9e751fb6480d54dc2d1791de3b\", \"exo_unittests\": \"8ced4bdecf7c1f3e638713f11e33da51d1a03078\", \"extensions_browsertests\": \"3d8714de8ebcfe6bf5b4ae9ce3084ca6bc68e6c8\", \"extensions_unittests\": \"1f9aa4e15f6e95aad5ee017e63e5fb8daa72a319\", \"filesystem_service_unittests\": \"5300b1d0a129f82c5bf0e950a46948e8ca716f4d\", \"gcm_unit_tests\": \"6c84c6de5ba0d8b8270c92748329cdb675004dd2\", \"gfx_unittests\": \"fd64ca4e77e68e88338cd080b3eb7cc6ca37f1f5\", \"gin_unittests\": \"7646270d4c0ebac35516ac0c192fd26f2e912656\", \"gl_unittests_ozone\": \"6594c4e61554156fbf4645db89e77ac1ad0b6f3c\", \"google_apis_unittests\": \"cc5b5f44eef8bace89748c9723c1c9af207e72bf\", \"gpu_unittests\": \"ce7ee335fa248ee4262053ddac1406092de66c9b\", \"gwp_asan_unittests\": \"33ba9cb830b416e78e8ab083510ad2b8be61c9ca\", \"interactive_ui_tests\": \"dba594930b6ace1c25b0e2410027f7eb90adb4d5\", \"ipc_tests\": \"d46ef44bbd466c83f91ccca4e40ea41d801ed54c\", \"jingle_unittests\": \"8e43521f1e5186d4d23c4fd11e1cb3478f56195f\", \"keyboard_unittests\": \"4b5a9688b621bae50e2575efd3bea1ef183d5768\", \"latency_unittests\": \"7b904bdc247c8965fd1f60e9cddb07e31c7897ee\", \"libjingle_xmpp_unittests\": \"ac00e9cfa9eeb8b61ec1d0ebf75c7fec739d790e\", \"liburlpattern_unittests\": \"aadae281892cf35a5c4e61524067e3ba4385f143\", \"media_blink_unittests\": \"56b5e93e6620cf87f9b02e5dcbd97330258434c0\", \"media_unittests\": \"6254cf6ea2b947c8096d411adfc6e7ccf18906fc\", \"message_center_unittests\": \"f126f72df3f7c35e803445ba798f538ce0573e77\", \"midi_unittests\": \"35c8694d10507a0b992827c414a13c6f743523bf\", \"mojo_core_unittests\": \"8c4ccc762a4ae4763c4dea7e803105486072a8a9\", \"mojo_unittests\": \"abfac679412b391543ccb9c29d2b4c62dff8e8af\", \"nacl_helper_nonsfi_unittests\": \"d3471b629520eb164b86c4f317615190734cd5ce\", \"nacl_loader_unittests\": \"fcea76e381bbf47010aff6e45e23bd1bb0de39d3\", \"native_theme_unittests\": \"59e9939766f0ca67398647feed6312e6e113d51b\", \"net_unittests\": \"9b7c86aef83a2d551195ac09a06f089f1b147845\", \"ozone_gl_unittests\": \"e80b4a66cef96a1ff564aa438b0d4aab0ac06ca6\", \"ozone_unittests\": \"07f05a800668511496e9e163c182ce98389fb10e\", \"ozone_x11_unittests\": \"02fa18a0b7efd687425eb1fc782de6a348d1a35a\", \"pdf_unittests\": \"56ac9e81687559031f07d3fbebdec8f6edbfd854\", \"perfetto_unittests\": \"8e2d0d0f84bcbfa7053ed6cfb398c51194b505ea\", \"ppapi_unittests\": \"859d6367dbc2886ccea1a1cac62ed5210ade62c1\", \"printing_unittests\": \"81c4db2ee12566d4ca81ef85aadae45b0b38ba26\", \"remoting_unittests\": \"8054a6609ae615233f52286fae4c4252a1e9bd8c\", \"sandbox_linux_unittests\": \"2a5b066db0cd7833fdd12919360c5473f1344cff\", \"service_manager_unittests\": \"6aeded9f1f1374e3c6050eaa1d0ed39c14d992a1\", \"services_unittests\": \"9486ab0da0811511021dda0da4be6d20f5867a1c\", \"shell_dialogs_unittests\": \"5075e2f5f2283dbe4070a281868540c59fbfbf6e\", \"shell_encryption_unittests\": \"accd3f1d7bbd3ae944916c2b735f49f518f691dc\", \"skia_unittests\": \"0a1e16d8ba66d9b197f1b67691690d0701917386\", \"snapshot_unittests\": \"741e1db1e31938d3733cba027aec47815806df86\", \"sql_unittests\": \"68694a2cbc69dfe31e02e4a93a9a41d62187776e\", \"storage_unittests\": \"dc5b2ab1758e0f495bcd7923b0c74250cb032ec5\", \"sync_integration_tests\": \"8c02bb7f911d51c147bef3e8e8447286d32411ab\", \"traffic_annotation_auditor_unittests\": \"af6ba840b2078465fa43d51394418c3aeb10b0b8\", \"ui_base_unittests\": \"916f2c82c0a571e9abe7eb4f5e32eb048f38ffad\", \"ui_chromeos_unittests\": \"e845d851662d625f12e8bfbcf4880320f51afc54\", \"ui_touch_selection_unittests\": \"55a1b07292238845d9f419caf9b167239f17a904\", \"unit_tests\": \"624641e5efa6a3f2ec1343f5085bc18fe126eed4\", \"url_unittests\": \"52cc54a66ff1927e95813210cd2cbba78a5f787d\", \"usage_time_limit_unittests\": \"f85e2b3757f70039f89bd95c3568646cc36daf97\", \"views_unittests\": \"a24e02cb933f6cbcca8cbdf5f5bebf56a4de3720\", \"viz_unittests\": \"11da7768489c32889d74bb428448f82a5993f83e\", \"wayland_client_tests\": \"ff9e8acc114d6221417e90f606e6fd0ca9592c6d\", \"wm_unittests\": \"13ee5f97e7514aac691b354087043aa7daea7aa4\", \"wtf_unittests\": \"eb21e8bd79870f000b5f35ed3f2b1119a6a1e189\", \"zlib_unittests\": \"fde722f48ece375c242a154a1f54275d94cee98d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_4a782824ba39c0c6a32b2128ebb0687e83cb58dc0cbed64e2911b02f7893cfe6_v2\", \"builder_71ab4550ecc1db180544ee9bd5ebac58222d39ccc6e59d756fcde46903b3067c_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_f11fafa89e130b21aaaf89babe10260b305cabf64cf9dbedf786af2782bba9d2_v2\", \"builder_fe96da9c6a19fa2fc3ad0c2b7020b7cb68e4a92161f4cd6cb7dc1d1e2d5af5dc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-193-vl1d\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- All/AppLaunchHandlerSystemWebAppsBrowserTest.LaunchSWA/_Default\\n\\n- AdsMemoryMeasurementBrowserTest.SingleAdFrame_MaxMemoryBytesRecorded\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614779268504264",
"status": "COMPLETED",
"status_changed_ts": "1614779268773353",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22847",
"builder:linux-chromeos-dbg",
"buildset:commit/git/3302f0828ae546c38a3634799281bfae771ee550",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3302f0828ae546c38a3634799281bfae771ee550",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019205084196385232",
"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/8853775022131046384/+/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:52112873283a7310",
"user_agent:luci-scheduler"
],
"updated_ts": "1614785119794734",
"url": "https://ci.chromium.org/b/8853775022131046384",
"utcnow_ts": "1614785120378673"
}
}
[15:25:20.386] Invocation finished in 1h39m1.377077339s with status FAILED