[03:49:21.373] New invocation is queued and will start shortly
[03:49:22.731] Starting the invocation (attempt 1)
[03:49:22.780] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:49:22.782] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:49:22.783] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025946817240996624",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4d8c3b498d5d1763d53cfcceea7b47c1603e4230\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9025946817240996624",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/4d8c3b498d5d1763d53cfcceea7b47c1603e4230",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d8c3b498d5d1763d53cfcceea7b47c1603e4230",
"gitiles_ref:refs/heads/master"
]
}
[03:49:23.620] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608349762879460",
"id": "8860516754997615984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d8c3b498d5d1763d53cfcceea7b47c1603e4230\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608349763456920",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21818",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4d8c3b498d5d1763d53cfcceea7b47c1603e4230",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d8c3b498d5d1763d53cfcceea7b47c1603e4230",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025946817240996624",
"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/8860516754997615984/+/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": "1608349763457031",
"url": "https://ci.chromium.org/b/8860516754997615984",
"utcnow_ts": "1608349763613283"
}
}
[03:49:23.620] Task URL: https://ci.chromium.org/b/8860516754997615984
[03:49:23.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:2:0) after 1m0s
[03:50:23.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:2:0)
[03:50:23.689] Timer tick, asking Buildbucket for the build status
[03:50:23.775] Build 8860516754997615984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:23.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:3:0) after 1m0s
[03:51:23.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:3:0)
[03:51:23.811] Timer tick, asking Buildbucket for the build status
[03:51:23.873] Build 8860516754997615984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:23.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:4:0) after 1m0s
[03:52:24.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:4:0)
[03:52:24.013] Timer tick, asking Buildbucket for the build status
[03:52:24.170] Build 8860516754997615984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:24.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:5:0) after 1m0s
[03:53:24.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:5:0)
[03:53:24.188] Timer tick, asking Buildbucket for the build status
[03:53:24.269] Build 8860516754997615984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:24.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:6:0) after 1m0s
[03:54:24.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:6:0)
[03:54:24.306] Timer tick, asking Buildbucket for the build status
[03:54:24.676] Build 8860516754997615984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:24.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:7:0) after 1m0s
[03:55:24.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:7:0)
[03:55:24.721] Timer tick, asking Buildbucket for the build status
[03:55:24.911] Build 8860516754997615984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:24.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:8:0) after 1m0s
[03:56:06.212] Received PubSub notification, asking Buildbucket for the build status
[03:56:06.312] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:24.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:8:0)
[03:56:24.930] Timer tick, asking Buildbucket for the build status
[03:56:25.065] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:25.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:10:0) after 1m0s
[03:57:25.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:10:0)
[03:57:25.086] Timer tick, asking Buildbucket for the build status
[03:57:25.179] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:25.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:11:0) after 1m0s
[03:58:25.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:11:0)
[03:58:25.250] Timer tick, asking Buildbucket for the build status
[03:58:25.303] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:25.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:12:0) after 1m0s
[03:59:25.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:12:0)
[03:59:25.322] Timer tick, asking Buildbucket for the build status
[03:59:25.458] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:25.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:13:0) after 1m0s
[04:00:25.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:13:0)
[04:00:25.487] Timer tick, asking Buildbucket for the build status
[04:00:25.615] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:25.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:14:0) after 1m0s
[04:01:25.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:14:0)
[04:01:25.638] Timer tick, asking Buildbucket for the build status
[04:01:25.755] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:25.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:15:0) after 1m0s
[04:02:25.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:15:0)
[04:02:25.779] Timer tick, asking Buildbucket for the build status
[04:02:26.092] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:26.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:16:0) after 1m0s
[04:03:26.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:16:0)
[04:03:26.117] Timer tick, asking Buildbucket for the build status
[04:03:26.312] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:26.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:17:0) after 1m0s
[04:04:26.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:17:0)
[04:04:26.334] Timer tick, asking Buildbucket for the build status
[04:04:26.724] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:26.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:18:0) after 1m0s
[04:05:26.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:18:0)
[04:05:26.748] Timer tick, asking Buildbucket for the build status
[04:05:26.833] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:26.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:19:0) after 1m0s
[04:06:26.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:19:0)
[04:06:26.823] Timer tick, asking Buildbucket for the build status
[04:06:26.935] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:26.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:20:0) after 1m0s
[04:07:26.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:20:0)
[04:07:26.960] Timer tick, asking Buildbucket for the build status
[04:07:27.193] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:27.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:21:0) after 1m0s
[04:08:27.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:21:0)
[04:08:27.322] Timer tick, asking Buildbucket for the build status
[04:08:27.430] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:27.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:22:0) after 1m0s
[04:09:27.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:22:0)
[04:09:27.550] Timer tick, asking Buildbucket for the build status
[04:09:27.975] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:27.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:23:0) after 1m0s
[04:10:28.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:23:0)
[04:10:28.188] Timer tick, asking Buildbucket for the build status
[04:10:28.251] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:28.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:24:0) after 1m0s
[04:11:28.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:24:0)
[04:11:28.467] Timer tick, asking Buildbucket for the build status
[04:11:28.534] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:28.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:25:0) after 1m0s
[04:12:28.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:25:0)
[04:12:28.667] Timer tick, asking Buildbucket for the build status
[04:12:28.777] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:28.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:26:0) after 1m0s
[04:13:28.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:26:0)
[04:13:28.905] Timer tick, asking Buildbucket for the build status
[04:13:28.968] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:28.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:27:0) after 1m0s
[04:14:29.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:27:0)
[04:14:29.073] Timer tick, asking Buildbucket for the build status
[04:14:29.317] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:29.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:28:0) after 1m0s
[04:15:29.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:28:0)
[04:15:29.320] Timer tick, asking Buildbucket for the build status
[04:15:29.636] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:29.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:29:0) after 1m0s
[04:16:29.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:29:0)
[04:16:29.721] Timer tick, asking Buildbucket for the build status
[04:16:29.789] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:29.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:30:0) after 1m0s
[04:17:29.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:30:0)
[04:17:29.836] Timer tick, asking Buildbucket for the build status
[04:17:29.895] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:29.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:31:0) after 1m0s
[04:18:29.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:31:0)
[04:18:29.962] Timer tick, asking Buildbucket for the build status
[04:18:30.076] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:30.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:32:0) after 1m0s
[04:19:30.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:32:0)
[04:19:30.107] Timer tick, asking Buildbucket for the build status
[04:19:30.196] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:30.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:33:0) after 1m0s
[04:20:30.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:33:0)
[04:20:30.222] Timer tick, asking Buildbucket for the build status
[04:20:30.287] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:30.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:34:0) after 1m0s
[04:21:30.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:34:0)
[04:21:30.359] Timer tick, asking Buildbucket for the build status
[04:21:30.624] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:30.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:35:0) after 1m0s
[04:22:30.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:35:0)
[04:22:30.706] Timer tick, asking Buildbucket for the build status
[04:22:30.751] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:30.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:36:0) after 1m0s
[04:23:30.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:36:0)
[04:23:30.754] Timer tick, asking Buildbucket for the build status
[04:23:30.820] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:30.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:37:0) after 1m0s
[04:24:30.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:37:0)
[04:24:30.889] Timer tick, asking Buildbucket for the build status
[04:24:31.017] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:31.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:38:0) after 1m0s
[04:25:31.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:38:0)
[04:25:31.038] Timer tick, asking Buildbucket for the build status
[04:25:31.105] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:31.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:39:0) after 1m0s
[04:26:31.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:39:0)
[04:26:31.156] Timer tick, asking Buildbucket for the build status
[04:26:31.303] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:31.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:40:0) after 1m0s
[04:27:31.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:40:0)
[04:27:31.330] Timer tick, asking Buildbucket for the build status
[04:27:31.526] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:31.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:41:0) after 1m0s
[04:28:31.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:41:0)
[04:28:31.584] Timer tick, asking Buildbucket for the build status
[04:28:31.664] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:31.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:42:0) after 1m0s
[04:29:31.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:42:0)
[04:29:31.685] Timer tick, asking Buildbucket for the build status
[04:29:31.779] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:31.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:43:0) after 1m0s
[04:30:31.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:43:0)
[04:30:31.878] Timer tick, asking Buildbucket for the build status
[04:30:31.930] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:31.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:44:0) after 1m0s
[04:31:31.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:44:0)
[04:31:31.970] Timer tick, asking Buildbucket for the build status
[04:31:32.328] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:32.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:45:0) after 1m0s
[04:32:32.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:45:0)
[04:32:32.379] Timer tick, asking Buildbucket for the build status
[04:32:32.475] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:32.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:46:0) after 1m0s
[04:33:32.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:46:0)
[04:33:32.500] Timer tick, asking Buildbucket for the build status
[04:33:32.547] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:32.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:47:0) after 1m0s
[04:34:32.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:47:0)
[04:34:32.608] Timer tick, asking Buildbucket for the build status
[04:34:32.782] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:32.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:48:0) after 1m0s
[04:35:32.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:48:0)
[04:35:32.855] Timer tick, asking Buildbucket for the build status
[04:35:32.923] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:32.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:49:0) after 1m0s
[04:36:32.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:49:0)
[04:36:32.945] Timer tick, asking Buildbucket for the build status
[04:36:33.115] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:33.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:50:0) after 1m0s
[04:37:33.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:50:0)
[04:37:33.136] Timer tick, asking Buildbucket for the build status
[04:37:33.234] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:33.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:51:0) after 1m0s
[04:38:33.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:51:0)
[04:38:33.267] Timer tick, asking Buildbucket for the build status
[04:38:33.386] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:33.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:52:0) after 1m0s
[04:39:33.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:52:0)
[04:39:33.409] Timer tick, asking Buildbucket for the build status
[04:39:33.518] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:33.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:53:0) after 1m0s
[04:40:33.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:53:0)
[04:40:33.580] Timer tick, asking Buildbucket for the build status
[04:40:33.712] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:33.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:54:0) after 1m0s
[04:41:33.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:54:0)
[04:41:33.769] Timer tick, asking Buildbucket for the build status
[04:41:33.837] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:33.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:55:0) after 1m0s
[04:42:33.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:55:0)
[04:42:33.919] Timer tick, asking Buildbucket for the build status
[04:42:34.106] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:34.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:56:0) after 1m0s
[04:43:34.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:56:0)
[04:43:34.304] Timer tick, asking Buildbucket for the build status
[04:43:34.433] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:34.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:57:0) after 1m0s
[04:44:34.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:57:0)
[04:44:34.566] Timer tick, asking Buildbucket for the build status
[04:44:35.354] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:35.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:58:0) after 1m0s
[04:45:35.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:58:0)
[04:45:35.525] Timer tick, asking Buildbucket for the build status
[04:45:35.814] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:35.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:59:0) after 1m0s
[04:46:35.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:59:0)
[04:46:35.870] Timer tick, asking Buildbucket for the build status
[04:46:36.049] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:36.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:60:0) after 1m0s
[04:47:36.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:60:0)
[04:47:36.185] Timer tick, asking Buildbucket for the build status
[04:47:36.525] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:36.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:61:0) after 1m0s
[04:48:36.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:61:0)
[04:48:36.536] Timer tick, asking Buildbucket for the build status
[04:48:37.128] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:37.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:62:0) after 1m0s
[04:49:37.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:62:0)
[04:49:37.155] Timer tick, asking Buildbucket for the build status
[04:49:37.278] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:37.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:63:0) after 1m0s
[04:50:37.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:63:0)
[04:50:37.588] Timer tick, asking Buildbucket for the build status
[04:50:37.696] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:37.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:64:0) after 1m0s
[04:51:37.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:64:0)
[04:51:37.752] Timer tick, asking Buildbucket for the build status
[04:51:37.827] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:37.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:65:0) after 1m0s
[04:52:37.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:65:0)
[04:52:37.926] Timer tick, asking Buildbucket for the build status
[04:52:37.983] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:37.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:66:0) after 1m0s
[04:53:38.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:66:0)
[04:53:38.005] Timer tick, asking Buildbucket for the build status
[04:53:38.064] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:38.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:67:0) after 1m0s
[04:54:38.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:67:0)
[04:54:38.103] Timer tick, asking Buildbucket for the build status
[04:54:38.410] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:38.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:68:0) after 1m0s
[04:55:38.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:68:0)
[04:55:38.468] Timer tick, asking Buildbucket for the build status
[04:55:38.581] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:38.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:69:0) after 1m0s
[04:56:38.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:69:0)
[04:56:38.972] Timer tick, asking Buildbucket for the build status
[04:56:39.035] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:39.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:70:0) after 1m0s
[04:57:39.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:70:0)
[04:57:39.176] Timer tick, asking Buildbucket for the build status
[04:57:39.248] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:39.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:71:0) after 1m0s
[04:58:41.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:71:0)
[04:58:41.166] Timer tick, asking Buildbucket for the build status
[04:58:41.255] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:41.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:72:0) after 1m0s
[04:59:41.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:72:0)
[04:59:41.244] Timer tick, asking Buildbucket for the build status
[04:59:41.358] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:41.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:73:0) after 1m0s
[05:00:41.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:73:0)
[05:00:41.396] Timer tick, asking Buildbucket for the build status
[05:00:41.539] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:41.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:74:0) after 1m0s
[05:01:41.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:74:0)
[05:01:41.576] Timer tick, asking Buildbucket for the build status
[05:01:41.708] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:41.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:75:0) after 1m0s
[05:02:41.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:75:0)
[05:02:41.733] Timer tick, asking Buildbucket for the build status
[05:02:41.847] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:41.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:76:0) after 1m0s
[05:03:41.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:76:0)
[05:03:41.879] Timer tick, asking Buildbucket for the build status
[05:03:42.200] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:42.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:77:0) after 1m0s
[05:04:42.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:77:0)
[05:04:42.231] Timer tick, asking Buildbucket for the build status
[05:04:42.703] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:42.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:78:0) after 1m0s
[05:05:42.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:78:0)
[05:05:42.759] Timer tick, asking Buildbucket for the build status
[05:05:42.867] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:42.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:79:0) after 1m0s
[05:06:42.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:79:0)
[05:06:42.889] Timer tick, asking Buildbucket for the build status
[05:06:43.007] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:43.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:80:0) after 1m0s
[05:07:43.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:80:0)
[05:07:43.027] Timer tick, asking Buildbucket for the build status
[05:07:43.156] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:43.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:81:0) after 1m0s
[05:08:43.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:81:0)
[05:08:43.190] Timer tick, asking Buildbucket for the build status
[05:08:43.391] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:43.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:82:0) after 1m0s
[05:09:43.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:82:0)
[05:09:43.418] Timer tick, asking Buildbucket for the build status
[05:09:43.521] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:43.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:83:0) after 1m0s
[05:10:43.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:83:0)
[05:10:43.601] Timer tick, asking Buildbucket for the build status
[05:10:43.672] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:43.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:84:0) after 1m0s
[05:11:43.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:84:0)
[05:11:43.762] Timer tick, asking Buildbucket for the build status
[05:11:44.082] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:44.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:85:0) after 1m0s
[05:12:44.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:85:0)
[05:12:44.104] Timer tick, asking Buildbucket for the build status
[05:12:44.202] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:44.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:86:0) after 1m0s
[05:13:44.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:86:0)
[05:13:44.294] Timer tick, asking Buildbucket for the build status
[05:13:44.362] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:44.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:87:0) after 1m0s
[05:14:44.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:87:0)
[05:14:44.423] Timer tick, asking Buildbucket for the build status
[05:14:44.515] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:44.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:88:0) after 1m0s
[05:15:44.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:88:0)
[05:15:44.572] Timer tick, asking Buildbucket for the build status
[05:15:44.709] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:44.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:89:0) after 1m0s
[05:16:44.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:89:0)
[05:16:44.767] Timer tick, asking Buildbucket for the build status
[05:16:44.832] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:44.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:90:0) after 1m0s
[05:17:44.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:90:0)
[05:17:44.824] Timer tick, asking Buildbucket for the build status
[05:17:45.095] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:45.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:91:0) after 1m0s
[05:18:45.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:91:0)
[05:18:45.132] Timer tick, asking Buildbucket for the build status
[05:18:45.243] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:45.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:92:0) after 1m0s
[05:19:45.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:92:0)
[05:19:45.342] Timer tick, asking Buildbucket for the build status
[05:19:45.526] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:45.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:93:0) after 1m0s
[05:20:45.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:93:0)
[05:20:45.635] Timer tick, asking Buildbucket for the build status
[05:20:45.698] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:45.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:94:0) after 1m0s
[05:21:45.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:94:0)
[05:21:45.749] Timer tick, asking Buildbucket for the build status
[05:21:46.117] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:46.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:95:0) after 1m0s
[05:22:46.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:95:0)
[05:22:46.117] Timer tick, asking Buildbucket for the build status
[05:22:46.214] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:46.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:96:0) after 1m0s
[05:23:46.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:96:0)
[05:23:46.306] Timer tick, asking Buildbucket for the build status
[05:23:46.542] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:46.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:97:0) after 1m0s
[05:24:46.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:97:0)
[05:24:46.566] Timer tick, asking Buildbucket for the build status
[05:24:46.697] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:46.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:98:0) after 1m0s
[05:25:46.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:98:0)
[05:25:46.736] Timer tick, asking Buildbucket for the build status
[05:25:46.802] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:46.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:99:0) after 1m0s
[05:26:46.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:99:0)
[05:26:46.777] Timer tick, asking Buildbucket for the build status
[05:26:46.853] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:46.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:100:0) after 1m0s
[05:27:46.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:100:0)
[05:27:46.876] Timer tick, asking Buildbucket for the build status
[05:27:47.001] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:47.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:101:0) after 1m0s
[05:28:47.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:101:0)
[05:28:47.036] Timer tick, asking Buildbucket for the build status
[05:28:48.688] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:48.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:102:0) after 1m0s
[05:29:48.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:102:0)
[05:29:48.835] Timer tick, asking Buildbucket for the build status
[05:29:48.952] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:48.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:103:0) after 1m0s
[05:30:48.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:103:0)
[05:30:48.984] Timer tick, asking Buildbucket for the build status
[05:30:49.063] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:49.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:104:0) after 1m0s
[05:31:49.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:104:0)
[05:31:49.087] Timer tick, asking Buildbucket for the build status
[05:31:49.206] Build 8860516754997615984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:49.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9025946817240996624:105:0) after 1m0s
[05:32:11.541] Received PubSub notification, asking Buildbucket for the build status
[05:32:12.424] Build 8860516754997615984: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:32:12.424] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608355930471926",
"created_by": "project:chromium",
"created_ts": "1608349762879460",
"failure_reason": "BUILD_FAILURE",
"id": "8860516754997615984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d8c3b498d5d1763d53cfcceea7b47c1603e4230\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- UnifiedMessageCenterViewTest.StackingCounterNotificationRemoval\\n\\n**content_browsertests** failed because of:\\n\\n- AccessibilityActionBrowserTest.DoDefaultActionFocusesContentEditable\\n\\n- AccessibilityActionBrowserTest.IncrementDecrementActions\\n\\n- AccessibilityFullscreenBrowserTest.IgnoreElementsOutsideFullscreenElement\\n\\n- AccessibilityCanvasActionBrowserTest.CanvasGetImage\\n\\n- ...11 more failure(s) (15 total)...\"}, \"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\": \"4d8c3b498d5d1763d53cfcceea7b47c1603e4230\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-51-nr8p\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21818, \"got_angle_revision\": \"011e0ad18679541b98b1d83bd86fd70bc7ea5664\", \"got_dawn_revision\": \"5d4fd88a7403aa255d49919d011af54c691b344a\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"4d8c3b498d5d1763d53cfcceea7b47c1603e4230\", \"got_revision_cp\": \"refs/heads/master@{#838500}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"f841b934b402f97c1b88f08c8bcb6c0dac13f0d3\", \"got_v8_revision_cp\": \"refs/heads/8.9.175@{#1}\", \"got_webrtc_revision\": \"d7f750f7be4b2f61018d3cb55a995d09daa226e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32862}\", \"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\": \"4d8c3b498d5d1763d53cfcceea7b47c1603e4230\", \"swarm_hashes_refs/heads/master(at){#838500}_without_patch\": {\"absl_hardening_tests\": \"3c07a26375e2bf58db59686f1be3fa241e73e2b8\", \"accessibility_unittests\": \"c3c04affe072335da9e61b141fcf733db0b5c5ea\", \"angle_unittests\": \"6360ef8506c316b25c50b38027aa5d81222fc5ea\", \"app_list_unittests\": \"03c165df185e4cf09e5592331fb87f9fae359029\", \"app_shell_unittests\": \"782d79cee290b30b570e570cdcae9dbab6afbc66\", \"ash_unittests\": \"2fd0ddbdd763787cf2f7e345ae24adaf8d24439a\", \"aura_unittests\": \"0a8b51c9284f6df8f7f943a4214e4d1f652050dd\", \"base_unittests\": \"f028d2510c710fbfdf84c973335fd78b3b31ae2d\", \"base_util_unittests\": \"017c15667d9db5e12c824f558c4e0aa66c19d9e2\", \"blink_common_unittests\": \"9fe1cdc6ed842e498a00f68db01fb1cd9e9593a0\", \"blink_fuzzer_unittests\": \"d5215b1033648855fa7a96067bd5f43694031211\", \"blink_heap_unittests\": \"ab2c037eee22797b9b93822f9bc06aa2127be129\", \"blink_platform_unittests\": \"9f46482455294a4739ef1579ce5bcd79cceae133\", \"blink_unittests\": \"76b97269eccca7e7eb98aef4e920665738cb90d6\", \"boringssl_crypto_tests\": \"03606d58bafd1e26caf0ce9e7a625b9b8198d4b3\", \"boringssl_ssl_tests\": \"330e4982ad7e046f7e4240df8c7323085f85b065\", \"browser_tests\": \"6a63827253a6e6c02ca69c4cab4c11cea34ed1cb\", \"capture_unittests\": \"91b763c9ee6440a88518480f53f91cc9139551ca\", \"cast_unittests\": \"25f55eb1462da2d2c5738a68f9589c4cf23564a8\", \"cc_unittests\": \"a107d10974e1c9ff2e468b46470d3909853ac829\", \"chrome_app_unittests\": \"2669f8f39bc8f952fb63cee98cb64dcdfbdea647\", \"chromedriver_unittests\": \"9fb6d55d1519b3e3821d9275bfbd5715952ab982\", \"chromeos_components_unittests\": \"d9fdc4c6008df8404b08b85bff49d193d37aeb2e\", \"chromeos_unittests\": \"1cc60b45ee060ac0b3d2b9e1a6c220e8b3fc2e9a\", \"components_browsertests\": \"64eeb0d539bb655d3d996bb787d21d3d15e4a56b\", \"components_unittests\": \"5e62f7427c85052d7ba5eb1cf4de8927c82699f4\", \"compositor_unittests\": \"6cac611b0eed1fe35827d66ef562d75552da5d90\", \"content_browsertests\": \"b97d876522372eb7cd1d526c01e51a4b4eb304a5\", \"content_unittests\": \"a4936a664313031d24fc8a1e03aaca2d18baaa2e\", \"crashpad_tests\": \"05fd03d7cb479c338d7dfbdaa417f4c7a7dad694\", \"crypto_unittests\": \"da64b068d815436fa345bc6b09697d369f612931\", \"dbus_unittests\": \"a89649246a405fc17df08597f6ceefb5200113f9\", \"device_unittests\": \"81c8e2fd7fa2b1afc8520379b47caa483f48cc08\", \"display_unittests\": \"33727da73bc33ad4d44e9545ddd039bb7eac9602\", \"events_unittests\": \"4e7913e27f121150c5374a2531ee74244af4c6a1\", \"exo_unittests\": \"d17c60d25616f8f99e51793fe149fc2426f4794c\", \"extensions_browsertests\": \"161a04b3bdae254fabb2ef16c9300dd162fd87cf\", \"extensions_unittests\": \"2fa0e3a966b7d0634d39b94d5b8f37a2b3807da5\", \"filesystem_service_unittests\": \"63f304509920946d1a6c794e632587d591a4fc85\", \"gcm_unit_tests\": \"e277627e123b8088b3a6bca506d9b4abb187ddf7\", \"gfx_unittests\": \"e983c2696c896b9ac25c84112eaa9e64c7b08e35\", \"gin_unittests\": \"f7a59156d17bc67b2827ffb074d655b2a67fa58a\", \"gl_unittests_ozone\": \"0d38c1f5fed0460b558a02f9707f944e4b7f1f92\", \"google_apis_unittests\": \"f620eb831b2f1da21b785c4b6607db86a1690546\", \"gpu_unittests\": \"e1606c271502bd4fc219bbede165636715a25b54\", \"gwp_asan_unittests\": \"5661f9239a4f63beb0150f7aa9835c5c66baea50\", \"interactive_ui_tests\": \"0894bc915b1a786d4b939bd34068ffa657c34d66\", \"ipc_tests\": \"255a009f87353b80e9f185f816c17d9766273e19\", \"jingle_unittests\": \"ad20ee2bdd0de1bd14d6838a6dfafa40206db223\", \"keyboard_unittests\": \"2253b82526c7bf854355f3237e01bf2dcfb6a3b9\", \"latency_unittests\": \"b1a2e51298c7659296aa19efe9459175714a2fc3\", \"libjingle_xmpp_unittests\": \"75b45e8acb63c49ae3b3a3d5eeafa1454bd9dd05\", \"liburlpattern_unittests\": \"83b388732d908fd8e97c61661ea6ee8164a2b9bb\", \"media_blink_unittests\": \"02b87b2917c7f8d2b05a8b4ce884d799cddfae0d\", \"media_unittests\": \"97c2b244458b599967d4dc1bd49e392af491ced9\", \"message_center_unittests\": \"1b845e73c4ba1c169f649014d35d856375c1820e\", \"midi_unittests\": \"f3217b52c3a5443bfa2f7ddde880c5a49a008ed2\", \"mojo_core_unittests\": \"b0c611e95a61e1fb821cf1dd779099e720d3631d\", \"mojo_unittests\": \"b437b1c7cb0990fe5326f3cbba974e7494709060\", \"nacl_helper_nonsfi_unittests\": \"9703089717d7a14c9851043bedd7ba9ff4a76526\", \"nacl_loader_unittests\": \"eef45658be41030894b0da20b4481c3697e306f9\", \"native_theme_unittests\": \"89d0be8cdd847cfdc32f0d5ab155d3b43b45f576\", \"net_unittests\": \"26a884ae03fa3409f8f26e11d35b909bd0a3b543\", \"ozone_gl_unittests\": \"9f5fe1f51d333f2dde89fa7fd7c9e461b9ac59bb\", \"ozone_unittests\": \"e45d4e94927c449468b32e9bf60370ec91bd7a9d\", \"ozone_x11_unittests\": \"06a439bc5f9f51b2c35e3637944a7888af7e3710\", \"pdf_unittests\": \"c3e235b2dae61d3028d6f561f7a032653d0dde0d\", \"perfetto_unittests\": \"2efc9d8a527af8c28e3e3819c9b74b7ddb493c48\", \"ppapi_unittests\": \"448f6e1919f133ad991666caca2736f3f5477130\", \"printing_unittests\": \"e84217b993790f011f6660105697df8467be08ff\", \"remoting_unittests\": \"a88319fb3acd0c40721c1c6b74afd45afe08fe0a\", \"sandbox_linux_unittests\": \"c3022a467c047e604a77bece7e79ee2d019d46e1\", \"service_manager_unittests\": \"f3050677bce0b27c2c98864c08aeecf623f6384c\", \"services_unittests\": \"019cec7e4e29f66939da968b1899bb26ced69fd4\", \"shell_dialogs_unittests\": \"b85ccac78bba2ffaf93d2b3af6f052d5831c5f2d\", \"shell_encryption_unittests\": \"a214519808f177f91d0383e66e46b6e832b00178\", \"skia_unittests\": \"93d29b1f883a96f2e1af4b14e40c10109723d0e5\", \"snapshot_unittests\": \"152b10eed440d555ff8540fb6fa69dafa89eb411\", \"sql_unittests\": \"ce4eb563e4df22a381361491b6e176ef0d63bf55\", \"storage_unittests\": \"15017e81405af00d98e5614505b81d4df20a9136\", \"sync_integration_tests\": \"e6d2262bd6680767f2dc16a46dbb8da37a0aa5f7\", \"traffic_annotation_auditor_unittests\": \"01bc3f4a0a55225baf837bd8f2b87885ab2dfea4\", \"ui_base_unittests\": \"b00db02d54462fbae502938fc20231abff57fb2e\", \"ui_chromeos_unittests\": \"6d3551cb619a69a6911e7d8c3c3f694468a8962c\", \"ui_touch_selection_unittests\": \"65bd9e6aa95fbdfcef5b2d9cd0728bbd89238d8a\", \"unit_tests\": \"eec31266da9df8d709bcc4465e441978f0e0534e\", \"url_unittests\": \"3bc29c238f1120ccaaf5fc0c559a168f6f23c758\", \"usage_time_limit_unittests\": \"6e610189f7a12ce50f39c7ab9c5288dbf8a3d792\", \"views_unittests\": \"7dac3ff83a7dd654f23d4b710b95c7242cd484d0\", \"viz_unittests\": \"29fb67c267afbf77d0896ac4ca40266cc1c16b22\", \"wm_unittests\": \"8e1cbe9ddeba46d895c695b5ffa5bccf09842f2c\", \"wtf_unittests\": \"e02332066cdc4da91306e84f252059c9aa8e9aed\", \"zlib_unittests\": \"3d8b4703acbdfbc26ed494cc07c9eab17d362cc6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_9f02bd2de6eac19c457931accbc0b1e66d92a355209524fd30dfc8de3911a1f0_v2\", \"builder_a038fb555685f584149318062f53e0831a488e02c6d40fe1e156bb7cef46d2c7_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-51-nr8p\"], \"image\": [\"chrome-xenial-20121300-d43316a0aad\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- UnifiedMessageCenterViewTest.StackingCounterNotificationRemoval\\n\\n**content_browsertests** failed because of:\\n\\n- AccessibilityActionBrowserTest.DoDefaultActionFocusesContentEditable\\n\\n- AccessibilityActionBrowserTest.IncrementDecrementActions\\n\\n- AccessibilityFullscreenBrowserTest.IgnoreElementsOutsideFullscreenElement\\n\\n- AccessibilityCanvasActionBrowserTest.CanvasGetImage\\n\\n- ...11 more failure(s) (15 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608350164664200",
"status": "COMPLETED",
"status_changed_ts": "1608350166014851",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21818",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4d8c3b498d5d1763d53cfcceea7b47c1603e4230",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d8c3b498d5d1763d53cfcceea7b47c1603e4230",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025946817240996624",
"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/8860516754997615984/+/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:5091ef50a6ccaa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608355930725378",
"url": "https://ci.chromium.org/b/8860516754997615984",
"utcnow_ts": "1608355931699869"
}
}
[05:32:12.424] Invocation finished in 1h42m51.067465446s with status FAILED