[08:52:24.449] New invocation is queued and will start shortly
[08:52:25.549] Starting the invocation (attempt 1)
[08:52:25.624] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:52:25.624] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:52:25.624] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028645659897014720",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4495da77967c9da41b8d500f4f54d7171e18049a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9028645659897014720",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4495da77967c9da41b8d500f4f54d7171e18049a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4495da77967c9da41b8d500f4f54d7171e18049a",
"gitiles_ref:refs/heads/master"
]
}
[08:52:27.319] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605775945768874",
"id": "8863215597849016928",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4495da77967c9da41b8d500f4f54d7171e18049a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605775946982414",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42988",
"builder:linux-chromeos-rel",
"buildset:commit/git/4495da77967c9da41b8d500f4f54d7171e18049a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4495da77967c9da41b8d500f4f54d7171e18049a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028645659897014720",
"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/8863215597849016928/+/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": "1605775946982544",
"url": "https://ci.chromium.org/b/8863215597849016928",
"utcnow_ts": "1605775947295858"
}
}
[08:52:27.319] Task URL: https://ci.chromium.org/b/8863215597849016928
[08:52:27.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:2:0) after 1m0s
[08:53:27.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:2:0)
[08:53:27.346] Timer tick, asking Buildbucket for the build status
[08:53:28.392] Build 8863215597849016928: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:28.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:3:0) after 1m0s
[08:53:50.018] Received PubSub notification, asking Buildbucket for the build status
[08:53:50.295] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:28.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:3:0)
[08:54:28.479] Timer tick, asking Buildbucket for the build status
[08:54:28.612] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:28.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:5:0) after 1m0s
[08:55:28.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:5:0)
[08:55:28.766] Timer tick, asking Buildbucket for the build status
[08:55:28.917] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:28.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:6:0) after 1m0s
[08:56:29.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:6:0)
[08:56:29.112] Timer tick, asking Buildbucket for the build status
[08:56:29.201] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:29.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:7:0) after 1m0s
[08:57:29.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:7:0)
[08:57:29.264] Timer tick, asking Buildbucket for the build status
[08:57:29.397] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:29.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:8:0) after 1m0s
[08:58:29.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:8:0)
[08:58:29.686] Timer tick, asking Buildbucket for the build status
[08:58:30.198] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:30.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:9:0) after 1m0s
[08:59:30.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:9:0)
[08:59:30.339] Timer tick, asking Buildbucket for the build status
[08:59:30.450] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:30.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:10:0) after 1m0s
[09:00:30.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:10:0)
[09:00:30.691] Timer tick, asking Buildbucket for the build status
[09:00:32.348] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:32.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:11:0) after 1m0s
[09:01:32.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:11:0)
[09:01:32.889] Timer tick, asking Buildbucket for the build status
[09:01:33.039] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:33.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:12:0) after 1m0s
[09:02:33.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:12:0)
[09:02:33.492] Timer tick, asking Buildbucket for the build status
[09:02:33.648] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:33.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:13:0) after 1m0s
[09:03:33.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:13:0)
[09:03:33.865] Timer tick, asking Buildbucket for the build status
[09:03:33.923] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:33.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:14:0) after 1m0s
[09:04:33.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:14:0)
[09:04:33.949] Timer tick, asking Buildbucket for the build status
[09:04:34.373] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:34.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:15:0) after 1m0s
[09:05:34.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:15:0)
[09:05:34.409] Timer tick, asking Buildbucket for the build status
[09:05:40.183] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:40.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:16:0) after 1m0s
[09:06:40.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:16:0)
[09:06:40.235] Timer tick, asking Buildbucket for the build status
[09:06:40.359] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:40.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:17:0) after 1m0s
[09:07:40.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:17:0)
[09:07:40.440] Timer tick, asking Buildbucket for the build status
[09:07:40.548] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:40.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:18:0) after 1m0s
[09:08:40.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:18:0)
[09:08:40.643] Timer tick, asking Buildbucket for the build status
[09:08:40.939] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:40.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:19:0) after 1m0s
[09:09:40.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:19:0)
[09:09:40.928] Timer tick, asking Buildbucket for the build status
[09:09:40.998] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:40.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:20:0) after 1m0s
[09:10:41.060] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:20:0)
[09:10:41.060] Timer tick, asking Buildbucket for the build status
[09:10:41.163] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:41.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:21:0) after 1m0s
[09:11:41.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:21:0)
[09:11:41.193] Timer tick, asking Buildbucket for the build status
[09:11:41.258] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:41.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:22:0) after 1m0s
[09:12:41.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:22:0)
[09:12:41.301] Timer tick, asking Buildbucket for the build status
[09:12:41.409] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:41.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:23:0) after 1m0s
[09:13:41.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:23:0)
[09:13:41.426] Timer tick, asking Buildbucket for the build status
[09:13:41.708] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:41.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:24:0) after 1m0s
[09:14:41.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:24:0)
[09:14:41.737] Timer tick, asking Buildbucket for the build status
[09:14:41.839] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:41.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:25:0) after 1m0s
[09:15:41.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:25:0)
[09:15:41.874] Timer tick, asking Buildbucket for the build status
[09:15:41.944] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:41.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:26:0) after 1m0s
[09:16:42.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:26:0)
[09:16:42.038] Timer tick, asking Buildbucket for the build status
[09:16:42.098] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:42.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:27:0) after 1m0s
[09:17:42.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:27:0)
[09:17:42.323] Timer tick, asking Buildbucket for the build status
[09:17:42.584] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:42.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:28:0) after 1m0s
[09:18:42.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:28:0)
[09:18:42.770] Timer tick, asking Buildbucket for the build status
[09:18:42.880] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:42.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:29:0) after 1m0s
[09:19:42.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:29:0)
[09:19:42.958] Timer tick, asking Buildbucket for the build status
[09:19:43.095] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:43.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:30:0) after 1m0s
[09:20:43.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:30:0)
[09:20:43.115] Timer tick, asking Buildbucket for the build status
[09:20:43.242] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:43.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:31:0) after 1m0s
[09:21:43.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:31:0)
[09:21:43.290] Timer tick, asking Buildbucket for the build status
[09:21:43.348] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:43.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:32:0) after 1m0s
[09:22:43.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:32:0)
[09:22:43.378] Timer tick, asking Buildbucket for the build status
[09:22:43.646] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:43.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:33:0) after 1m0s
[09:23:43.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:33:0)
[09:23:43.747] Timer tick, asking Buildbucket for the build status
[09:23:43.818] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:43.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:34:0) after 1m0s
[09:24:43.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:34:0)
[09:24:43.856] Timer tick, asking Buildbucket for the build status
[09:24:44.027] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:44.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:35:0) after 1m0s
[09:25:44.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:35:0)
[09:25:44.130] Timer tick, asking Buildbucket for the build status
[09:25:44.198] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:44.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:36:0) after 1m0s
[09:26:44.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:36:0)
[09:26:44.228] Timer tick, asking Buildbucket for the build status
[09:26:44.287] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:44.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:37:0) after 1m0s
[09:27:44.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:37:0)
[09:27:44.352] Timer tick, asking Buildbucket for the build status
[09:27:44.446] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:44.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:38:0) after 1m0s
[09:28:44.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:38:0)
[09:28:44.501] Timer tick, asking Buildbucket for the build status
[09:28:44.571] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:44.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:39:0) after 1m0s
[09:29:44.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:39:0)
[09:29:44.547] Timer tick, asking Buildbucket for the build status
[09:29:44.603] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:44.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:40:0) after 1m0s
[09:30:44.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:40:0)
[09:30:44.699] Timer tick, asking Buildbucket for the build status
[09:30:44.921] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:44.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:41:0) after 1m0s
[09:31:45.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:41:0)
[09:31:45.024] Timer tick, asking Buildbucket for the build status
[09:31:45.172] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:45.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:42:0) after 1m0s
[09:32:45.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:42:0)
[09:32:45.233] Timer tick, asking Buildbucket for the build status
[09:32:45.497] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:45.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:43:0) after 1m0s
[09:33:45.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:43:0)
[09:33:45.611] Timer tick, asking Buildbucket for the build status
[09:33:45.816] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:45.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:44:0) after 1m0s
[09:34:45.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:44:0)
[09:34:45.792] Timer tick, asking Buildbucket for the build status
[09:34:46.117] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:46.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:45:0) after 1m0s
[09:35:46.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:45:0)
[09:35:46.107] Timer tick, asking Buildbucket for the build status
[09:35:47.378] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:47.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:46:0) after 1m0s
[09:36:47.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:46:0)
[09:36:47.405] Timer tick, asking Buildbucket for the build status
[09:36:47.542] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:47.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:47:0) after 1m0s
[09:37:47.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:47:0)
[09:37:47.566] Timer tick, asking Buildbucket for the build status
[09:37:48.988] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:48.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:48:0) after 1m0s
[09:38:49.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:48:0)
[09:38:49.107] Timer tick, asking Buildbucket for the build status
[09:38:49.912] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:49.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:49:0) after 1m0s
[09:39:49.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:49:0)
[09:39:49.947] Timer tick, asking Buildbucket for the build status
[09:39:50.203] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:50.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:50:0) after 1m0s
[09:40:50.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:50:0)
[09:40:50.209] Timer tick, asking Buildbucket for the build status
[09:40:50.377] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:50.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:51:0) after 1m0s
[09:41:50.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:51:0)
[09:41:50.442] Timer tick, asking Buildbucket for the build status
[09:41:50.576] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:50.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:52:0) after 1m0s
[09:42:50.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:52:0)
[09:42:50.569] Timer tick, asking Buildbucket for the build status
[09:42:50.728] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:50.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:53:0) after 1m0s
[09:43:50.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:53:0)
[09:43:50.858] Timer tick, asking Buildbucket for the build status
[09:43:50.993] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:50.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:54:0) after 1m0s
[09:44:51.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:54:0)
[09:44:51.064] Timer tick, asking Buildbucket for the build status
[09:44:51.364] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:51.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:55:0) after 1m0s
[09:45:51.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:55:0)
[09:45:51.425] Timer tick, asking Buildbucket for the build status
[09:45:51.842] Build 8863215597849016928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:51.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028645659897014720:56:0) after 1m0s
[09:46:27.354] Received PubSub notification, asking Buildbucket for the build status
[09:46:27.814] Build 8863215597849016928: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:46:27.815] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605779185611883",
"created_by": "project:chromium",
"created_ts": "1605775945768874",
"failure_reason": "BUILD_FAILURE",
"id": "8863215597849016928",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4495da77967c9da41b8d500f4f54d7171e18049a\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SystemFeaturesPolicyTest.DisableCameraAfterInstall\\n\\n- ExtensionPolicyTest.ExtensionInstallBlocklistComponentApps\\n\\n- SystemFeaturesPolicyTest.DisableCameraBeforeInstall\"}, \"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\": \"4495da77967c9da41b8d500f4f54d7171e18049a\", \"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-19-g34z\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42988, \"got_angle_revision\": \"8d63a7fc492471fd9bc4781e6fb9a5ce38dfdcd9\", \"got_dawn_revision\": \"ce207be41fd6d9f1334ca6f251812511dc63faca\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"4495da77967c9da41b8d500f4f54d7171e18049a\", \"got_revision_cp\": \"refs/heads/master@{#829139}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"060020166679a265a2bc8250f4d78a7a8c29de89\", \"got_v8_revision\": \"8645ae9d96dc816b29ce5ff58430c8e8d07be20a\", \"got_v8_revision_cp\": \"refs/heads/8.9.12@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4495da77967c9da41b8d500f4f54d7171e18049a\", \"swarm_hashes_refs/heads/master(at){#829139}_without_patch\": {\"absl_hardening_tests\": \"bd2d4159f3dea406589dc350f49cf23456a90df2\", \"accessibility_unittests\": \"e566cb98ee53b8fcf525fe94039ba51a0600d40b\", \"angle_unittests\": \"69361fcf6456c1887ab9be19b00d7592258b15b8\", \"app_list_unittests\": \"11b336bdc5377bc6535ec0ed4b06113f9fdff1e1\", \"app_shell_unittests\": \"ec6379bfd96cd34d11d349b6916137147760cee2\", \"ash_unittests\": \"4a3889ddb7952cbb2f915e71a47f03c3da2dd378\", \"aura_unittests\": \"b387e06eb97790e6a42f11d4adb04d06da1c7fa0\", \"base_unittests\": \"be55f29c5be350224faa359bf7fa170d95b41bec\", \"base_util_unittests\": \"9384b3220c81525fe04a0d29dac67c068f16908c\", \"blink_common_unittests\": \"cec3de89781a36363888f6c477ca781bd0ade22d\", \"blink_fuzzer_unittests\": \"f4581ede2cf4cb091482f407eb0f6ce590c68641\", \"blink_heap_unittests\": \"19d65b1ee79398654b1fb9ee50fe216d0ea25371\", \"blink_platform_unittests\": \"529100cba13b10e56e7fde17782912558b69e8cc\", \"blink_unittests\": \"82dc09de654ddd375649021f99434c31ac81d17e\", \"boringssl_crypto_tests\": \"383f4b6701905f38cdc8c1942cd2fd1ac9507484\", \"boringssl_ssl_tests\": \"07056b14b90106454f906ac47fc6dd102825adc2\", \"browser_tests\": \"4997b058b7e5af70f9fe986e20e0626783b9f7f9\", \"capture_unittests\": \"d4e9971a77d2548dcf9899d38f71badc7db7af23\", \"cast_unittests\": \"dc61b682c5f6675072852949cfd1a946314baf90\", \"cc_unittests\": \"74a6ce797c21559637169f10b5d49d5a58911607\", \"chrome_app_unittests\": \"2bdb0dcbfcd81cf2e2338eca22869da2cf19219c\", \"chromedriver_unittests\": \"74b36ba9ad31a5312506ec4e28bd8114952c0ce2\", \"chromeos_components_unittests\": \"0032c15e78acde754b9ba8e6da546ddd78b5665f\", \"chromeos_unittests\": \"96a89626eda554f907cee7b6ee47e1e21ede7a5e\", \"components_browsertests\": \"dfd85bb37f6f4b4518d6270742e1853a576140fc\", \"components_unittests\": \"5d8aa3c52fe49c471fbf75207c8df632b8e0b7d0\", \"compositor_unittests\": \"a0f2550b9cde2ed8f8b79ad4c87162b430775c5c\", \"content_browsertests\": \"2b05b5f822c8749831315d5535c3c9b8ebdda96b\", \"content_unittests\": \"947d0638b5f0b3bb7eba15053808ff61dc7f6849\", \"crashpad_tests\": \"d377ec6566ddb9d007935d44bb8afa7947422742\", \"crypto_unittests\": \"bea7514263e032456676236d7d0ad0973469bf4d\", \"dbus_unittests\": \"5f968fdafdbc286c2ef5a849c0a1b87d06f56981\", \"device_unittests\": \"e3a5cc61233a142e7e1fa403e8fa2cf43bfb4786\", \"display_unittests\": \"7e094494148beb0d700b0f5a69e64e87f820c87f\", \"events_unittests\": \"de015065c0a03ee30fb373ac31a582fcaa0ba398\", \"exo_unittests\": \"2e8c9d924fcf42c54e17d0d0c058eb178ab6dc59\", \"extensions_browsertests\": \"73fb027d239b2c9dcedd898f4768a8195c68229c\", \"extensions_unittests\": \"7c533894d11c87eaca23cf143c688b41e8887f66\", \"filesystem_service_unittests\": \"5391bed695c332acf249acfb136bc08b5a2eb6bf\", \"gcm_unit_tests\": \"d05a53c59a9a5c3ddd6aebd457339304ff290456\", \"gfx_unittests\": \"cde07ced559bc0ed893b5738752976b35432c79e\", \"gin_unittests\": \"bbc2c2292a484c182f10d2f77f3c1daee51ff4ff\", \"gl_unittests_ozone\": \"ebd4b803ee6c475f0bcccef24326fd8b16978965\", \"google_apis_unittests\": \"23745bbe4ad1cf48649d66d124429c0da609ecf9\", \"gpu_unittests\": \"d47c69633bbfda194afd5c374c40d138e155dae1\", \"gwp_asan_unittests\": \"748f8e77209f6a45e0890e54ed74bb915111c8c4\", \"interactive_ui_tests\": \"eb11a5c25fa112c6aec67b27761be6f4b0daa4af\", \"ipc_tests\": \"6faf42677ceccb390d4842825f449b559be8bfa1\", \"jingle_unittests\": \"32a7ef19b9ad37c1cf5f097c49bfee942005bb69\", \"keyboard_unittests\": \"de39ed8ab07482a327f5276ae309ede3ce19d5df\", \"latency_unittests\": \"2fd8787023da8614dfccd04809f207ce4adce214\", \"libjingle_xmpp_unittests\": \"e1b8fdf15a29c6444039df647b4e8a4c2777c9f7\", \"liburlpattern_unittests\": \"2f1a168b8df191f503ad8c748068f3072c100730\", \"media_blink_unittests\": \"14d444837dfdceedd012d0e62c960deb3ee31f62\", \"media_unittests\": \"cf012e690e51999e33317443dade3cc8b032e25f\", \"message_center_unittests\": \"4a463db4b7edc165234d18d311e872678cdee87c\", \"midi_unittests\": \"42aa6634429d0bf36249e3c75fc64d4ae3a5755c\", \"mojo_core_unittests\": \"204d3c6c4f1b03d1b3d090adcbd3e5b680d864b0\", \"mojo_unittests\": \"ab84c2bb34590506e14c56b2c2897775bb60edcb\", \"nacl_helper_nonsfi_unittests\": \"7dc01aab50693d27a4831a6b34ccd18241b17e74\", \"nacl_loader_unittests\": \"6e27da7ba55116bc1c5838adbbc9d19bdc307589\", \"native_theme_unittests\": \"1112d9c8b5659bb35e12ab1e1614558b1a403499\", \"net_unittests\": \"7256b720ff2c11e61e8c1d28e49d8b862877baa4\", \"ozone_gl_unittests\": \"93f892a75db5661614cec923a931d5b97f1589e5\", \"ozone_unittests\": \"26a22ff102299282b507e1357c502cf0825fe194\", \"ozone_x11_unittests\": \"a2d9f2ba9f8908afce32f6ea236fc3289708ee14\", \"pdf_unittests\": \"289416b4a5b84e6b192eef0a317684fe1394b91c\", \"perfetto_unittests\": \"8445ecea7059a3b145c256986fc2ca221494a0f4\", \"ppapi_unittests\": \"5beab4caff55a09bd1019dd1c6202bd5b9e2401d\", \"printing_unittests\": \"a230279ad229d6b1e62bf0ca84528c255fff90ce\", \"remoting_unittests\": \"d6595419fb4c17c22bb49a77a5912de871886ae4\", \"sandbox_linux_unittests\": \"40801ded6fa2477102a81cb018c23f7f4e7b88aa\", \"service_manager_unittests\": \"f933d26d719c9bbb2bd73c0a3796f2afb88d646f\", \"services_unittests\": \"dd8c668871c5e5bd1ce4f573a6a11c4570a6933b\", \"shell_dialogs_unittests\": \"c17925d27351fbd52b508e2aa034c750e9a63e29\", \"skia_unittests\": \"d64777cab964a68b36403cbfd254bdd9793b5c3d\", \"snapshot_unittests\": \"23e4c87b65ea2412799ff90ac6f1fbed30d389bc\", \"sql_unittests\": \"6ad0a73eb1bda4ebfc96586711dc24dad3357a4d\", \"storage_unittests\": \"47bf7a9783d2e56be57eed362af782365466f1c5\", \"sync_integration_tests\": \"8580f7503dc97428e0e1334919fcf0d503e1bcc9\", \"traffic_annotation_auditor_unittests\": \"8e9c684bc7155c922179ed6c4548b731fddccba8\", \"ui_base_unittests\": \"2cb68b5cece0c32173fecd5dad7c7a8c341ceb8b\", \"ui_chromeos_unittests\": \"49e198a514acb411466a39ac7014400656342c42\", \"ui_touch_selection_unittests\": \"f11539f90728fd19ea28a8ddc70ead013c8364a6\", \"unit_tests\": \"d1c64f3b0b3dbf684816bef5d8ce7397bf1eb14c\", \"url_unittests\": \"7af030c87564bd07dc0565de7dd5546dc0897a19\", \"usage_time_limit_unittests\": \"0a7f989873560e88fd0295a6fa9c9290fb70611c\", \"views_unittests\": \"76946f4d954a62153aca8dc6017577224f4a98bc\", \"viz_unittests\": \"587b8d981757cc3161f3ee6cf640e0d04b3105cd\", \"wayland_client_perftests\": \"c462183db5c8141e6e79d4e1cf055d9f4bdc0b4e\", \"wm_unittests\": \"37ea06c7def67278220b9d9ccb0be66ba28cacc4\", \"wtf_unittests\": \"1a2d96767e087aeb7b882266dadf99bdad012282\", \"zlib_unittests\": \"74b69c727e2f97db922677bbab83581104f2ecf5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093d924ecb526dae9a1dcf9db4725f8944eec2b4c0f2ddc6c2ac4a04802f7f15_v2\", \"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_76783dd5c2a60df34bfc78d9ceb12670a24fdda597cce6f387a1a4911c7cdf2d_v2\", \"builder_8afdcfef4ae6fecf44bdc59bc36ab1bd30dfc75b00b262fd5e6b3421af127659_v2\", \"builder_8f7e40299f74ff9d94767563834f98f87bf45cac7401cd036aab5fd865cb3b58_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_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-19-g34z\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"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\": [\"5637-4d8b832\"], \"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- SystemFeaturesPolicyTest.DisableCameraAfterInstall\\n\\n- ExtensionPolicyTest.ExtensionInstallBlocklistComponentApps\\n\\n- SystemFeaturesPolicyTest.DisableCameraBeforeInstall\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605776029168766",
"status": "COMPLETED",
"status_changed_ts": "1605776029634130",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42988",
"builder:linux-chromeos-rel",
"buildset:commit/git/4495da77967c9da41b8d500f4f54d7171e18049a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4495da77967c9da41b8d500f4f54d7171e18049a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028645659897014720",
"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/8863215597849016928/+/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:4ff885ff56c46e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605779186943800",
"url": "https://ci.chromium.org/b/8863215597849016928",
"utcnow_ts": "1605779187743522"
}
}
[09:46:27.815] Invocation finished in 54m3.383534168s with status FAILED