[19:08:42.939] New invocation is queued and will start shortly
[19:08:44.154] Starting the invocation (attempt 1)
[19:08:44.188] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:08:44.188] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:08:44.188] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018097637034996688",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9018097637034996688",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d",
"gitiles_ref:refs/heads/master"
]
}
[19:08:46.624] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615835324937477",
"id": "8852667574277378720",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615835326352133",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22995",
"builder:linux-chromeos-dbg",
"buildset:commit/git/7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018097637034996688",
"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/8852667574277378720/+/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": "1615835326352528",
"url": "https://ci.chromium.org/b/8852667574277378720",
"utcnow_ts": "1615835326607237"
}
}
[19:08:46.624] Task URL: https://ci.chromium.org/b/8852667574277378720
[19:08:46.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:2:0) after 1m0s
[19:08:53.429] Received PubSub notification, asking Buildbucket for the build status
[19:08:53.498] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:46.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:2:0)
[19:09:46.774] Build status: STARTED
[19:09:46.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:4:0) after 2m20s
[19:12:06.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:4:0)
[19:12:06.834] Timer tick, asking Buildbucket for the build status
[19:12:07.235] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:07.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:5:0) after 1m0s
[19:13:07.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:5:0)
[19:13:07.492] Timer tick, asking Buildbucket for the build status
[19:13:08.375] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:08.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:6:0) after 1m0s
[19:14:08.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:6:0)
[19:14:08.574] Timer tick, asking Buildbucket for the build status
[19:14:08.689] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:08.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:7:0) after 1m0s
[19:15:10.063] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:7:0)
[19:15:10.063] Timer tick, asking Buildbucket for the build status
[19:15:10.154] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:10.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:8:0) after 1m0s
[19:16:10.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:8:0)
[19:16:10.311] Timer tick, asking Buildbucket for the build status
[19:16:11.808] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:11.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:9:0) after 1m0s
[19:17:11.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:9:0)
[19:17:11.811] Timer tick, asking Buildbucket for the build status
[19:17:11.884] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:11.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:10:0) after 1m0s
[19:18:12.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:10:0)
[19:18:12.091] Timer tick, asking Buildbucket for the build status
[19:18:12.157] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:12.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:11:0) after 1m0s
[19:19:12.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:11:0)
[19:19:12.319] Timer tick, asking Buildbucket for the build status
[19:19:12.448] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:12.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:12:0) after 1m0s
[19:20:12.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:12:0)
[19:20:12.540] Timer tick, asking Buildbucket for the build status
[19:20:13.746] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:13.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:13:0) after 1m0s
[19:21:13.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:13:0)
[19:21:13.833] Timer tick, asking Buildbucket for the build status
[19:21:15.047] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:15.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:14:0) after 1m0s
[19:22:15.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:14:0)
[19:22:15.399] Timer tick, asking Buildbucket for the build status
[19:22:17.062] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:17.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:15:0) after 1m0s
[19:23:17.202] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:15:0)
[19:23:17.202] Timer tick, asking Buildbucket for the build status
[19:23:17.297] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:17.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:16:0) after 1m0s
[19:24:17.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:16:0)
[19:24:17.337] Timer tick, asking Buildbucket for the build status
[19:24:17.438] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:17.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:17:0) after 1m0s
[19:25:17.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:17:0)
[19:25:17.457] Timer tick, asking Buildbucket for the build status
[19:25:17.530] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:17.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:18:0) after 1m0s
[19:26:17.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:18:0)
[19:26:17.612] Timer tick, asking Buildbucket for the build status
[19:26:17.696] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:17.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:19:0) after 1m0s
[19:27:18.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:19:0)
[19:27:18.002] Timer tick, asking Buildbucket for the build status
[19:27:18.115] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:18.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:20:0) after 1m0s
[19:28:18.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:20:0)
[19:28:18.103] Timer tick, asking Buildbucket for the build status
[19:28:18.245] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:18.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:21:0) after 1m0s
[19:29:18.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:21:0)
[19:29:18.646] Timer tick, asking Buildbucket for the build status
[19:29:18.749] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:18.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:22:0) after 1m0s
[19:30:18.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:22:0)
[19:30:18.771] Timer tick, asking Buildbucket for the build status
[19:30:18.883] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:18.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:23:0) after 1m0s
[19:31:19.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:23:0)
[19:31:19.560] Timer tick, asking Buildbucket for the build status
[19:31:19.638] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:19.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:24:0) after 1m0s
[19:32:19.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:24:0)
[19:32:19.790] Timer tick, asking Buildbucket for the build status
[19:32:20.412] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:20.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:25:0) after 1m0s
[19:33:20.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:25:0)
[19:33:20.487] Timer tick, asking Buildbucket for the build status
[19:33:20.563] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:20.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:26:0) after 1m0s
[19:34:20.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:26:0)
[19:34:20.593] Timer tick, asking Buildbucket for the build status
[19:34:20.761] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:20.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:27:0) after 1m0s
[19:35:20.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:27:0)
[19:35:20.744] Timer tick, asking Buildbucket for the build status
[19:35:20.878] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:20.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:28:0) after 1m0s
[19:36:20.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:28:0)
[19:36:20.915] Timer tick, asking Buildbucket for the build status
[19:36:21.451] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:21.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:29:0) after 1m0s
[19:37:21.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:29:0)
[19:37:21.567] Timer tick, asking Buildbucket for the build status
[19:37:21.819] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:21.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:30:0) after 1m0s
[19:38:21.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:30:0)
[19:38:21.874] Timer tick, asking Buildbucket for the build status
[19:38:22.092] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:22.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:31:0) after 1m0s
[19:39:22.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:31:0)
[19:39:22.509] Timer tick, asking Buildbucket for the build status
[19:39:22.608] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:22.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:32:0) after 1m0s
[19:40:22.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:32:0)
[19:40:22.757] Timer tick, asking Buildbucket for the build status
[19:40:22.832] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:22.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:33:0) after 1m0s
[19:41:22.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:33:0)
[19:41:22.873] Timer tick, asking Buildbucket for the build status
[19:41:22.973] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:22.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:34:0) after 1m0s
[19:42:23.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:34:0)
[19:42:23.016] Timer tick, asking Buildbucket for the build status
[19:42:23.102] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:23.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:35:0) after 1m0s
[19:43:23.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:35:0)
[19:43:23.148] Timer tick, asking Buildbucket for the build status
[19:43:23.222] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:23.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:36:0) after 1m0s
[19:44:23.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:36:0)
[19:44:23.295] Timer tick, asking Buildbucket for the build status
[19:44:23.456] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:23.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:37:0) after 1m0s
[19:45:23.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:37:0)
[19:45:23.496] Timer tick, asking Buildbucket for the build status
[19:45:23.574] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:23.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:38:0) after 1m0s
[19:46:23.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:38:0)
[19:46:23.608] Timer tick, asking Buildbucket for the build status
[19:46:23.859] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:23.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:39:0) after 1m0s
[19:47:23.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:39:0)
[19:47:23.872] Timer tick, asking Buildbucket for the build status
[19:47:24.249] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:24.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:40:0) after 1m0s
[19:48:24.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:40:0)
[19:48:24.336] Timer tick, asking Buildbucket for the build status
[19:48:24.411] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:24.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:41:0) after 1m0s
[19:49:24.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:41:0)
[19:49:24.409] Timer tick, asking Buildbucket for the build status
[19:49:25.317] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:25.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:42:0) after 1m0s
[19:50:25.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:42:0)
[19:50:25.370] Timer tick, asking Buildbucket for the build status
[19:50:27.582] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:27.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:43:0) after 1m0s
[19:51:27.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:43:0)
[19:51:27.620] Timer tick, asking Buildbucket for the build status
[19:51:27.718] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:27.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:44:0) after 1m0s
[19:52:27.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:44:0)
[19:52:27.739] Timer tick, asking Buildbucket for the build status
[19:52:27.855] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:27.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:45:0) after 1m0s
[19:53:27.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:45:0)
[19:53:27.909] Timer tick, asking Buildbucket for the build status
[19:53:28.356] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:28.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:46:0) after 1m0s
[19:54:28.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:46:0)
[19:54:28.365] Timer tick, asking Buildbucket for the build status
[19:54:28.465] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:28.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:47:0) after 1m0s
[19:55:28.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:47:0)
[19:55:28.496] Timer tick, asking Buildbucket for the build status
[19:55:28.599] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:28.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:48:0) after 1m0s
[19:56:28.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:48:0)
[19:56:28.624] Timer tick, asking Buildbucket for the build status
[19:56:28.742] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:28.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:49:0) after 1m0s
[19:57:28.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:49:0)
[19:57:28.763] Timer tick, asking Buildbucket for the build status
[19:57:28.846] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:28.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:50:0) after 1m0s
[19:58:29.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:50:0)
[19:58:29.003] Timer tick, asking Buildbucket for the build status
[19:58:29.082] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:29.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:51:0) after 1m0s
[19:59:29.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:51:0)
[19:59:29.551] Timer tick, asking Buildbucket for the build status
[19:59:29.720] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:29.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:52:0) after 1m0s
[20:00:29.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:52:0)
[20:00:29.886] Timer tick, asking Buildbucket for the build status
[20:00:29.999] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:29.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:53:0) after 1m0s
[20:01:30.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:53:0)
[20:01:30.351] Timer tick, asking Buildbucket for the build status
[20:01:30.495] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:30.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:54:0) after 1m0s
[20:02:30.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:54:0)
[20:02:30.897] Timer tick, asking Buildbucket for the build status
[20:02:31.046] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:31.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:55:0) after 1m0s
[20:03:31.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:55:0)
[20:03:31.141] Timer tick, asking Buildbucket for the build status
[20:03:31.807] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:31.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:56:0) after 1m0s
[20:04:32.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:56:0)
[20:04:32.100] Timer tick, asking Buildbucket for the build status
[20:04:32.282] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:32.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:57:0) after 1m0s
[20:05:32.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:57:0)
[20:05:32.687] Timer tick, asking Buildbucket for the build status
[20:05:32.768] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:32.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:58:0) after 1m0s
[20:06:33.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:58:0)
[20:06:33.194] Timer tick, asking Buildbucket for the build status
[20:06:33.493] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:33.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:59:0) after 1m0s
[20:07:34.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:59:0)
[20:07:34.321] Timer tick, asking Buildbucket for the build status
[20:07:34.394] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:34.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:60:0) after 1m0s
[20:08:34.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:60:0)
[20:08:34.499] Timer tick, asking Buildbucket for the build status
[20:08:34.706] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:34.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:61:0) after 1m0s
[20:09:34.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:61:0)
[20:09:34.861] Timer tick, asking Buildbucket for the build status
[20:09:35.252] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:35.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:62:0) after 1m0s
[20:10:35.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:62:0)
[20:10:35.302] Timer tick, asking Buildbucket for the build status
[20:10:35.635] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:35.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:63:0) after 1m0s
[20:11:36.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:63:0)
[20:11:36.639] Timer tick, asking Buildbucket for the build status
[20:11:36.968] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:36.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:64:0) after 1m0s
[20:12:36.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:64:0)
[20:12:36.999] Timer tick, asking Buildbucket for the build status
[20:12:37.125] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:37.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:65:0) after 1m0s
[20:13:37.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:65:0)
[20:13:37.191] Timer tick, asking Buildbucket for the build status
[20:13:37.299] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:37.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:66:0) after 1m0s
[20:14:37.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:66:0)
[20:14:37.330] Timer tick, asking Buildbucket for the build status
[20:14:37.443] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:37.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:67:0) after 1m0s
[20:15:38.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:67:0)
[20:15:38.321] Timer tick, asking Buildbucket for the build status
[20:15:38.511] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:38.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:68:0) after 1m0s
[20:16:38.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:68:0)
[20:16:38.550] Timer tick, asking Buildbucket for the build status
[20:16:38.638] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:38.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:69:0) after 1m0s
[20:17:38.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:69:0)
[20:17:38.667] Timer tick, asking Buildbucket for the build status
[20:17:38.771] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:38.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:70:0) after 1m0s
[20:18:39.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:70:0)
[20:18:39.363] Timer tick, asking Buildbucket for the build status
[20:18:39.736] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:39.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:71:0) after 1m0s
[20:19:40.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:71:0)
[20:19:40.381] Timer tick, asking Buildbucket for the build status
[20:19:40.540] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:40.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:72:0) after 1m0s
[20:20:40.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:72:0)
[20:20:40.825] Timer tick, asking Buildbucket for the build status
[20:20:40.933] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:40.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:73:0) after 1m0s
[20:21:41.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:73:0)
[20:21:41.461] Timer tick, asking Buildbucket for the build status
[20:21:51.156] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:51.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:74:0) after 1m0s
[20:22:51.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:74:0)
[20:22:51.849] Timer tick, asking Buildbucket for the build status
[20:22:51.936] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:51.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:75:0) after 1m0s
[20:23:52.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:75:0)
[20:23:52.990] Timer tick, asking Buildbucket for the build status
[20:23:53.968] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:53.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:76:0) after 1m0s
[20:24:53.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:76:0)
[20:24:53.991] Timer tick, asking Buildbucket for the build status
[20:24:54.113] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:54.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:77:0) after 1m0s
[20:25:54.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:77:0)
[20:25:54.314] Timer tick, asking Buildbucket for the build status
[20:25:54.434] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:54.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:78:0) after 1m0s
[20:26:54.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:78:0)
[20:26:54.486] Timer tick, asking Buildbucket for the build status
[20:26:54.638] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:54.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:79:0) after 1m0s
[20:27:54.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:79:0)
[20:27:54.813] Timer tick, asking Buildbucket for the build status
[20:27:55.342] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:55.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:80:0) after 1m0s
[20:28:55.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:80:0)
[20:28:55.390] Timer tick, asking Buildbucket for the build status
[20:28:55.635] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:55.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:81:0) after 1m0s
[20:29:55.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:81:0)
[20:29:55.732] Timer tick, asking Buildbucket for the build status
[20:29:56.158] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:56.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:82:0) after 1m0s
[20:30:56.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:82:0)
[20:30:56.238] Timer tick, asking Buildbucket for the build status
[20:30:56.527] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:56.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:83:0) after 1m0s
[20:31:56.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:83:0)
[20:31:56.549] Timer tick, asking Buildbucket for the build status
[20:31:57.886] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:57.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:84:0) after 1m0s
[20:32:57.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:84:0)
[20:32:57.896] Timer tick, asking Buildbucket for the build status
[20:32:58.113] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:58.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:85:0) after 1m0s
[20:33:58.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:85:0)
[20:33:58.131] Timer tick, asking Buildbucket for the build status
[20:33:58.287] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:58.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:86:0) after 1m0s
[20:34:58.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:86:0)
[20:34:58.298] Timer tick, asking Buildbucket for the build status
[20:34:58.372] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:58.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:87:0) after 1m0s
[20:35:58.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:87:0)
[20:35:58.622] Timer tick, asking Buildbucket for the build status
[20:35:58.710] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:58.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:88:0) after 1m0s
[20:36:58.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:88:0)
[20:36:58.941] Timer tick, asking Buildbucket for the build status
[20:37:00.727] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:00.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:89:0) after 1m0s
[20:38:00.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:89:0)
[20:38:00.862] Timer tick, asking Buildbucket for the build status
[20:38:00.966] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:00.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:90:0) after 1m0s
[20:39:01.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:90:0)
[20:39:01.391] Timer tick, asking Buildbucket for the build status
[20:39:01.616] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:01.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:91:0) after 1m0s
[20:40:01.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:91:0)
[20:40:01.836] Timer tick, asking Buildbucket for the build status
[20:40:01.927] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:01.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:92:0) after 1m0s
[20:41:02.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:92:0)
[20:41:02.201] Timer tick, asking Buildbucket for the build status
[20:41:02.313] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:02.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:93:0) after 1m0s
[20:42:02.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:93:0)
[20:42:02.381] Timer tick, asking Buildbucket for the build status
[20:42:02.851] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:02.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:94:0) after 1m0s
[20:43:02.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:94:0)
[20:43:02.897] Timer tick, asking Buildbucket for the build status
[20:43:03.043] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:03.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:95:0) after 1m0s
[20:44:03.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:95:0)
[20:44:03.078] Timer tick, asking Buildbucket for the build status
[20:44:03.345] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:03.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:96:0) after 1m0s
[20:45:03.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:96:0)
[20:45:03.369] Timer tick, asking Buildbucket for the build status
[20:45:03.846] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:03.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:97:0) after 1m0s
[20:46:03.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:97:0)
[20:46:03.911] Timer tick, asking Buildbucket for the build status
[20:46:04.019] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:04.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:98:0) after 1m0s
[20:47:04.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:98:0)
[20:47:04.048] Timer tick, asking Buildbucket for the build status
[20:47:04.157] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:04.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:99:0) after 1m0s
[20:48:04.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:99:0)
[20:48:04.242] Timer tick, asking Buildbucket for the build status
[20:48:04.379] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:04.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:100:0) after 1m0s
[20:49:04.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:100:0)
[20:49:04.360] Timer tick, asking Buildbucket for the build status
[20:49:04.855] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:04.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:101:0) after 1m0s
[20:50:04.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:101:0)
[20:50:04.920] Timer tick, asking Buildbucket for the build status
[20:50:05.050] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:05.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:102:0) after 1m0s
[20:51:05.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:102:0)
[20:51:05.203] Timer tick, asking Buildbucket for the build status
[20:51:05.327] Build 8852667574277378720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:05.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:103:0) after 1m0s
[20:52:05.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018097637034996688:103:0)
[20:52:05.762] Timer tick, asking Buildbucket for the build status
[20:52:05.853] Build 8852667574277378720: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:52:05.853] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615841466312180",
"created_by": "project:chromium",
"created_ts": "1615835324937477",
"failure_reason": "BUILD_FAILURE",
"id": "8852667574277378720",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ContextMenuBrowserTest.CopyLinkTextTouchTextImage\\n\\n- All/LookalikeUrlNavigationThrottleBrowserTest.NonIdn_NoMatch/3\\n\\n- FingerprintUnlockTest.TimeoutIncludesSuspendedTime\\n\\n- ChromeVoxBackgroundTest.ReinsertedNodeRecovery\\n\\n- ...519 more failure(s) (523 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\": \"7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d\", \"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-12-gp6v\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22995, \"got_angle_revision\": \"a11d65a172f885042cf4fdab5bfd124d174f5190\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d\", \"got_revision_cp\": \"refs/heads/master@{#862903}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"d7ff8abb545ad8160e189a7e8352c13c7b0e30b8\", \"got_v8_revision_cp\": \"refs/heads/9.1.126@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"is_cached\": true, \"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\": \"7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d\", \"swarm_hashes_refs/heads/master(at){#862903}_without_patch\": {\"absl_hardening_tests\": \"bf657a3ba430dd2094a9ecc41c363378d34c744f\", \"accessibility_unittests\": \"c8ad48f184207db3b6a072afe247cd3fdbf10518\", \"angle_unittests\": \"74a03bed60f278d9af3eca3df981d37d1c07c180\", \"app_list_unittests\": \"a0207a53f9a50bdf849d30dd0ac2ae0ce9e6f0a4\", \"app_shell_unittests\": \"da979b4c068e1451cb4efdacdefb78b8a65ba7a8\", \"ash_unittests\": \"6921c962ae9fac2d0aa0527e55ee2cb9575e7ef2\", \"aura_unittests\": \"c1cfd260a97618613d955355f99ecb9a474c7774\", \"base_unittests\": \"e21c5c157ee708ff58da250add519e6fd10a4d29\", \"base_util_unittests\": \"86eac15707c2103335923f958a6d983ffe77fba9\", \"blink_common_unittests\": \"c761111646d033c7da6076b7d17d3730ffa2c3dc\", \"blink_fuzzer_unittests\": \"d9a93cbba13a0a572a73b7f25c067dc1f1531d78\", \"blink_heap_unittests\": \"1945599d907f6995ad4320d18719c0d8cfe3114a\", \"blink_platform_unittests\": \"8dad7d5d08c52a69689a7d68b244da2f1fc5d9a1\", \"blink_unittests\": \"89fd60cb763fe7e18906504a03eb6751372fce3c\", \"boringssl_crypto_tests\": \"b7208a4e8286688cd6c7136d0052a8c9d9b75a56\", \"boringssl_ssl_tests\": \"fa73d05fa2226b3ce012c900344b50a4304ab008\", \"browser_tests\": \"700ac5d649ad1a3029139a98faf1cfca85425d1f\", \"capture_unittests\": \"c6b05b4ba968245bbb1a7abf253689ce869c231f\", \"cast_unittests\": \"d8515bb1283f0e15407eb9bbfe09d0462f86f8cb\", \"cc_unittests\": \"30481a1ca87d4d33a731c2ecd5f15b23d3034946\", \"chrome_app_unittests\": \"9a308afae41cd89ec40ed6b85b0bb6c3132e9b1e\", \"chromedriver_unittests\": \"a2c01a1afb3ac00c28a542d6c5f44dc7cf7b7700\", \"chromeos_components_unittests\": \"0aed6d3d83f4c91a8786f26f9abdd4b805bb03cf\", \"chromeos_unittests\": \"24603d0e5a7b0912dd0ea9b50798f84c97be6d37\", \"color_unittests\": \"710583e7c771aac2b72da94c31c642742613cf5e\", \"components_browsertests\": \"d286fff1f1101294df60a39c639ee8119e24e6bd\", \"components_unittests\": \"6ae248e7a3490285ba3699861fb43a3c2ad5b9f6\", \"compositor_unittests\": \"11abedc18f09238cccf7d8c0b301c5f6eb22dc6c\", \"content_browsertests\": \"7f1c8dbd57a63485daafb8bee07738dfd3cdffc5\", \"content_unittests\": \"fb5a2311b430a319ab8b972ce54d2e581bfc11a4\", \"crashpad_tests\": \"d9637d3f24b2088e5962fc995cb58f91986ea362\", \"crypto_unittests\": \"5a85d2c524e69137cf18087906efa2a5f7ab881e\", \"dbus_unittests\": \"1943e1c1c1908d358bdbc3d3ee639f1c2fa869e6\", \"device_unittests\": \"5ef326674330bf4f6e00729aa9990b877708b46d\", \"display_unittests\": \"9f7991bfa5fc1e2e4a8715bd9ecce1d665b59086\", \"events_unittests\": \"0b4bd3f55056a1b7c74263299199b123cf746af3\", \"exo_unittests\": \"e58d09498f32bd454336a151294fee6e0bd6f4de\", \"extensions_browsertests\": \"dad7caf51f0eba3324289374855e4fccdaf7471c\", \"extensions_unittests\": \"e7cbd03e6f15194dd3dc5d897e25669bde2449c0\", \"filesystem_service_unittests\": \"e68cccd75f9aba2c78a740696ff1fdb5d7691cc4\", \"gcm_unit_tests\": \"461b2c70e355c13540985519eb96370069b2dd4f\", \"gfx_unittests\": \"6a6177108dad4334c21cec1825ec774f328c0d5d\", \"gin_unittests\": \"9cbe086f70535cc82b4702d07362a814a0211c17\", \"gl_unittests_ozone\": \"41693c1f6d335a60c09a33a6f39787af74df55c0\", \"google_apis_unittests\": \"a4d65df428aeaebe9463db0fc87aa9d61aa31fff\", \"gpu_unittests\": \"6698e2fa14024b3d32317668a9b14eddff8502c4\", \"gwp_asan_unittests\": \"aa21ac52144c7f76fdb477cc3164d881c80cb89b\", \"interactive_ui_tests\": \"b1872101850e9c268bc7bf0199c58f5cb23fba00\", \"ipc_tests\": \"c6dfeb5527e2bd0f52c1dc35ef3b54e838f2a97b\", \"jingle_unittests\": \"0b526454bd07a9e93b144517153c63bd9995f11a\", \"keyboard_unittests\": \"7197bbb5e4fa9f0b041b4bb6be1aa1dd118d02ed\", \"latency_unittests\": \"e4d0a9b98749b34f88383beeebe4eff034abbaf8\", \"libjingle_xmpp_unittests\": \"495b1b2375a62ee33943fa3195455b4ddff1ae91\", \"liburlpattern_unittests\": \"57474c21249a9d42ae4b792ecd31577d9c91599e\", \"media_blink_unittests\": \"f60073355349245d64278b64b8f78db740866554\", \"media_unittests\": \"0edcfcfa7835eb0a719fdced9c47947564b54de7\", \"message_center_unittests\": \"c34bb88f77612f0fc978286b9827103a144dc92f\", \"midi_unittests\": \"80931b8816cee973efb401b0457af53446354f21\", \"mojo_core_unittests\": \"5402edc7213dad83af8532b6843495c58a75a755\", \"mojo_unittests\": \"879989a276eea0541afba70dddf2282f43ab0dfd\", \"nacl_helper_nonsfi_unittests\": \"cc0a70817a05dc04277737ba46a9ec44487c14ac\", \"nacl_loader_unittests\": \"b792fad91233d3b6a4ee5a7fd62e893a4d2660ee\", \"native_theme_unittests\": \"d1e8772294cffc0a3220e1404a03b5746c25bf15\", \"net_unittests\": \"6c2dbce67aceb758e1ea10e1f3fd1ad28cc2352c\", \"ozone_gl_unittests\": \"a4c55b2c1fa638aad499d261bd1e3fff9e0db406\", \"ozone_unittests\": \"fd4302c1779d81e050e0a63226bde8e61fb4babf\", \"ozone_x11_unittests\": \"be90bfe1b66d5363544d3e9b3fab124c92467edc\", \"pdf_unittests\": \"d87a0906bb21e8979beb9fc21098e9290a1ea6af\", \"perfetto_unittests\": \"2f86d2a855fc8f8effa8b06ae8dc98288c438f86\", \"ppapi_unittests\": \"5e1a441f4b6c855a45ae51bc5511f92f7e4b216c\", \"printing_unittests\": \"e607ad86cfaf70c92b1ea0af469ee0ae06cb9d3f\", \"remoting_unittests\": \"91f1be874c303c7b91d56fe29f70c3ffe766e6a2\", \"sandbox_linux_unittests\": \"2228ad71ccbab690b3425ddacedcf6dbe982fc75\", \"service_manager_unittests\": \"1e28a372715880c3804ce32a52d495bd9c94701d\", \"services_unittests\": \"2a255796818068cf43138fc16c66fbf4a71e791f\", \"shell_dialogs_unittests\": \"660b5fadcd7615d1bf03176cb21ab566f5f80052\", \"shell_encryption_unittests\": \"8dc035634236e8a5404f7378d15a6ed435f5bc17\", \"skia_unittests\": \"89f0fb0ee00de7f53c338e1d6bc7373d46699de0\", \"snapshot_unittests\": \"4815343385f0205a98ac7c0f82471d056dd3e150\", \"sql_unittests\": \"6d1d434c94eb57b2dde0517765769e7fd13b7d80\", \"storage_unittests\": \"c5d978e89efb4b37be3e04b77f188ad5b74a7a70\", \"sync_integration_tests\": \"300ab8016822b8126aaf13303e521e673d512c64\", \"traffic_annotation_auditor_unittests\": \"35a7d55baf0ed22a27dbc6eedf2da00786f4e361\", \"ui_base_unittests\": \"d0076e10d914d9b534e7528a85fc3fda1f12358e\", \"ui_chromeos_unittests\": \"bd87228d324d61cbe73a2e4e17e01f204d633337\", \"ui_touch_selection_unittests\": \"aed4a698cf262fc226a146fb0b01282ba3deec22\", \"unit_tests\": \"bbbd01bc329c347ab859d11999d5ef14ff2bce60\", \"url_unittests\": \"8c12ecbefc281442db130b931d09958eb0011e50\", \"usage_time_limit_unittests\": \"b6546f53bfc6b5954bb938c814cc99943980e43e\", \"views_unittests\": \"99cb21f47d7e93c8ee7fb2ea469a4df7faea911e\", \"viz_unittests\": \"d7cb5883d7184c3f80cca23c04b7fa0a322a1f8f\", \"wayland_client_tests\": \"83f6d430c70610fd9fb2407d53b10fe2ebbdc1b5\", \"wm_unittests\": \"0c7bc541bc8e3fac672055cbf27e017743d032a1\", \"wtf_unittests\": \"dc214b1baa439ce1bded0e9ee2242d00400d3238\", \"zlib_unittests\": \"549f11b1729f0885d31b0feca53921075e5658df\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0e4559ffd6169ab721e778dfe83867ca08424e9b4d480f5c0e6f91286240fc3f_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_52b3455bc016485ac846758ed32679b3eb96461dff570695d0073fe962116473_v2\", \"builder_a3b3a06131dfa9924971b27d0acd4e18e80a2242b6d6ae41f94b770d7c996d6f_v2\", \"builder_be6033c31ad7fe993e51aceefec32c9781d9cb2aa7f3fe71df24045d7e98805a_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_v2\", \"builder_f568157a4ec00fb9568da05a054c670841713183d1f68e80f18d1b79a7a19e1f_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-12-gp6v\"], \"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\": [\"5754-8c4f760\"], \"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- ContextMenuBrowserTest.CopyLinkTextTouchTextImage\\n\\n- All/LookalikeUrlNavigationThrottleBrowserTest.NonIdn_NoMatch/3\\n\\n- FingerprintUnlockTest.TimeoutIncludesSuspendedTime\\n\\n- ChromeVoxBackgroundTest.ReinsertedNodeRecovery\\n\\n- ...519 more failure(s) (523 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615835332541694",
"status": "COMPLETED",
"status_changed_ts": "1615835332943899",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22995",
"builder:linux-chromeos-dbg",
"buildset:commit/git/7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c13dc11bd1fffdd01b8e9666f01b06ed5b1e08d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018097637034996688",
"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/8852667574277378720/+/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:52501bf22e1f8510",
"user_agent:luci-scheduler"
],
"updated_ts": "1615841466312442",
"url": "https://ci.chromium.org/b/8852667574277378720",
"utcnow_ts": "1615841525839306"
}
}
[20:52:05.853] Invocation finished in 1h43m22.929934068s with status FAILED