[19:24:06.277] New invocation is queued and will start shortly
[19:24:07.464] Starting the invocation (attempt 1)
[19:24:07.512] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:24:07.512] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:24:07.512] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026793977620986976",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5110aed3a176c4fc466f7224c40c031c68a92468\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9026793977620986976",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5110aed3a176c4fc466f7224c40c031c68a92468",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5110aed3a176c4fc466f7224c40c031c68a92468",
"gitiles_ref:refs/heads/master"
]
}
[19:24:08.350] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607541847649234",
"id": "8861363915517668448",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5110aed3a176c4fc466f7224c40c031c68a92468\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607541848179650",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43581",
"builder:linux-chromeos-rel",
"buildset:commit/git/5110aed3a176c4fc466f7224c40c031c68a92468",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5110aed3a176c4fc466f7224c40c031c68a92468",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026793977620986976",
"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/8861363915517668448/+/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": "1607541848179975",
"url": "https://ci.chromium.org/b/8861363915517668448",
"utcnow_ts": "1607541848335582"
}
}
[19:24:08.350] Task URL: https://ci.chromium.org/b/8861363915517668448
[19:24:08.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:2:0) after 1m0s
[19:24:32.769] Received PubSub notification, asking Buildbucket for the build status
[19:24:33.459] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:08.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:2:0)
[19:25:08.484] Timer tick, asking Buildbucket for the build status
[19:25:08.614] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:08.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:4:0) after 1m0s
[19:26:09.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:4:0)
[19:26:09.047] Timer tick, asking Buildbucket for the build status
[19:26:09.168] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:09.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:5:0) after 1m0s
[19:27:09.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:5:0)
[19:27:09.294] Timer tick, asking Buildbucket for the build status
[19:27:09.677] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:09.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:6:0) after 1m0s
[19:28:09.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:6:0)
[19:28:09.764] Timer tick, asking Buildbucket for the build status
[19:28:10.016] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:10.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:7:0) after 1m0s
[19:29:10.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:7:0)
[19:29:10.087] Timer tick, asking Buildbucket for the build status
[19:29:10.379] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:10.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:8:0) after 1m0s
[19:30:10.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:8:0)
[19:30:10.428] Timer tick, asking Buildbucket for the build status
[19:30:10.570] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:10.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:9:0) after 1m0s
[19:31:10.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:9:0)
[19:31:10.672] Timer tick, asking Buildbucket for the build status
[19:31:13.600] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:13.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:10:0) after 1m0s
[19:32:13.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:10:0)
[19:32:13.598] Timer tick, asking Buildbucket for the build status
[19:32:13.670] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:13.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:11:0) after 1m0s
[19:33:13.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:11:0)
[19:33:13.871] Timer tick, asking Buildbucket for the build status
[19:33:15.938] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:15.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:12:0) after 1m0s
[19:34:16.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:12:0)
[19:34:16.042] Timer tick, asking Buildbucket for the build status
[19:34:16.193] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:16.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:13:0) after 1m0s
[19:35:16.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:13:0)
[19:35:16.327] Timer tick, asking Buildbucket for the build status
[19:35:16.395] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:16.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:14:0) after 1m0s
[19:36:16.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:14:0)
[19:36:16.642] Timer tick, asking Buildbucket for the build status
[19:36:16.717] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:16.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:15:0) after 1m0s
[19:37:16.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:15:0)
[19:37:16.877] Timer tick, asking Buildbucket for the build status
[19:37:16.944] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:16.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:16:0) after 1m0s
[19:38:17.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:16:0)
[19:38:17.185] Timer tick, asking Buildbucket for the build status
[19:38:17.247] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:17.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:17:0) after 1m0s
[19:39:17.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:17:0)
[19:39:17.354] Timer tick, asking Buildbucket for the build status
[19:39:17.659] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:17.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:18:0) after 1m0s
[19:40:17.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:18:0)
[19:40:17.759] Timer tick, asking Buildbucket for the build status
[19:40:17.860] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:17.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:19:0) after 1m0s
[19:41:17.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:19:0)
[19:41:17.970] Timer tick, asking Buildbucket for the build status
[19:41:18.085] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:18.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:20:0) after 1m0s
[19:42:18.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:20:0)
[19:42:18.155] Timer tick, asking Buildbucket for the build status
[19:42:18.234] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:18.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:21:0) after 1m0s
[19:43:18.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:21:0)
[19:43:18.335] Timer tick, asking Buildbucket for the build status
[19:43:18.451] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:18.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:22:0) after 1m0s
[19:44:18.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:22:0)
[19:44:18.449] Timer tick, asking Buildbucket for the build status
[19:44:18.883] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:18.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:23:0) after 1m0s
[19:45:18.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:23:0)
[19:45:18.924] Timer tick, asking Buildbucket for the build status
[19:45:19.005] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:19.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:24:0) after 1m0s
[19:46:19.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:24:0)
[19:46:19.065] Timer tick, asking Buildbucket for the build status
[19:46:19.231] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:19.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:25:0) after 1m0s
[19:47:19.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:25:0)
[19:47:19.262] Timer tick, asking Buildbucket for the build status
[19:47:19.416] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:19.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:26:0) after 1m0s
[19:48:19.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:26:0)
[19:48:19.423] Timer tick, asking Buildbucket for the build status
[19:48:19.697] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:19.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:27:0) after 1m0s
[19:49:19.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:27:0)
[19:49:19.713] Timer tick, asking Buildbucket for the build status
[19:49:20.270] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:20.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:28:0) after 1m0s
[19:50:20.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:28:0)
[19:50:20.301] Timer tick, asking Buildbucket for the build status
[19:50:20.361] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:20.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:29:0) after 1m0s
[19:51:20.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:29:0)
[19:51:20.384] Timer tick, asking Buildbucket for the build status
[19:51:20.482] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:20.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:30:0) after 1m0s
[19:52:20.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:30:0)
[19:52:20.539] Timer tick, asking Buildbucket for the build status
[19:52:20.608] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:20.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:31:0) after 1m0s
[19:53:20.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:31:0)
[19:53:20.644] Timer tick, asking Buildbucket for the build status
[19:53:20.709] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:20.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:32:0) after 1m0s
[19:54:20.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:32:0)
[19:54:20.733] Timer tick, asking Buildbucket for the build status
[19:54:20.852] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:20.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:33:0) after 1m0s
[19:55:20.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:33:0)
[19:55:20.872] Timer tick, asking Buildbucket for the build status
[19:55:20.965] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:20.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:34:0) after 1m0s
[19:56:20.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:34:0)
[19:56:20.996] Timer tick, asking Buildbucket for the build status
[19:56:21.136] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:21.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:35:0) after 1m0s
[19:57:21.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:35:0)
[19:57:21.171] Timer tick, asking Buildbucket for the build status
[19:57:21.423] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:21.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:36:0) after 1m0s
[19:58:21.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:36:0)
[19:58:21.467] Timer tick, asking Buildbucket for the build status
[19:58:21.533] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:21.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:37:0) after 1m0s
[19:59:21.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:37:0)
[19:59:21.600] Timer tick, asking Buildbucket for the build status
[19:59:21.788] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:21.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:38:0) after 1m0s
[20:00:21.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:38:0)
[20:00:21.818] Timer tick, asking Buildbucket for the build status
[20:00:22.189] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:22.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:39:0) after 1m0s
[20:01:22.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:39:0)
[20:01:22.174] Timer tick, asking Buildbucket for the build status
[20:01:22.671] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:22.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:40:0) after 1m0s
[20:02:22.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:40:0)
[20:02:22.720] Timer tick, asking Buildbucket for the build status
[20:02:22.784] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:22.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:41:0) after 1m0s
[20:03:22.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:41:0)
[20:03:22.808] Timer tick, asking Buildbucket for the build status
[20:03:22.878] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:22.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:42:0) after 1m0s
[20:04:22.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:42:0)
[20:04:22.905] Timer tick, asking Buildbucket for the build status
[20:04:23.010] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:23.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:43:0) after 1m0s
[20:05:23.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:43:0)
[20:05:23.119] Timer tick, asking Buildbucket for the build status
[20:05:23.183] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:23.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:44:0) after 1m0s
[20:06:23.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:44:0)
[20:06:23.227] Timer tick, asking Buildbucket for the build status
[20:06:23.330] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:23.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:45:0) after 1m0s
[20:07:23.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:45:0)
[20:07:23.375] Timer tick, asking Buildbucket for the build status
[20:07:23.657] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:23.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:46:0) after 1m0s
[20:08:23.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:46:0)
[20:08:23.690] Timer tick, asking Buildbucket for the build status
[20:08:23.762] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:23.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:47:0) after 1m0s
[20:09:23.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:47:0)
[20:09:23.782] Timer tick, asking Buildbucket for the build status
[20:09:23.910] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:23.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:48:0) after 1m0s
[20:10:23.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:48:0)
[20:10:23.991] Timer tick, asking Buildbucket for the build status
[20:10:24.139] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:24.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:49:0) after 1m0s
[20:11:24.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:49:0)
[20:11:24.223] Timer tick, asking Buildbucket for the build status
[20:11:24.391] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:24.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:50:0) after 1m0s
[20:12:24.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:50:0)
[20:12:24.490] Timer tick, asking Buildbucket for the build status
[20:12:24.615] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:24.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:51:0) after 1m0s
[20:13:24.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:51:0)
[20:13:24.676] Timer tick, asking Buildbucket for the build status
[20:13:24.870] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:24.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:52:0) after 1m0s
[20:14:24.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:52:0)
[20:14:24.913] Timer tick, asking Buildbucket for the build status
[20:14:25.083] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:25.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:53:0) after 1m0s
[20:15:25.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:53:0)
[20:15:25.233] Timer tick, asking Buildbucket for the build status
[20:15:25.373] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:25.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:54:0) after 1m0s
[20:16:25.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:54:0)
[20:16:25.418] Timer tick, asking Buildbucket for the build status
[20:16:25.851] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:25.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:55:0) after 1m0s
[20:17:25.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:55:0)
[20:17:25.892] Timer tick, asking Buildbucket for the build status
[20:17:26.016] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:26.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:56:0) after 1m0s
[20:18:26.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:56:0)
[20:18:26.046] Timer tick, asking Buildbucket for the build status
[20:18:26.205] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:26.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:57:0) after 1m0s
[20:19:26.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:57:0)
[20:19:26.267] Timer tick, asking Buildbucket for the build status
[20:19:26.629] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:26.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:58:0) after 1m0s
[20:20:26.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:58:0)
[20:20:26.658] Timer tick, asking Buildbucket for the build status
[20:20:26.761] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:26.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:59:0) after 1m0s
[20:21:26.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:59:0)
[20:21:26.803] Timer tick, asking Buildbucket for the build status
[20:21:27.009] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:27.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:60:0) after 1m0s
[20:22:27.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:60:0)
[20:22:27.057] Timer tick, asking Buildbucket for the build status
[20:22:27.216] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:27.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:61:0) after 1m0s
[20:23:27.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:61:0)
[20:23:27.284] Timer tick, asking Buildbucket for the build status
[20:23:27.424] Build 8861363915517668448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:27.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9026793977620986976:62:0) after 1m0s
[20:23:58.349] Received PubSub notification, asking Buildbucket for the build status
[20:23:59.489] Build 8861363915517668448: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:23:59.489] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607545436933774",
"created_by": "project:chromium",
"created_ts": "1607541847649234",
"failure_reason": "BUILD_FAILURE",
"id": "8861363915517668448",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5110aed3a176c4fc466f7224c40c031c68a92468\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- FileDialog/FilesAppBrowserTest.Test/openFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/saveFileDialogDefaultFilter\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"5110aed3a176c4fc466f7224c40c031c68a92468\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-12-79fr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43581, \"got_angle_revision\": \"f0f79e08366208f15f05c909bc7b81a1198e261f\", \"got_dawn_revision\": \"99d6c14a3b34a370760caea4d408e0d6c194ed56\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"5110aed3a176c4fc466f7224c40c031c68a92468\", \"got_revision_cp\": \"refs/heads/master@{#835299}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"eb597ff5b7f6a78eb7601b4c32e8d43b119ef3e8\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"5110aed3a176c4fc466f7224c40c031c68a92468\", \"swarm_hashes_refs/heads/master(at){#835299}_without_patch\": {\"absl_hardening_tests\": \"7b9aac37a39cba899b78adada21529a5f5aacd93\", \"accessibility_unittests\": \"4a88c527046d669e4ada1868ae039281e974f391\", \"angle_unittests\": \"6e11ddd1f6a703a24ba1daf9ea350a4f1ef87ee9\", \"app_list_unittests\": \"92eb9217695d57ac4445b04fdb3e93a147dadc57\", \"app_shell_unittests\": \"3ff1b2c859f10ea4c30b6b8816441ab7dc7bc3a1\", \"ash_unittests\": \"7553c47c5355f4c3ad0ce4ab465e983a21f8b86e\", \"aura_unittests\": \"28930047cfe5bc8235dbb66f609d305849915ef4\", \"base_unittests\": \"32028857c66e447827d5307b9d1a9f8d4cced17b\", \"base_util_unittests\": \"d59d7f6a1cbd1678ca353232a44d96e627c6f6ee\", \"blink_common_unittests\": \"5b399b3d4e050d06e77dfef8968921e751025206\", \"blink_fuzzer_unittests\": \"4a68aad28136b01baba168297e3a85381a4dfffe\", \"blink_heap_unittests\": \"a2e988a9d4429b4037f124a48bd23ad17dbc34b4\", \"blink_platform_unittests\": \"fd615a291ed5e132ebfa36a578b31fd39cb42be1\", \"blink_unittests\": \"cb31f40441c757bea3abe7e02c01116f3acb550f\", \"boringssl_crypto_tests\": \"40c68e495ba84a91392c27105d34411bc0ae63e5\", \"boringssl_ssl_tests\": \"d0ce2a7f80eb5a5359553dbfaccd9236a32f42bf\", \"browser_tests\": \"ccc6bf6746488c4e6bbcb50c7ca0ed1c195d84d1\", \"capture_unittests\": \"a5be0c1d9b63e29aeac840f7cda04e9e80494a6c\", \"cast_unittests\": \"50ebdf806f09864829af952e232c34371f662952\", \"cc_unittests\": \"b74e9267b46cf5eea94f6901577e23898f2a9c52\", \"chrome_app_unittests\": \"4c2f9955b85282c76e97d55c3a7df9260d66071a\", \"chromedriver_unittests\": \"a4434205e6e4e68bcbd5608de18694afd30a9c5c\", \"chromeos_components_unittests\": \"6913e42d4c9831870d9388c6f05f94a67622ac71\", \"chromeos_unittests\": \"424cf70aeef63dd14e8524d6fa4bceb8ccd6ca60\", \"components_browsertests\": \"8d1c994c3910c445ee94dacfa0fb62d68785b73e\", \"components_unittests\": \"48f9f73e118267eb73e18120bb6146314a8931e5\", \"compositor_unittests\": \"138bf1f68dc66ac25a9cf191224a7b0a6e798705\", \"content_browsertests\": \"e32ec1c6429fffb35d1bbfa71e14e6c23042c338\", \"content_unittests\": \"d4edbc9a30c011afb31dee709aac9a9e5bfe74b8\", \"crashpad_tests\": \"8436e21ee8255274ef3c83b2163afa25a8ff6c35\", \"crypto_unittests\": \"52a76800df8ebcb5efaec57fb7bcfc324606d90f\", \"dbus_unittests\": \"fd62c968eafff08c3cf235ffc8a4ec7df9b07cb6\", \"device_unittests\": \"0d220e54f8882861bff3e7ba1114643ee2c69805\", \"display_unittests\": \"a97adb4dea7f61e53d2f2cc4597aace2e400f072\", \"events_unittests\": \"3bc890ce6093f6f1b00620b2c3329248fce91b53\", \"exo_unittests\": \"f1a8e5a1ca35301bbe9fbfd19c7ff947af87b657\", \"extensions_browsertests\": \"060933541d4fdd66d950f7eab980d5fb0eea8893\", \"extensions_unittests\": \"5a140b7400083a3da5bc9a174ba3968fc32b21d6\", \"filesystem_service_unittests\": \"c19db737c21ad5a3113797f9d84725adcebd7970\", \"gcm_unit_tests\": \"09e4fce053cd0c814ae0fbde2f21c4a634f88aa9\", \"gfx_unittests\": \"f46368eb09ef4772475eec7f20f7035ceee1d3c9\", \"gin_unittests\": \"757c195719b75406881d088f399e889879291867\", \"gl_unittests_ozone\": \"9df5bd1c2e90bbb6b4543b132938c7d53a3cad72\", \"google_apis_unittests\": \"ed29bbf1781460af11bc829488288d6d2495dc46\", \"gpu_unittests\": \"7a1c50bc09264df0f04944f7878c1519dea2b14c\", \"gwp_asan_unittests\": \"078fdc738760bcc45c9b2f332fbc208c6d610fa5\", \"interactive_ui_tests\": \"3144eae4740c7af1cbe11cfe43dff5a4e3c588ec\", \"ipc_tests\": \"b65fcac285fc42b543fa04f3858e1780b9456659\", \"jingle_unittests\": \"ad4cd6a553ef59c56ba7a5b4d4d4077ca384e91a\", \"keyboard_unittests\": \"ce846ea52eb40217cb2a027b7b35fd366427fa5f\", \"latency_unittests\": \"c61f35561751b47def10fb43f60ff08d3fc8a027\", \"libjingle_xmpp_unittests\": \"bfeb137c15d89e6fd21dbfe6e9246233713a5c44\", \"liburlpattern_unittests\": \"2d6d61975a96629abb149947ead52044f2481852\", \"media_blink_unittests\": \"aff62ef76ba4891551dbda97945477b58589c12d\", \"media_unittests\": \"e9b46bc6d5c1eb208b070f91294323a3f8dfef4b\", \"message_center_unittests\": \"1a5931ea5b227be2a715d87023c9b3d8203a7a8d\", \"midi_unittests\": \"b574c0c07978285756b796a46aff3a8aabf0db4c\", \"mojo_core_unittests\": \"0a00566b92bbac1e4c579f616d21098c8aae01b3\", \"mojo_unittests\": \"f8d76d0bd0c57470f94b33b5165e10c037ad43c8\", \"nacl_helper_nonsfi_unittests\": \"d38dfea63c0412282d59405298403bc7456c05db\", \"nacl_loader_unittests\": \"4da695d0b9ca9887f2714c6e296fd9685b4c94eb\", \"native_theme_unittests\": \"f49dd7e78b81d96a1c041929202c77059607030a\", \"net_unittests\": \"cefc7513102d6d457c9925db061f3518cce4dabd\", \"ozone_gl_unittests\": \"b2744b7eec2179d050858cee2e8e4eb6fc472166\", \"ozone_unittests\": \"08e2a8d0de41b29b6e5097213f9146c72d9e9c7e\", \"ozone_x11_unittests\": \"fa2ea0ffd3670346d64f65180588b922ccc1df7e\", \"pdf_unittests\": \"e80b0666b6aac371fb2164ac267423d874b8fd1a\", \"perfetto_unittests\": \"5142b8e03846dc994cad09336a0af060e6365d85\", \"ppapi_unittests\": \"966adcc992b82117f66cda9a986a511ea494e3b5\", \"printing_unittests\": \"7552ba54e12c6690978f19c726e012f965b01dba\", \"remoting_unittests\": \"ff0b53c97a4d089a00a0f4492a6c91e062f48011\", \"sandbox_linux_unittests\": \"9816e60c3ae2f8da1b1e2baffdbefb40f22cadac\", \"service_manager_unittests\": \"35611acdd5540e1d101d7d372c9636a58c2c5454\", \"services_unittests\": \"7bdd0294c93913423ab9d411f71e01b1e45b0500\", \"shell_dialogs_unittests\": \"f4311eeec5e3e7a9cc5c37e09634435805e304d8\", \"shell_encryption_unittests\": \"ee0a568197ead82b6f6578c367056a286ff9f4e0\", \"skia_unittests\": \"df8f3bd5a1829dfdee93af35df5e0c93c958a0db\", \"snapshot_unittests\": \"e7aa24ec9e94d4e3a416c2e013fc008e436d0079\", \"sql_unittests\": \"4180972210faa1b364263579a254eb6e9657dfdb\", \"storage_unittests\": \"850041fb75e9e08440a3d0e203c0a87c9003ee0e\", \"sync_integration_tests\": \"07dc0ebbd6ac4ac411f29f1b9e8758e70408836d\", \"traffic_annotation_auditor_unittests\": \"149b560e282e9943b4dec54de716f90457126cae\", \"ui_base_unittests\": \"8ed4fe7c56abf46fc4f14b084850e688d3e72d21\", \"ui_chromeos_unittests\": \"3b27de7fd5706d303002e47685b8d5b2caacdb5d\", \"ui_touch_selection_unittests\": \"3c8843c1af16ac055ee8137e7aeac4e7e8031246\", \"unit_tests\": \"7ba89e2f5ebd0b65a6e39b223dc5968da800da52\", \"url_unittests\": \"9da369b623128604bd8880aeea63ca54cb3003dd\", \"usage_time_limit_unittests\": \"64cb04e75b96191c78be675a2c92e4348faf442e\", \"views_unittests\": \"d571153e6fcb509feba7e46d16d815c596ae9bc8\", \"viz_unittests\": \"a1080f0cd09d776b440bdbe18bb9d5e4ef5d59b3\", \"wayland_client_perftests\": \"2a6e0676a2ad76a0d0a30e7e2a0c5bea583def72\", \"wm_unittests\": \"fa0a7ea919b57070e44d52a0cb219d34c90535a5\", \"wtf_unittests\": \"796a2f0e2f858eb9e1e4de1f266b31bd421ac5c7\", \"zlib_unittests\": \"929fc6c9e488a63a9c900f835f4903ef9f6a2bc9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_08506e22a0e0fdc29bd318165699b599f0671cf91287ea7a96642b02d7ff24e4_v2\", \"builder_219e0ffc8c25f5e0b87c50b06543131c770ba4c60d610054ab97561314494a41_v2\", \"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-12-79fr\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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\": [\"5640-d0eb544\"], \"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- FileDialog/FilesAppBrowserTest.Test/openFileDialogDefaultFilter\\n\\n- FileDialog/FilesAppBrowserTest.Test/saveFileDialogDefaultFilter\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607541871523203",
"status": "COMPLETED",
"status_changed_ts": "1607541872417259",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43581",
"builder:linux-chromeos-rel",
"buildset:commit/git/5110aed3a176c4fc466f7224c40c031c68a92468",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5110aed3a176c4fc466f7224c40c031c68a92468",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026793977620986976",
"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/8861363915517668448/+/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:5061c782e2cd1410",
"user_agent:luci-scheduler"
],
"updated_ts": "1607545437869299",
"url": "https://ci.chromium.org/b/8861363915517668448",
"utcnow_ts": "1607545438517749"
}
}
[20:23:59.489] Invocation finished in 59m53.229323923s with status FAILED