[04:53:08.418] New invocation is queued and will start shortly
[04:53:09.595] Starting the invocation (attempt 1)
[04:53:09.630] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:53:09.631] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:53:09.631] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022409522617934336",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7b262d4bdc370d55087a3f817818a324779cde01\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022409522617934336",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/7b262d4bdc370d55087a3f817818a324779cde01",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7b262d4bdc370d55087a3f817818a324779cde01",
"gitiles_ref:refs/heads/master"
]
}
[04:53:10.428] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611723189792242",
"id": "8856979460498413792",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7b262d4bdc370d55087a3f817818a324779cde01\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611723190230318",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44934",
"builder:linux-chromeos-rel",
"buildset:commit/git/7b262d4bdc370d55087a3f817818a324779cde01",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7b262d4bdc370d55087a3f817818a324779cde01",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022409522617934336",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856979460498413792/+/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": "1611723190230432",
"url": "https://ci.chromium.org/b/8856979460498413792",
"utcnow_ts": "1611723190420356"
}
}
[04:53:10.428] Task URL: https://ci.chromium.org/b/8856979460498413792
[04:53:10.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:2:0) after 1m0s
[04:54:10.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:2:0)
[04:54:10.416] Timer tick, asking Buildbucket for the build status
[04:54:10.961] Build 8856979460498413792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:54:10.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:3:0) after 1m0s
[04:54:21.606] Received PubSub notification, asking Buildbucket for the build status
[04:54:21.679] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:10.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:3:0)
[04:55:10.984] Timer tick, asking Buildbucket for the build status
[04:55:11.147] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:11.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:5:0) after 1m0s
[04:56:11.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:5:0)
[04:56:11.183] Timer tick, asking Buildbucket for the build status
[04:56:11.906] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:11.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:6:0) after 1m0s
[04:57:11.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:6:0)
[04:57:11.959] Timer tick, asking Buildbucket for the build status
[04:57:12.052] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:12.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:7:0) after 1m0s
[04:58:12.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:7:0)
[04:58:12.215] Timer tick, asking Buildbucket for the build status
[04:58:12.361] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:12.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:8:0) after 1m0s
[04:59:12.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:8:0)
[04:59:12.363] Timer tick, asking Buildbucket for the build status
[04:59:12.469] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:12.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:9:0) after 1m0s
[05:00:12.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:9:0)
[05:00:12.514] Timer tick, asking Buildbucket for the build status
[05:00:12.909] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:12.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:10:0) after 1m0s
[05:01:13.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:10:0)
[05:01:13.011] Timer tick, asking Buildbucket for the build status
[05:01:13.132] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:13.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:11:0) after 1m0s
[05:02:13.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:11:0)
[05:02:13.106] Timer tick, asking Buildbucket for the build status
[05:02:13.181] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:13.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:12:0) after 1m0s
[05:03:13.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:12:0)
[05:03:13.243] Timer tick, asking Buildbucket for the build status
[05:03:13.454] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:13.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:13:0) after 1m0s
[05:04:13.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:13:0)
[05:04:13.469] Timer tick, asking Buildbucket for the build status
[05:04:13.587] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:13.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:14:0) after 1m0s
[05:05:13.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:14:0)
[05:05:13.618] Timer tick, asking Buildbucket for the build status
[05:05:13.684] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:13.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:15:0) after 1m0s
[05:06:13.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:15:0)
[05:06:13.724] Timer tick, asking Buildbucket for the build status
[05:06:13.815] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:13.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:16:0) after 1m0s
[05:07:13.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:16:0)
[05:07:13.840] Timer tick, asking Buildbucket for the build status
[05:07:14.006] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:14.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:17:0) after 1m0s
[05:08:14.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:17:0)
[05:08:14.028] Timer tick, asking Buildbucket for the build status
[05:08:16.472] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:16.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:18:0) after 1m0s
[05:09:16.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:18:0)
[05:09:16.565] Timer tick, asking Buildbucket for the build status
[05:09:16.652] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:16.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:19:0) after 1m0s
[05:10:16.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:19:0)
[05:10:16.700] Timer tick, asking Buildbucket for the build status
[05:10:17.640] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:17.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:20:0) after 1m0s
[05:11:17.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:20:0)
[05:11:17.760] Timer tick, asking Buildbucket for the build status
[05:11:17.843] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:17.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:21:0) after 1m0s
[05:12:17.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:21:0)
[05:12:17.848] Timer tick, asking Buildbucket for the build status
[05:12:18.382] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:18.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:22:0) after 1m0s
[05:13:18.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:22:0)
[05:13:18.423] Timer tick, asking Buildbucket for the build status
[05:13:18.544] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:18.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:23:0) after 1m0s
[05:14:18.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:23:0)
[05:14:18.615] Timer tick, asking Buildbucket for the build status
[05:14:18.716] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:18.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:24:0) after 1m0s
[05:15:18.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:24:0)
[05:15:18.792] Timer tick, asking Buildbucket for the build status
[05:15:19.036] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:19.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:25:0) after 1m0s
[05:16:19.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:25:0)
[05:16:19.101] Timer tick, asking Buildbucket for the build status
[05:16:19.184] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:19.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:26:0) after 1m0s
[05:17:19.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:26:0)
[05:17:19.302] Timer tick, asking Buildbucket for the build status
[05:17:19.422] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:19.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:27:0) after 1m0s
[05:18:19.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:27:0)
[05:18:19.645] Timer tick, asking Buildbucket for the build status
[05:18:19.786] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:19.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:28:0) after 1m0s
[05:19:19.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:28:0)
[05:19:19.879] Timer tick, asking Buildbucket for the build status
[05:19:19.940] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:19.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:29:0) after 1m0s
[05:20:20.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:29:0)
[05:20:20.787] Timer tick, asking Buildbucket for the build status
[05:20:22.160] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:22.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:30:0) after 1m0s
[05:21:22.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:30:0)
[05:21:22.205] Timer tick, asking Buildbucket for the build status
[05:21:22.277] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:22.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:31:0) after 1m0s
[05:22:22.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:31:0)
[05:22:22.424] Timer tick, asking Buildbucket for the build status
[05:22:22.501] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:22.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:32:0) after 1m0s
[05:23:22.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:32:0)
[05:23:22.563] Timer tick, asking Buildbucket for the build status
[05:23:22.699] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:22.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:33:0) after 1m0s
[05:24:22.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:33:0)
[05:24:22.922] Timer tick, asking Buildbucket for the build status
[05:24:23.661] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:23.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:34:0) after 1m0s
[05:25:23.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:34:0)
[05:25:23.976] Timer tick, asking Buildbucket for the build status
[05:25:24.070] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:24.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:35:0) after 1m0s
[05:26:24.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:35:0)
[05:26:24.206] Timer tick, asking Buildbucket for the build status
[05:26:24.312] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:24.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:36:0) after 1m0s
[05:27:24.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:36:0)
[05:27:24.517] Timer tick, asking Buildbucket for the build status
[05:27:24.583] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:24.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:37:0) after 1m0s
[05:28:24.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:37:0)
[05:28:24.602] Timer tick, asking Buildbucket for the build status
[05:28:24.716] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:24.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:38:0) after 1m0s
[05:29:24.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:38:0)
[05:29:24.759] Timer tick, asking Buildbucket for the build status
[05:29:24.931] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:24.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:39:0) after 1m0s
[05:30:24.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:39:0)
[05:30:24.979] Timer tick, asking Buildbucket for the build status
[05:30:25.226] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:25.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:40:0) after 1m0s
[05:31:25.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:40:0)
[05:31:25.204] Timer tick, asking Buildbucket for the build status
[05:31:25.336] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:25.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:41:0) after 1m0s
[05:32:25.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:41:0)
[05:32:25.364] Timer tick, asking Buildbucket for the build status
[05:32:25.928] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:25.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:42:0) after 1m0s
[05:33:25.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:42:0)
[05:33:25.960] Timer tick, asking Buildbucket for the build status
[05:33:26.181] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:26.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:43:0) after 1m0s
[05:34:26.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:43:0)
[05:34:26.212] Timer tick, asking Buildbucket for the build status
[05:34:26.328] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:26.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:44:0) after 1m0s
[05:35:26.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:44:0)
[05:35:26.462] Timer tick, asking Buildbucket for the build status
[05:35:26.790] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:26.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:45:0) after 1m0s
[05:36:26.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:45:0)
[05:36:26.873] Timer tick, asking Buildbucket for the build status
[05:36:27.047] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:27.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:46:0) after 1m0s
[05:37:27.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:46:0)
[05:37:27.123] Timer tick, asking Buildbucket for the build status
[05:37:27.292] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:27.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:47:0) after 1m0s
[05:38:27.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:47:0)
[05:38:27.337] Timer tick, asking Buildbucket for the build status
[05:38:27.428] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:27.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:48:0) after 1m0s
[05:39:27.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:48:0)
[05:39:27.486] Timer tick, asking Buildbucket for the build status
[05:39:27.623] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:27.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:49:0) after 1m0s
[05:40:27.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:49:0)
[05:40:27.642] Timer tick, asking Buildbucket for the build status
[05:40:27.788] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:27.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:50:0) after 1m0s
[05:41:27.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:50:0)
[05:41:27.800] Timer tick, asking Buildbucket for the build status
[05:41:27.931] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:27.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:51:0) after 1m0s
[05:42:27.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:51:0)
[05:42:27.928] Timer tick, asking Buildbucket for the build status
[05:42:28.043] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:28.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:52:0) after 1m0s
[05:43:28.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:52:0)
[05:43:28.332] Timer tick, asking Buildbucket for the build status
[05:43:28.456] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:28.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:53:0) after 1m0s
[05:44:28.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:53:0)
[05:44:28.500] Timer tick, asking Buildbucket for the build status
[05:44:28.571] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:28.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:54:0) after 1m0s
[05:45:28.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:54:0)
[05:45:28.555] Timer tick, asking Buildbucket for the build status
[05:45:29.042] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:29.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:55:0) after 1m0s
[05:46:29.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:55:0)
[05:46:29.082] Timer tick, asking Buildbucket for the build status
[05:46:29.271] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:29.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:56:0) after 1m0s
[05:47:29.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:56:0)
[05:47:29.365] Timer tick, asking Buildbucket for the build status
[05:47:29.542] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:29.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:57:0) after 1m0s
[05:48:30.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:57:0)
[05:48:30.270] Timer tick, asking Buildbucket for the build status
[05:48:30.410] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:30.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:58:0) after 1m0s
[05:49:30.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:58:0)
[05:49:30.545] Timer tick, asking Buildbucket for the build status
[05:49:30.719] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:30.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:59:0) after 1m0s
[05:50:30.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:59:0)
[05:50:30.964] Timer tick, asking Buildbucket for the build status
[05:50:31.127] Build 8856979460498413792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022409522617934336:60:0) after 1m0s
[05:50:57.071] Received PubSub notification, asking Buildbucket for the build status
[05:50:57.162] Build 8856979460498413792: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:50:57.162] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611726654917515",
"created_by": "project:chromium",
"created_ts": "1611723189792242",
"failure_reason": "BUILD_FAILURE",
"id": "8856979460498413792",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7b262d4bdc370d55087a3f817818a324779cde01\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TestAsNormalAndGuestUser/SpokenFeedbackTest.OverviewMode/0\\n\\n- KioskErrorScreenTest.OpenCertificateConfig\"}, \"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\": \"7b262d4bdc370d55087a3f817818a324779cde01\", \"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-120-8ubm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44934, \"got_angle_revision\": \"f9afaaeedee68302e9936532de16126313a3bd02\", \"got_dawn_revision\": \"2df77f4325841e667e6b9ff9921fd626496a3b64\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"7b262d4bdc370d55087a3f817818a324779cde01\", \"got_revision_cp\": \"refs/heads/master@{#847503}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"aec0826aba0db51914209ec85a515d3a99311f07\", \"got_v8_revision\": \"5a6bbde4f5b86e56cbb0827de847a936161efbfe\", \"got_v8_revision_cp\": \"refs/heads/9.0.80@{#1}\", \"got_webrtc_revision\": \"fae4fb13451bea44ae1a94509befe8d7c6b11eb6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33077}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"prev_got_revision\": \"4892be8b7ec96b34468ce4fa5636f979b7ff7bb3\", \"prev_got_revision_cp\": \"refs/heads/master@{#847223}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7b262d4bdc370d55087a3f817818a324779cde01\", \"swarm_hashes_refs/heads/master(at){#847503}_without_patch\": {\"absl_hardening_tests\": \"ba47532e0184d1ec1795c03e4eb4806e08803548\", \"accessibility_unittests\": \"bd716e919cb699edde12d1247e89aba4b2a4431b\", \"angle_unittests\": \"e87202f56f9a2771684bb6314eb6755c04f32ccc\", \"app_list_unittests\": \"854e644b2502340a7cabbe90083a1622d187fc07\", \"app_shell_unittests\": \"0c3c5f18c0b837a13216f2945b97d7cde8898c67\", \"ash_unittests\": \"07d248418d880e66259b1e44954f1e63dbbdb72a\", \"aura_unittests\": \"6baa78ba2939d3c69024b3ca7231ed92a6242575\", \"base_unittests\": \"5565979617261494955706a57a038df1d4d3c8e8\", \"base_util_unittests\": \"8dcf5e343fe8f402d60d1c592e2eb15ab487b443\", \"blink_common_unittests\": \"26d57e3a1762a52ef12bc37d044455c08727933f\", \"blink_fuzzer_unittests\": \"4b862f8f8a501b0d44b458176864a03feb2fb896\", \"blink_heap_unittests\": \"190888b2f41e65bd9283cc9840e4191e283d384f\", \"blink_platform_unittests\": \"4b65c9cdfdddcab104b348ce93e2a17e2d40380c\", \"blink_unittests\": \"807e6794adcdf4d7de8646378162d51a4e5c800b\", \"boringssl_crypto_tests\": \"f12ff3549d4adaa7f10ad9b33ffbb5f0a4d74604\", \"boringssl_ssl_tests\": \"6fbcd485beabb6dd09bf961e8864ae0094cbc0a8\", \"browser_tests\": \"c8e13d80f03e0191f8562a121c871d27ba3830ae\", \"capture_unittests\": \"01e037f3c0f3b8e260268bdf1c31a929db6539bc\", \"cast_unittests\": \"36084ed2b5ef60f052ee8d7ad995d523f96551b7\", \"cc_unittests\": \"07b2bace51a53c21f70265921535330b884126ec\", \"chrome_app_unittests\": \"d16bbe82a3ed9c6ac5d5f5b65dd62cbaa3d4efc2\", \"chromedriver_unittests\": \"46185cc9ccb33f1ef728b6e6339099076307229d\", \"chromeos_components_unittests\": \"ef4c8def6e296fa3b49c658651bda542bdb2b22a\", \"chromeos_unittests\": \"d1591bb0b5656e078bb3b261db3b9dafb9f05217\", \"components_browsertests\": \"8239388f42e51f6714c8850e4167e9c8854b3856\", \"components_unittests\": \"b6eae85a7ea03749dc0ec56dc42ef3e4e9cf79d8\", \"compositor_unittests\": \"a367a28337b77ac784efaf83df303932fe778606\", \"content_browsertests\": \"f0c7f8da9a81dd3cc58acd839d599dad923ade5d\", \"content_unittests\": \"3b173f0d48ed356ab3189f728b1611e2f0088e2b\", \"crashpad_tests\": \"7fa9abd985f58c1d50e40225d54c95eea123c6bd\", \"crypto_unittests\": \"53b837ee02b6160f6aef8b76eda4a2b02a339261\", \"dbus_unittests\": \"015920727bc0c3c412121cc8040f5296163737fc\", \"device_unittests\": \"9809ad66c9b54e4b03da063bab74e26ee06966b1\", \"display_unittests\": \"15240f8d69905354c2bd44d3cc2149f363ed5e30\", \"events_unittests\": \"adaa9fea6ae78b093635534afc16f3519e78d81b\", \"exo_unittests\": \"b505999defccb6a02124bfd25600478d416987f3\", \"extensions_browsertests\": \"2cc9d9b3310042aaba04f8000f7f62fc3869101b\", \"extensions_unittests\": \"5a2bb82702afa3e7f44a3438f465157f203a61a6\", \"filesystem_service_unittests\": \"09a7311ede342ab753e32ee41be8971cddc1a612\", \"gcm_unit_tests\": \"2950ffaa601da3f97c3661589eddcfa19161f446\", \"gfx_unittests\": \"91b50527b9c2537b97515ce7eb810f8dbf378d3e\", \"gin_unittests\": \"5450b5879714cd02e12cc10c4b92f2fe4ae6bc3b\", \"gl_unittests_ozone\": \"68cad9a33ea72d4174833eda36f22acad5deb048\", \"google_apis_unittests\": \"aa34411c8855d79d7dac68a9e02cbe968188cc66\", \"gpu_unittests\": \"84c1551954551b2d3474c676da82a4d66d3b6312\", \"gwp_asan_unittests\": \"e46e56f0b682b224bb789233adb646cb4664f787\", \"interactive_ui_tests\": \"74e4b981a9399d9edffa6323eb39d1b27974f192\", \"ipc_tests\": \"313b820892013f8f1c5a1d199b6adad27b03f4f1\", \"jingle_unittests\": \"adb755db020d22a04ca8e918a7246f81d12e35ca\", \"keyboard_unittests\": \"1c80399e95f853ef461e41715b8d7ad42d8ec975\", \"latency_unittests\": \"0fa265774bcb0503af40bf5d6ffdf97703000144\", \"libjingle_xmpp_unittests\": \"c609a5678f23962adb7efc23e2e8aa3894b1a327\", \"liburlpattern_unittests\": \"e9d3f4d2f4dc0d87c27553d99b8251c1ac519f55\", \"media_blink_unittests\": \"1b2931a92566865b97f26046c4ea82ed8d8158ba\", \"media_unittests\": \"c6c6040cee6a027704d30e07121f49e94a170a28\", \"message_center_unittests\": \"9215d68c63fb16ffa9e26e2e78da9c1fe14f9cb2\", \"midi_unittests\": \"c75271bfadb17bbbe32b0b7d0ab188ebcbbc6517\", \"mojo_core_unittests\": \"baacf18278d1fd8c4338d782241542f290e3c904\", \"mojo_unittests\": \"c8caeec7fbcda5154d3027d31a40c259bd45637a\", \"nacl_helper_nonsfi_unittests\": \"b30713387c976f2ba0963fa9a692d07442da568f\", \"nacl_loader_unittests\": \"eb61933d660b363d2cd0225d8a6e8834f0fc08ef\", \"native_theme_unittests\": \"b45d920db80776b2da08f49c002b882269f79514\", \"net_unittests\": \"9e4e78dc96a129c3c36b47c9edddac0f2420916d\", \"ozone_gl_unittests\": \"37a38432f2a7b84193cd13b8f95b6b2a030f08a2\", \"ozone_unittests\": \"24890073e2735add8399013c41ab559879dfbb2b\", \"ozone_x11_unittests\": \"ccef9e76613b96436e2b6eb68c8e633d66c67b88\", \"pdf_unittests\": \"935a989e458d696814a82abf20ebd74ad68fa93a\", \"perfetto_unittests\": \"528ba31a71cad5f10baac1820112e399b98f4f68\", \"ppapi_unittests\": \"6826eee9185f8dc40de7abb48c5ff499836e24e5\", \"printing_unittests\": \"f4ab4f108c8115458309d401f04f62b965e28fbc\", \"remoting_unittests\": \"5aec7831c7e0f6fa032fe72aab111d710544f825\", \"sandbox_linux_unittests\": \"bc5c01fe2be6e482151379b7db32500869eb968d\", \"service_manager_unittests\": \"a57764e375eddc678c911475aa69d19663680389\", \"services_unittests\": \"df196ef9295d60b29cab0b018e4cc1ec27621a84\", \"shell_dialogs_unittests\": \"4c3d90909927fb7d5f8b5694ff556c392b6db6d2\", \"shell_encryption_unittests\": \"0711da466cb5b6836c839832ecd2c4523bf66c3b\", \"skia_unittests\": \"d0fb550fcf43926a65d88fac1603beb448238bc3\", \"snapshot_unittests\": \"d6051ffb4f39b70da8870c98294dcee599a4a35c\", \"sql_unittests\": \"21c770f908d63e94b1cb9c731e8e544b4e97ee3b\", \"storage_unittests\": \"d1bbbe466c73b8a5505c2cab54ca9b59fc147bef\", \"sync_integration_tests\": \"3834f16f90d8079842061c930759801fdab56429\", \"traffic_annotation_auditor_unittests\": \"1cc5bca07180516c5d6d35bc80795c1cc5073109\", \"ui_base_unittests\": \"8ddbb77c040304232b1b5b47eb3be69e90b1c996\", \"ui_chromeos_unittests\": \"911608b7324ec7fd6ac9220d80a4b44d30cb7471\", \"ui_touch_selection_unittests\": \"1a8b43cc56fa182668ab06b19fe7be8c7023c437\", \"unit_tests\": \"fc95fb7a38d5e55f8dc50566779fd3fc5ab6e312\", \"url_unittests\": \"6c4995f2aa50361906f15ab7a44eba75e77d1c16\", \"usage_time_limit_unittests\": \"fdfcedb56fa6de8c79584837538464045ed8f4b4\", \"views_unittests\": \"604d7ff8ba3b17bc3e43591c60fe966e2fd69809\", \"viz_unittests\": \"2808d7e7ec508320701b131e63035c23cbc604fa\", \"wayland_client_perftests\": \"ca2d0b67a32790a7474c9f8f149c5a6cc4dbd623\", \"wm_unittests\": \"de688d523ec4e415b234e5bc1ec0ac1337bacd42\", \"wtf_unittests\": \"117894be4d03aeb8c99e9e36ffa7ef690c169295\", \"zlib_unittests\": \"64b451fa316d9483e46d4f32a69c413479aba0f6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_057d00cc9cf4bf141513fc3b802f5b4dacb7566e9fed03d4c1bbb1efa2ee2ffd_v2\", \"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_37a6efb75484d21403d66fa62f2f0e5111772231983ba4ec0093d39fca548be8_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a942695c23e0ee058ce93eb378e4719ec038e7b245f732574f7a4ff5c9d830ce_v2\", \"builder_cccbb10c846899f06deffa42caa19db86cd5babce89846fb5ee94576babbc4b9_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-120-8ubm\"], \"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\": [\"5672-4713de1\"], \"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- TestAsNormalAndGuestUser/SpokenFeedbackTest.OverviewMode/0\\n\\n- KioskErrorScreenTest.OpenCertificateConfig\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611723260770013",
"status": "COMPLETED",
"status_changed_ts": "1611723261057819",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44934",
"builder:linux-chromeos-rel",
"buildset:commit/git/7b262d4bdc370d55087a3f817818a324779cde01",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7b262d4bdc370d55087a3f817818a324779cde01",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022409522617934336",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856979460498413792/+/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:515b01c23ab40010",
"user_agent:luci-scheduler"
],
"updated_ts": "1611726656757971",
"url": "https://ci.chromium.org/b/8856979460498413792",
"utcnow_ts": "1611726657152066"
}
}
[05:50:57.162] Invocation finished in 57m48.773660096s with status FAILED