[16:03:19.028] New invocation is queued and will start shortly
[16:03:20.237] Starting the invocation (attempt 1)
[16:03:20.332] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:03:20.332] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:03:20.333] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023454522329965392",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9023454522329965392",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"gitiles_ref:refs/heads/master"
]
}
[16:03:21.410] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610726600545453",
"id": "8858024460065313856",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610726600995766",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22215",
"builder:linux-chromeos-dbg",
"buildset:commit/git/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023454522329965392",
"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/8858024460065313856/+/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": "1610726600995880",
"url": "https://ci.chromium.org/b/8858024460065313856",
"utcnow_ts": "1610726601394182"
}
}
[16:03:21.410] Task URL: https://ci.chromium.org/b/8858024460065313856
[16:03:21.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:2:0) after 1m0s
[16:03:44.319] Received PubSub notification, asking Buildbucket for the build status
[16:03:44.644] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:21.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:2:0)
[16:04:21.423] Timer tick, asking Buildbucket for the build status
[16:04:21.618] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:21.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:4:0) after 1m0s
[16:05:21.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:4:0)
[16:05:21.711] Timer tick, asking Buildbucket for the build status
[16:05:22.362] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:22.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:5:0) after 1m0s
[16:06:22.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:5:0)
[16:06:22.430] Timer tick, asking Buildbucket for the build status
[16:06:22.497] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:22.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:6:0) after 1m0s
[16:07:22.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:6:0)
[16:07:22.651] Timer tick, asking Buildbucket for the build status
[16:07:22.807] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:22.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:7:0) after 1m0s
[16:08:22.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:7:0)
[16:08:22.871] Timer tick, asking Buildbucket for the build status
[16:08:23.291] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:23.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:8:0) after 1m0s
[16:09:23.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:8:0)
[16:09:23.347] Timer tick, asking Buildbucket for the build status
[16:09:23.468] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:23.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:9:0) after 1m0s
[16:10:23.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:9:0)
[16:10:23.570] Timer tick, asking Buildbucket for the build status
[16:10:23.747] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:23.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:10:0) after 1m0s
[16:11:23.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:10:0)
[16:11:23.770] Timer tick, asking Buildbucket for the build status
[16:11:23.985] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:23.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:11:0) after 1m0s
[16:12:24.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:11:0)
[16:12:24.134] Timer tick, asking Buildbucket for the build status
[16:12:24.616] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:24.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:12:0) after 1m0s
[16:13:24.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:12:0)
[16:13:24.662] Timer tick, asking Buildbucket for the build status
[16:13:24.886] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:24.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:13:0) after 1m0s
[16:14:24.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:13:0)
[16:14:24.885] Timer tick, asking Buildbucket for the build status
[16:14:25.132] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:25.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:14:0) after 1m0s
[16:15:25.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:14:0)
[16:15:25.142] Timer tick, asking Buildbucket for the build status
[16:15:25.250] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:25.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:15:0) after 1m0s
[16:16:25.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:15:0)
[16:16:25.309] Timer tick, asking Buildbucket for the build status
[16:16:25.402] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:25.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:16:0) after 1m0s
[16:17:25.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:16:0)
[16:17:25.443] Timer tick, asking Buildbucket for the build status
[16:17:25.540] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:25.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:17:0) after 1m0s
[16:18:25.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:17:0)
[16:18:25.521] Timer tick, asking Buildbucket for the build status
[16:18:26.088] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:26.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:18:0) after 1m0s
[16:19:26.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:18:0)
[16:19:26.215] Timer tick, asking Buildbucket for the build status
[16:19:26.336] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:26.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:19:0) after 1m0s
[16:20:26.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:19:0)
[16:20:26.409] Timer tick, asking Buildbucket for the build status
[16:20:26.517] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:26.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:20:0) after 1m0s
[16:21:26.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:20:0)
[16:21:26.528] Timer tick, asking Buildbucket for the build status
[16:21:26.753] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:26.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:21:0) after 1m0s
[16:22:26.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:21:0)
[16:22:26.758] Timer tick, asking Buildbucket for the build status
[16:22:26.843] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:26.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:22:0) after 1m0s
[16:23:26.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:22:0)
[16:23:26.901] Timer tick, asking Buildbucket for the build status
[16:23:27.006] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:27.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:23:0) after 1m0s
[16:24:27.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:23:0)
[16:24:27.030] Timer tick, asking Buildbucket for the build status
[16:24:27.105] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:27.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:24:0) after 1m0s
[16:25:27.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:24:0)
[16:25:27.166] Timer tick, asking Buildbucket for the build status
[16:25:27.498] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:27.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:25:0) after 1m0s
[16:26:27.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:25:0)
[16:26:27.533] Timer tick, asking Buildbucket for the build status
[16:26:27.636] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:27.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:26:0) after 1m0s
[16:27:27.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:26:0)
[16:27:27.673] Timer tick, asking Buildbucket for the build status
[16:27:27.754] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:27.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:27:0) after 1m0s
[16:28:27.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:27:0)
[16:28:27.781] Timer tick, asking Buildbucket for the build status
[16:28:28.031] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:28.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:28:0) after 1m0s
[16:29:28.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:28:0)
[16:29:28.067] Timer tick, asking Buildbucket for the build status
[16:29:28.146] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:28.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:29:0) after 1m0s
[16:30:28.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:29:0)
[16:30:28.198] Timer tick, asking Buildbucket for the build status
[16:30:28.258] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:28.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:30:0) after 1m0s
[16:31:28.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:30:0)
[16:31:28.339] Timer tick, asking Buildbucket for the build status
[16:31:28.413] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:28.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:31:0) after 1m0s
[16:32:28.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:31:0)
[16:32:28.507] Timer tick, asking Buildbucket for the build status
[16:32:28.564] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:28.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:32:0) after 1m0s
[16:33:28.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:32:0)
[16:33:28.711] Timer tick, asking Buildbucket for the build status
[16:33:28.814] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:28.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:33:0) after 1m0s
[16:34:28.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:33:0)
[16:34:28.886] Timer tick, asking Buildbucket for the build status
[16:34:28.957] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:28.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:34:0) after 1m0s
[16:35:29.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:34:0)
[16:35:29.150] Timer tick, asking Buildbucket for the build status
[16:35:29.294] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:29.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:35:0) after 1m0s
[16:36:30.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:35:0)
[16:36:30.059] Timer tick, asking Buildbucket for the build status
[16:36:30.138] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:30.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:36:0) after 1m0s
[16:37:30.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:36:0)
[16:37:30.961] Timer tick, asking Buildbucket for the build status
[16:37:31.037] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:31.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:37:0) after 1m0s
[16:38:31.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:37:0)
[16:38:31.111] Timer tick, asking Buildbucket for the build status
[16:38:31.607] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:31.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:38:0) after 1m0s
[16:39:31.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:38:0)
[16:39:31.646] Timer tick, asking Buildbucket for the build status
[16:39:32.002] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:32.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:39:0) after 1m0s
[16:40:32.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:39:0)
[16:40:32.102] Timer tick, asking Buildbucket for the build status
[16:40:32.497] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:32.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:40:0) after 1m0s
[16:41:32.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:40:0)
[16:41:32.528] Timer tick, asking Buildbucket for the build status
[16:41:32.696] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:32.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:41:0) after 1m0s
[16:42:32.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:41:0)
[16:42:32.739] Timer tick, asking Buildbucket for the build status
[16:42:33.019] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:33.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:42:0) after 1m0s
[16:43:33.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:42:0)
[16:43:33.137] Timer tick, asking Buildbucket for the build status
[16:43:33.271] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:33.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:43:0) after 1m0s
[16:44:33.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:43:0)
[16:44:33.371] Timer tick, asking Buildbucket for the build status
[16:44:33.517] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:33.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:44:0) after 1m0s
[16:45:33.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:44:0)
[16:45:33.555] Timer tick, asking Buildbucket for the build status
[16:45:33.828] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:33.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:45:0) after 1m0s
[16:46:33.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:45:0)
[16:46:33.840] Timer tick, asking Buildbucket for the build status
[16:46:33.913] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:33.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:46:0) after 1m0s
[16:47:33.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:46:0)
[16:47:33.949] Timer tick, asking Buildbucket for the build status
[16:47:34.049] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:34.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:47:0) after 1m0s
[16:48:34.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:47:0)
[16:48:34.076] Timer tick, asking Buildbucket for the build status
[16:48:34.199] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:34.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:48:0) after 1m0s
[16:49:34.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:48:0)
[16:49:34.247] Timer tick, asking Buildbucket for the build status
[16:49:34.692] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:34.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:49:0) after 1m0s
[16:50:34.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:49:0)
[16:50:34.859] Timer tick, asking Buildbucket for the build status
[16:50:35.144] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:35.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:50:0) after 1m0s
[16:51:35.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:50:0)
[16:51:35.230] Timer tick, asking Buildbucket for the build status
[16:51:35.592] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:35.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:51:0) after 1m0s
[16:52:35.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:51:0)
[16:52:35.636] Timer tick, asking Buildbucket for the build status
[16:52:35.732] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:35.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:52:0) after 1m0s
[16:53:35.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:52:0)
[16:53:35.842] Timer tick, asking Buildbucket for the build status
[16:53:36.052] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:36.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:53:0) after 1m0s
[16:54:36.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:53:0)
[16:54:36.149] Timer tick, asking Buildbucket for the build status
[16:54:36.722] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:36.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:54:0) after 1m0s
[16:55:36.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:54:0)
[16:55:36.769] Timer tick, asking Buildbucket for the build status
[16:55:36.923] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:36.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:55:0) after 1m0s
[16:56:36.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:55:0)
[16:56:36.949] Timer tick, asking Buildbucket for the build status
[16:56:37.076] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:37.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:56:0) after 1m0s
[16:57:37.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:56:0)
[16:57:37.147] Timer tick, asking Buildbucket for the build status
[16:57:37.969] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:37.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:57:0) after 1m0s
[16:58:38.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:57:0)
[16:58:38.002] Timer tick, asking Buildbucket for the build status
[16:58:38.127] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:38.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:58:0) after 1m0s
[16:59:38.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:58:0)
[16:59:38.192] Timer tick, asking Buildbucket for the build status
[16:59:38.278] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:38.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:59:0) after 1m0s
[17:00:38.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:59:0)
[17:00:38.312] Timer tick, asking Buildbucket for the build status
[17:00:39.254] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:39.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:60:0) after 1m0s
[17:01:39.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:60:0)
[17:01:39.399] Timer tick, asking Buildbucket for the build status
[17:01:39.915] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:39.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:61:0) after 1m0s
[17:02:40.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:61:0)
[17:02:40.070] Timer tick, asking Buildbucket for the build status
[17:02:40.253] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:40.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:62:0) after 1m0s
[17:03:40.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:62:0)
[17:03:40.338] Timer tick, asking Buildbucket for the build status
[17:03:40.701] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:40.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:63:0) after 1m0s
[17:04:40.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:63:0)
[17:04:40.889] Timer tick, asking Buildbucket for the build status
[17:04:41.104] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:41.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:64:0) after 1m0s
[17:05:41.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:64:0)
[17:05:41.308] Timer tick, asking Buildbucket for the build status
[17:05:41.549] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:41.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:65:0) after 1m0s
[17:06:41.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:65:0)
[17:06:41.644] Timer tick, asking Buildbucket for the build status
[17:06:41.808] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:41.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:66:0) after 1m0s
[17:07:41.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:66:0)
[17:07:41.815] Timer tick, asking Buildbucket for the build status
[17:07:42.202] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:42.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:67:0) after 1m0s
[17:08:42.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:67:0)
[17:08:42.181] Timer tick, asking Buildbucket for the build status
[17:08:42.379] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:42.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:68:0) after 1m0s
[17:09:42.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:68:0)
[17:09:42.367] Timer tick, asking Buildbucket for the build status
[17:09:42.503] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:42.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:69:0) after 1m0s
[17:10:42.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:69:0)
[17:10:42.503] Timer tick, asking Buildbucket for the build status
[17:10:42.597] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:42.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:70:0) after 1m0s
[17:11:42.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:70:0)
[17:11:42.621] Timer tick, asking Buildbucket for the build status
[17:11:42.939] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:42.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:71:0) after 1m0s
[17:12:42.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:71:0)
[17:12:42.957] Timer tick, asking Buildbucket for the build status
[17:12:43.092] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:43.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:72:0) after 1m0s
[17:13:43.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:72:0)
[17:13:43.116] Timer tick, asking Buildbucket for the build status
[17:13:43.478] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:43.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:73:0) after 1m0s
[17:14:43.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:73:0)
[17:14:43.584] Timer tick, asking Buildbucket for the build status
[17:14:43.709] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:43.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:74:0) after 1m0s
[17:15:43.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:74:0)
[17:15:43.839] Timer tick, asking Buildbucket for the build status
[17:15:43.929] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:43.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:75:0) after 1m0s
[17:16:43.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:75:0)
[17:16:43.987] Timer tick, asking Buildbucket for the build status
[17:16:44.192] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:44.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:76:0) after 1m0s
[17:17:44.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:76:0)
[17:17:44.243] Timer tick, asking Buildbucket for the build status
[17:17:44.365] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:44.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:77:0) after 1m0s
[17:18:44.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:77:0)
[17:18:44.422] Timer tick, asking Buildbucket for the build status
[17:18:44.799] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:44.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:78:0) after 1m0s
[17:19:44.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:78:0)
[17:19:44.821] Timer tick, asking Buildbucket for the build status
[17:19:44.910] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:44.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:79:0) after 1m0s
[17:20:44.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:79:0)
[17:20:44.936] Timer tick, asking Buildbucket for the build status
[17:20:45.257] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:45.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:80:0) after 1m0s
[17:21:45.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:80:0)
[17:21:45.317] Timer tick, asking Buildbucket for the build status
[17:21:45.385] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:45.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:81:0) after 1m0s
[17:22:45.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:81:0)
[17:22:45.412] Timer tick, asking Buildbucket for the build status
[17:22:45.559] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:45.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:82:0) after 1m0s
[17:23:45.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:82:0)
[17:23:45.614] Timer tick, asking Buildbucket for the build status
[17:23:45.801] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:45.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:83:0) after 1m0s
[17:24:45.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:83:0)
[17:24:45.977] Timer tick, asking Buildbucket for the build status
[17:24:46.068] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:46.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:84:0) after 1m0s
[17:25:46.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:84:0)
[17:25:46.268] Timer tick, asking Buildbucket for the build status
[17:25:46.337] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:46.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:85:0) after 1m0s
[17:26:46.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:85:0)
[17:26:46.494] Timer tick, asking Buildbucket for the build status
[17:26:46.636] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:46.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:86:0) after 1m0s
[17:27:46.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:86:0)
[17:27:46.722] Timer tick, asking Buildbucket for the build status
[17:27:46.787] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:46.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:87:0) after 1m0s
[17:28:46.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:87:0)
[17:28:46.822] Timer tick, asking Buildbucket for the build status
[17:28:46.930] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:46.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:88:0) after 1m0s
[17:29:46.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:88:0)
[17:29:46.967] Timer tick, asking Buildbucket for the build status
[17:29:47.338] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:47.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:89:0) after 1m0s
[17:30:47.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:89:0)
[17:30:47.413] Timer tick, asking Buildbucket for the build status
[17:30:47.532] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:47.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:90:0) after 1m0s
[17:31:47.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:90:0)
[17:31:47.605] Timer tick, asking Buildbucket for the build status
[17:31:47.712] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:47.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:91:0) after 1m0s
[17:32:47.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:91:0)
[17:32:47.813] Timer tick, asking Buildbucket for the build status
[17:32:48.143] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:48.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:92:0) after 1m0s
[17:33:48.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:92:0)
[17:33:48.156] Timer tick, asking Buildbucket for the build status
[17:33:48.609] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:48.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:93:0) after 1m0s
[17:34:48.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:93:0)
[17:34:48.622] Timer tick, asking Buildbucket for the build status
[17:34:48.696] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:48.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:94:0) after 1m0s
[17:35:48.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:94:0)
[17:35:48.775] Timer tick, asking Buildbucket for the build status
[17:35:48.863] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:48.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:95:0) after 1m0s
[17:36:48.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:95:0)
[17:36:48.901] Timer tick, asking Buildbucket for the build status
[17:36:48.977] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:48.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:96:0) after 1m0s
[17:37:49.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:96:0)
[17:37:49.004] Timer tick, asking Buildbucket for the build status
[17:37:49.078] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:49.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:97:0) after 1m0s
[17:38:49.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:97:0)
[17:38:49.161] Timer tick, asking Buildbucket for the build status
[17:38:49.310] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:49.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:98:0) after 1m0s
[17:39:49.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:98:0)
[17:39:49.325] Timer tick, asking Buildbucket for the build status
[17:39:49.556] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:49.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:99:0) after 1m0s
[17:40:49.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:99:0)
[17:40:49.617] Timer tick, asking Buildbucket for the build status
[17:40:49.787] Build 8858024460065313856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:49.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023454522329965392:100:0) after 1m0s
[17:40:56.649] Received PubSub notification, asking Buildbucket for the build status
[17:40:56.743] Build 8858024460065313856: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:40:56.743] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610732454533896",
"created_by": "project:chromium",
"created_ts": "1610726600545453",
"failure_reason": "BUILD_FAILURE",
"id": "8858024460065313856",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AutomationApiTest.TextareaAppendPerf\"}, \"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\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\", \"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-159-iph3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22215, \"got_angle_revision\": \"5678b56f5e2129615193034cd90bb6a0ef3c70d3\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\", \"got_revision_cp\": \"refs/heads/master@{#844091}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"aedd133cd85f40f92c304776d240a8a3503d1d71\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\", \"swarm_hashes_refs/heads/master(at){#844091}_without_patch\": {\"absl_hardening_tests\": \"246ce669e18d3acef1bddbc9e72fa94da205784f\", \"accessibility_unittests\": \"b43a00b2b60fb442f9b34de12efa938a1ab450c2\", \"angle_unittests\": \"1ce0af7dce47f48bb2c07b4241047ebf62918f5b\", \"app_list_unittests\": \"814f79c35ed682c62e16f7f3a5ed00d3e4bc7ef0\", \"app_shell_unittests\": \"d1409190bccc525155f740b7ecfd9c86ca9d0691\", \"ash_unittests\": \"59e8cf0eac86347d69b29d2a0d41c30925d01bd3\", \"aura_unittests\": \"17ca2af3bd7f3de1edc14c780e117b786f567f35\", \"base_unittests\": \"971ce10a4150d3e6b6f53670c0a28e12db9f86a6\", \"base_util_unittests\": \"6ddd0291ab13bde1e71d0ce0c07779f7029b40bd\", \"blink_common_unittests\": \"b9a38f5e1d5e1134b2d2b2f55372cec6b630b380\", \"blink_fuzzer_unittests\": \"13272bea2afe226da92e4183b2eaf7ed3de2004f\", \"blink_heap_unittests\": \"b52645468deb4d06c132af83fb91def0ce0694a8\", \"blink_platform_unittests\": \"f49d3bdbf8f399f5425947f42b495833c48e46da\", \"blink_unittests\": \"c9eeed99755d8cf8a248da2c2f788f8c461b7cba\", \"boringssl_crypto_tests\": \"c6f46b55a3efca98d9e1ecf16cb0896d182fc70b\", \"boringssl_ssl_tests\": \"36ed44dcd8cb75e4a69a85ba32a55a6435348f98\", \"browser_tests\": \"101b6170d355d4ee8fad9620b167685c8191d49c\", \"capture_unittests\": \"83c527fb794b049d9c52aad034002f7097527bd6\", \"cast_unittests\": \"444e4aa83a89de3abd3adb1b8d5fa92d1ab74878\", \"cc_unittests\": \"ed44cd6e97ea40f0f96bddcd508278336e3fee8d\", \"chrome_app_unittests\": \"a1fa1ea77b55677a29849006161fef30435f1282\", \"chromedriver_unittests\": \"121b209a3146fbfe1233626bbee1d32ae3c6677b\", \"chromeos_components_unittests\": \"472a953be6225183a819254c12f59cb29b90a1ee\", \"chromeos_unittests\": \"ea9b1b05d0d6955ba45e3fc1d404bde06df82b77\", \"components_browsertests\": \"cc68812f75d1fea9b0d9aa7fa5ce417f68ac3a47\", \"components_unittests\": \"61df6175eeca5a636101459e25840bf1fa8f8f5b\", \"compositor_unittests\": \"20e91ce8c3e76f1c77f3981e1f06e5590bcfa533\", \"content_browsertests\": \"f6719856d1b81bfb7943984ba188b12d13f3e385\", \"content_unittests\": \"fb17f763f4584a13506bd1e8977a7c38f28c7990\", \"crashpad_tests\": \"0819e05e8ebfbb2a812052c75a5c4bf5955b24b0\", \"crypto_unittests\": \"46ba08b4bdf9a80a9165d8436c539c5425904870\", \"dbus_unittests\": \"816845c0f68a5910bce830ee9da6a2a7d6f5d64d\", \"device_unittests\": \"ea3603bce6c688a6d5e363b32a90bcf322b0000b\", \"display_unittests\": \"90197deeb89f6d32f571cba5e82daafb9d33aa13\", \"events_unittests\": \"1872009ef9b8a4715c30d01ca39f8691a055ddaa\", \"exo_unittests\": \"46300ae7284e4f662b402b59259c10f248787837\", \"extensions_browsertests\": \"5c868830be798d240d57cb49f7acee6c0482e23b\", \"extensions_unittests\": \"8055d6fd1cbe272e8001d4ad60e41e47239fe4a1\", \"filesystem_service_unittests\": \"02063e706a7699c772e7b0b4da880ce0c81b13d7\", \"gcm_unit_tests\": \"7271cb798300249ab61ec7baa23f4dafbafc403e\", \"gfx_unittests\": \"6f5adb57a345e1c0d05277827aa031e28be561e6\", \"gin_unittests\": \"f04adfb3ada50c7f62f464d5f1ea3f7d8250a4fc\", \"gl_unittests_ozone\": \"10ab18bf407bf0b79a0ba71bbe9bb2728f9b544b\", \"google_apis_unittests\": \"0436d333e51faf5616f787927a7abb16574715c8\", \"gpu_unittests\": \"0874665e30f0ce0d3a80ea4530b3d09aa358531a\", \"gwp_asan_unittests\": \"180f122fa8e4a3fa477b22b8224c1d0888d20ec7\", \"interactive_ui_tests\": \"e97a3f4b5702c5e8c2a6026d99008a6c123fa3d1\", \"ipc_tests\": \"61b99ebaf2225c8baceefe36a82e1600a6759cbe\", \"jingle_unittests\": \"59186e13607cc214b021e94a841cc1f518efa85a\", \"keyboard_unittests\": \"8216886f2463e58bd3c9c1828621934e04e20479\", \"latency_unittests\": \"5dad9b1857386f79cd4a94b1a503427f39060510\", \"libjingle_xmpp_unittests\": \"889613ce7f7589897e6fc20bb6bff6ead2ec462d\", \"liburlpattern_unittests\": \"8a2b7b26306443017420a1ce3a4c2bdc8e2cb408\", \"media_blink_unittests\": \"468fd10decbc5ddd005f928df306a9496922243e\", \"media_unittests\": \"afe25be4d57c8b4d58180b1448b892bb609db460\", \"message_center_unittests\": \"aa89710ef3f7cd5693551216230367cc5ef8c3b9\", \"midi_unittests\": \"e145d20681fe717feb45612165b8115e93c8dc9f\", \"mojo_core_unittests\": \"0f9bd7ef088f7c5cfc7c3823f03d23f692b9e6ef\", \"mojo_unittests\": \"738f9b98d6bde13493353a5a6519095815c41f55\", \"nacl_helper_nonsfi_unittests\": \"186b74996c59fd42597021039ffc618546ff481d\", \"nacl_loader_unittests\": \"2c903eab50fb4fa281535a8348462b780d262e64\", \"native_theme_unittests\": \"878f91df87ab1bcaf26aa7ff5af99309860f4d07\", \"net_unittests\": \"4b834eb21467f2debec1dd34da4a903463d2f516\", \"ozone_gl_unittests\": \"c6d116a414248fccd9389669673b0bcc49921d9a\", \"ozone_unittests\": \"7cdf253c160479d152f764c8cbd27c623a096356\", \"ozone_x11_unittests\": \"1c3a256e255e397b185d5c758ffccf5c7dbb23a7\", \"pdf_unittests\": \"3f894ed88fdbed21bf1cdec7ebaaabd48ea4f12b\", \"perfetto_unittests\": \"56224527de75fe1adb7f3d9e74686cfa77a1ad06\", \"ppapi_unittests\": \"7de749944a40869fe89cc28986e0d5e539d355b5\", \"printing_unittests\": \"5dd9f1dde75e469fe2d7d757362307b134fa411c\", \"remoting_unittests\": \"937d9fa03442ba8e1bf8d01087baf6923f0b6c5d\", \"sandbox_linux_unittests\": \"b09923a382f7e5cb0eec0e9d78986a91a54a6cb7\", \"service_manager_unittests\": \"9a132f322091a3c7dbd6c95eb77c3ebcd6bd9c7b\", \"services_unittests\": \"2229abfba97377014823eece957c1af060fd7e3d\", \"shell_dialogs_unittests\": \"1f29779314344f2a76580dc4d3a692bdbc2d0aa0\", \"shell_encryption_unittests\": \"39782db45d39eca530b3f3fb193c33724eb005d4\", \"skia_unittests\": \"013c54cbcbacf0b37611e38dd0523a486a043031\", \"snapshot_unittests\": \"3a4b7bf3fae3ca4fc6739fe43d8ab12059f24066\", \"sql_unittests\": \"3478b5c2560dc055c19005d667c88f57ad55bbdc\", \"storage_unittests\": \"f7509ccb27f7f6659cefe0893771aef228fe934d\", \"sync_integration_tests\": \"d7d213fa52bccb18ad16a2bd62bd450d618db0ce\", \"traffic_annotation_auditor_unittests\": \"d2604e342f960ae9382efd66a69627362c3ee13d\", \"ui_base_unittests\": \"079a93f1a5e4ade7cd466df13909dbd9599b1aea\", \"ui_chromeos_unittests\": \"7b532aff4a1fb77583d3fa5ac1e6d6fd2b7a776c\", \"ui_touch_selection_unittests\": \"f3722205042295f8465dda971ba597e755a04e9f\", \"unit_tests\": \"9af195ccef60e4357788d4a5977c68438abae321\", \"url_unittests\": \"8613536c157e46d9de5779647609fd9ebc9ab3e9\", \"usage_time_limit_unittests\": \"3995ad4cf8325840b34d140388c90681fce74f6c\", \"views_unittests\": \"ef54636a3cba79b282c88452bb4daa4a15fe2014\", \"viz_unittests\": \"5a233ff029b9ee99457cd54812775db3f040d73b\", \"wm_unittests\": \"0824ad837b0e8749a599433aee288e252d6f7dc7\", \"wtf_unittests\": \"e165761295d04b8538d8e83c8d2eb647219f7cbe\", \"zlib_unittests\": \"18311d48e5b178dc3febb794bf8524e6b1dd9dc4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_7b27d20986eac2bbcc1513665f8799c69dbe398236c21423fd80a70475184e3c_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-159-iph3\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"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\": [\"5666-679c9bd\"], \"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- AutomationApiTest.TextareaAppendPerf\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610726623156822",
"status": "COMPLETED",
"status_changed_ts": "1610726623898385",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22215",
"builder:linux-chromeos-dbg",
"buildset:commit/git/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023454522329965392",
"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/8858024460065313856/+/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:511f9afb4169c310",
"user_agent:luci-scheduler"
],
"updated_ts": "1610732456179569",
"url": "https://ci.chromium.org/b/8858024460065313856",
"utcnow_ts": "1610732456733350"
}
}
[17:40:56.743] Invocation finished in 1h37m37.741772774s with status FAILED