[14:44:24.154] New invocation is queued and will start shortly
[14:44:25.191] Starting the invocation (attempt 1)
[14:44:25.225] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:44:25.225] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:44:25.225] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019292026738786768",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0ba9af69f17d98936091451a9f7ec0ffd81f0bc8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019292026738786768",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/0ba9af69f17d98936091451a9f7ec0ffd81f0bc8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ba9af69f17d98936091451a9f7ec0ffd81f0bc8",
"gitiles_ref:refs/heads/master"
]
}
[14:44:26.060] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614696265296112",
"id": "8853861964879379600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ba9af69f17d98936091451a9f7ec0ffd81f0bc8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614696265848418",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45843",
"builder:linux-chromeos-rel",
"buildset:commit/git/0ba9af69f17d98936091451a9f7ec0ffd81f0bc8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ba9af69f17d98936091451a9f7ec0ffd81f0bc8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019292026738786768",
"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/8853861964879379600/+/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": "1614696265848527",
"url": "https://ci.chromium.org/b/8853861964879379600",
"utcnow_ts": "1614696266045645"
}
}
[14:44:26.060] Task URL: https://ci.chromium.org/b/8853861964879379600
[14:44:26.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:2:0) after 1m0s
[14:44:43.130] Received PubSub notification, asking Buildbucket for the build status
[14:44:43.269] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:26.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:2:0)
[14:45:26.136] Timer tick, asking Buildbucket for the build status
[14:45:26.265] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:26.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:4:0) after 1m0s
[14:46:26.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:4:0)
[14:46:26.336] Timer tick, asking Buildbucket for the build status
[14:46:26.620] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:26.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:5:0) after 1m0s
[14:47:26.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:5:0)
[14:47:26.706] Timer tick, asking Buildbucket for the build status
[14:47:29.354] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:29.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:6:0) after 1m0s
[14:48:29.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:6:0)
[14:48:29.424] Timer tick, asking Buildbucket for the build status
[14:48:29.535] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:29.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:7:0) after 1m0s
[14:49:29.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:7:0)
[14:49:29.615] Timer tick, asking Buildbucket for the build status
[14:49:29.809] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:29.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:8:0) after 1m0s
[14:50:29.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:8:0)
[14:50:29.794] Timer tick, asking Buildbucket for the build status
[14:50:30.560] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:30.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:9:0) after 1m0s
[14:51:30.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:9:0)
[14:51:30.786] Timer tick, asking Buildbucket for the build status
[14:51:30.854] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:30.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:10:0) after 1m0s
[14:52:31.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:10:0)
[14:52:31.032] Timer tick, asking Buildbucket for the build status
[14:52:31.103] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:31.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:11:0) after 1m0s
[14:53:31.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:11:0)
[14:53:31.223] Timer tick, asking Buildbucket for the build status
[14:53:31.338] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:31.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:12:0) after 1m0s
[14:54:31.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:12:0)
[14:54:31.372] Timer tick, asking Buildbucket for the build status
[14:54:31.692] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:31.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:13:0) after 1m0s
[14:55:31.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:13:0)
[14:55:31.709] Timer tick, asking Buildbucket for the build status
[14:55:31.956] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:31.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:14:0) after 1m0s
[14:56:31.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:14:0)
[14:56:31.973] Timer tick, asking Buildbucket for the build status
[14:56:32.111] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:32.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:15:0) after 1m0s
[14:57:32.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:15:0)
[14:57:32.127] Timer tick, asking Buildbucket for the build status
[14:57:32.457] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:32.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:16:0) after 1m0s
[14:58:32.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:16:0)
[14:58:32.528] Timer tick, asking Buildbucket for the build status
[14:58:32.597] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:32.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:17:0) after 1m0s
[14:59:32.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:17:0)
[14:59:32.627] Timer tick, asking Buildbucket for the build status
[14:59:32.706] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:32.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:18:0) after 1m0s
[15:00:32.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:18:0)
[15:00:32.696] Timer tick, asking Buildbucket for the build status
[15:00:32.874] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:32.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:19:0) after 1m0s
[15:01:32.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:19:0)
[15:01:32.926] Timer tick, asking Buildbucket for the build status
[15:01:33.270] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:33.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:20:0) after 1m0s
[15:02:33.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:20:0)
[15:02:33.278] Timer tick, asking Buildbucket for the build status
[15:02:33.514] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:33.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:21:0) after 1m0s
[15:03:33.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:21:0)
[15:03:33.575] Timer tick, asking Buildbucket for the build status
[15:03:34.613] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:34.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:22:0) after 1m0s
[15:04:34.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:22:0)
[15:04:34.617] Timer tick, asking Buildbucket for the build status
[15:04:34.698] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:34.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:23:0) after 1m0s
[15:05:34.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:23:0)
[15:05:34.705] Timer tick, asking Buildbucket for the build status
[15:05:34.816] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:34.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:24:0) after 1m0s
[15:06:34.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:24:0)
[15:06:34.987] Timer tick, asking Buildbucket for the build status
[15:06:35.064] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:35.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:25:0) after 1m0s
[15:07:35.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:25:0)
[15:07:35.195] Timer tick, asking Buildbucket for the build status
[15:07:35.274] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:35.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:26:0) after 1m0s
[15:08:35.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:26:0)
[15:08:35.282] Timer tick, asking Buildbucket for the build status
[15:08:35.370] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:35.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:27:0) after 1m0s
[15:09:35.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:27:0)
[15:09:35.461] Timer tick, asking Buildbucket for the build status
[15:09:35.531] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:35.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:28:0) after 1m0s
[15:10:35.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:28:0)
[15:10:35.586] Timer tick, asking Buildbucket for the build status
[15:10:35.658] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:35.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:29:0) after 1m0s
[15:11:35.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:29:0)
[15:11:35.693] Timer tick, asking Buildbucket for the build status
[15:11:35.776] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:35.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:30:0) after 1m0s
[15:12:35.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:30:0)
[15:12:35.826] Timer tick, asking Buildbucket for the build status
[15:12:35.922] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:35.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:31:0) after 1m0s
[15:13:35.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:31:0)
[15:13:35.988] Timer tick, asking Buildbucket for the build status
[15:13:36.077] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:36.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:32:0) after 1m0s
[15:14:36.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:32:0)
[15:14:36.078] Timer tick, asking Buildbucket for the build status
[15:14:36.435] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:36.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:33:0) after 1m0s
[15:15:36.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:33:0)
[15:15:36.460] Timer tick, asking Buildbucket for the build status
[15:15:36.541] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:36.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:34:0) after 1m0s
[15:16:37.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:34:0)
[15:16:37.039] Timer tick, asking Buildbucket for the build status
[15:16:37.151] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:37.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:35:0) after 1m0s
[15:17:37.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:35:0)
[15:17:37.282] Timer tick, asking Buildbucket for the build status
[15:17:37.388] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:37.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:36:0) after 1m0s
[15:18:37.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:36:0)
[15:18:37.667] Timer tick, asking Buildbucket for the build status
[15:18:38.012] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:38.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:37:0) after 1m0s
[15:19:38.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:37:0)
[15:19:38.203] Timer tick, asking Buildbucket for the build status
[15:19:38.276] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:38.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:38:0) after 1m0s
[15:20:38.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:38:0)
[15:20:38.332] Timer tick, asking Buildbucket for the build status
[15:20:38.432] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:38.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:39:0) after 1m0s
[15:21:38.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:39:0)
[15:21:38.426] Timer tick, asking Buildbucket for the build status
[15:21:38.515] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:38.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:40:0) after 1m0s
[15:22:38.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:40:0)
[15:22:38.592] Timer tick, asking Buildbucket for the build status
[15:22:39.301] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:39.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:41:0) after 1m0s
[15:23:39.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:41:0)
[15:23:39.464] Timer tick, asking Buildbucket for the build status
[15:23:39.539] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:39.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:42:0) after 1m0s
[15:24:39.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:42:0)
[15:24:39.636] Timer tick, asking Buildbucket for the build status
[15:24:39.861] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:39.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:43:0) after 1m0s
[15:25:40.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:43:0)
[15:25:40.019] Timer tick, asking Buildbucket for the build status
[15:25:40.128] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:40.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:44:0) after 1m0s
[15:26:40.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:44:0)
[15:26:40.272] Timer tick, asking Buildbucket for the build status
[15:26:40.362] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:40.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:45:0) after 1m0s
[15:27:40.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:45:0)
[15:27:40.437] Timer tick, asking Buildbucket for the build status
[15:27:40.503] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:40.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:46:0) after 1m0s
[15:28:40.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:46:0)
[15:28:40.499] Timer tick, asking Buildbucket for the build status
[15:28:40.619] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:40.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:47:0) after 1m0s
[15:29:40.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:47:0)
[15:29:40.645] Timer tick, asking Buildbucket for the build status
[15:29:40.757] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:40.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:48:0) after 1m0s
[15:30:40.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:48:0)
[15:30:40.783] Timer tick, asking Buildbucket for the build status
[15:30:40.919] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:40.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:49:0) after 1m0s
[15:31:40.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:49:0)
[15:31:40.942] Timer tick, asking Buildbucket for the build status
[15:31:41.217] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:41.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:50:0) after 1m0s
[15:32:41.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:50:0)
[15:32:41.209] Timer tick, asking Buildbucket for the build status
[15:32:41.356] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:41.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:51:0) after 1m0s
[15:33:41.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:51:0)
[15:33:41.377] Timer tick, asking Buildbucket for the build status
[15:33:41.653] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:41.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:52:0) after 1m0s
[15:34:41.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:52:0)
[15:34:41.699] Timer tick, asking Buildbucket for the build status
[15:34:41.778] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:41.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:53:0) after 1m0s
[15:35:44.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:53:0)
[15:35:44.555] Timer tick, asking Buildbucket for the build status
[15:35:44.635] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:44.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:54:0) after 1m0s
[15:36:44.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:54:0)
[15:36:44.677] Timer tick, asking Buildbucket for the build status
[15:36:44.753] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:44.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:55:0) after 1m0s
[15:37:44.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:55:0)
[15:37:44.756] Timer tick, asking Buildbucket for the build status
[15:37:44.844] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:44.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:56:0) after 1m0s
[15:38:44.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:56:0)
[15:38:44.890] Timer tick, asking Buildbucket for the build status
[15:38:45.072] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:45.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:57:0) after 1m0s
[15:39:45.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:57:0)
[15:39:45.215] Timer tick, asking Buildbucket for the build status
[15:39:45.345] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:45.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:58:0) after 1m0s
[15:40:45.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:58:0)
[15:40:45.414] Timer tick, asking Buildbucket for the build status
[15:40:45.504] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:45.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:59:0) after 1m0s
[15:41:45.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:59:0)
[15:41:45.652] Timer tick, asking Buildbucket for the build status
[15:41:45.752] Build 8853861964879379600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:45.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019292026738786768:60:0) after 1m0s
[15:42:14.055] Received PubSub notification, asking Buildbucket for the build status
[15:42:14.149] Build 8853861964879379600: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:42:14.149] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614699733564501",
"created_by": "project:chromium",
"created_ts": "1614696265296112",
"failure_reason": "BUILD_FAILURE",
"id": "8853861964879379600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ba9af69f17d98936091451a9f7ec0ffd81f0bc8\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/PrerenderBrowserTest.GrantBroadcastChannel/MPArch\"}, \"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\": \"0ba9af69f17d98936091451a9f7ec0ffd81f0bc8\", \"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-123-mph6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45843, \"got_angle_revision\": \"6b6da203cce03287ef269846d54e77a8e871e221\", \"got_dawn_revision\": \"ec3f48242250668ca510ceef7336cf93ba80faa5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"0ba9af69f17d98936091451a9f7ec0ffd81f0bc8\", \"got_revision_cp\": \"refs/heads/master@{#858991}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"da800c736f3a0b127f576fbded9d56d380b78a77\", \"got_v8_revision_cp\": \"refs/heads/9.1.14@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ba9af69f17d98936091451a9f7ec0ffd81f0bc8\", \"swarm_hashes_refs/heads/master(at){#858991}_without_patch\": {\"absl_hardening_tests\": \"929f1dde61f4931e84a4b19754cd1027252ac611\", \"accessibility_unittests\": \"b3ab056e7856bcace4f9cb3205d3fd563f2949fa\", \"angle_unittests\": \"df1522fa122c7c4d1daad727296c6a46069eaf25\", \"app_list_unittests\": \"d1e4332824bcbd47b7670ca1edcf291a2b476ad5\", \"app_shell_unittests\": \"2490ede081d7bfd2fbe228824b6db7e2012d84e6\", \"ash_unittests\": \"f1741dd1ba80ac4a670b193ff567e987e8f50590\", \"aura_unittests\": \"668eea64402a27db3fcace02e9c912fbefb2d2b4\", \"base_unittests\": \"155e4963b4e06e84eaac5dec1a6f4acf983336ff\", \"base_util_unittests\": \"0dd851d2afa0cad8057e4bdbc393e4c3670384fd\", \"blink_common_unittests\": \"26532090c1d30d4ce8d860b17cc77520c8907932\", \"blink_fuzzer_unittests\": \"1f7d8e6d02b8daf3056591894e4cdd255ebfb802\", \"blink_heap_unittests\": \"9e3cb32d361c28966f13a7557d86328453cc5736\", \"blink_platform_unittests\": \"ff3fb0996a5613138a2ebc3ac010e29a8e69f8e5\", \"blink_unittests\": \"3afa43670a076e6bafc9af18288fe7e6b9ab7f7f\", \"boringssl_crypto_tests\": \"8e2626ab5eb4370588c3984c824c39e4f7ceb574\", \"boringssl_ssl_tests\": \"bf6b02cf686f5d709e645836264e6cfd4f9a0eec\", \"browser_tests\": \"fbdb595c72f920d86899231320f3523ed3b6f693\", \"capture_unittests\": \"6adb17f08ec60ac4ffcef36560f127707d339499\", \"cast_unittests\": \"305e6083892597d60b38994c52a7112952da4f8c\", \"cc_unittests\": \"7658148879cbf0812905ba7f7eb89461cce66750\", \"chrome_app_unittests\": \"d9290a6bba005015f5e637b285d04b0547e8eb3c\", \"chromedriver_unittests\": \"4599b8c37e4b6fb9d1cc6b4c838e1b3c6c253d22\", \"chromeos_components_unittests\": \"3b804e19df24122b9fa28e3c1845339c5a843aed\", \"chromeos_unittests\": \"f9a9c9d72d5ada1f9fc0aa01a8e7acaa28c84020\", \"color_unittests\": \"6518720462877c3c5f278bc6d331fc8526880a1b\", \"components_browsertests\": \"1a4c3891e6f27d9e7dc1fde89aa1b98953461824\", \"components_unittests\": \"e1644c7ede4a80510c74f68c3a9371c9dd3e6075\", \"compositor_unittests\": \"240bcc3c7ea0d2a2138ec29a763db7a44224369c\", \"content_browsertests\": \"ad19f75bf9a1b081c2e4142f3c58346f5ed84455\", \"content_unittests\": \"077eaa1a09f96176b1a110482a64925154da6d7a\", \"crashpad_tests\": \"8f34f540ea96b552deeab0254a4a3a3cf738f5f6\", \"crypto_unittests\": \"d618c14b8452a9f66bedf5476b172aaf53bfb8c3\", \"dbus_unittests\": \"ceb1cf698059f7528a70fa4bdd44f88409767be1\", \"device_unittests\": \"b214c360f40462a8eb3cfe762944ec4b517a79b9\", \"display_unittests\": \"2bb9ec23d2ab577a8166f4df29fdaa039fc87dd9\", \"events_unittests\": \"29e7a1cd055c30746f47c55618bc36b812b404c3\", \"exo_unittests\": \"a5b45e6b85bde5a236c116bf25ef1afa2ca80942\", \"extensions_browsertests\": \"573ecb7a7da820c78580e8a809404d41f23acf0c\", \"extensions_unittests\": \"f50c314a5afc4b40bfe61a33e0117ee50dbad374\", \"filesystem_service_unittests\": \"776468dae24682c1351b42f2cf4d2d7132c2bdbd\", \"gcm_unit_tests\": \"755a58b2779eadff7f23efbac9b0de3e095f7bb4\", \"gfx_unittests\": \"50e522b467afbcf026f64d85b74f088fcf9d270e\", \"gin_unittests\": \"e6002ac071df1b28108dee4c131455ae4d21ce80\", \"gl_unittests_ozone\": \"01c390b0e40721c76bb2fff8071fc3899156183e\", \"google_apis_unittests\": \"f5c5ac71395ec3c820379da36e37e3b5576ccdbe\", \"gpu_unittests\": \"79588fd2f659963ffc37bdf08bac3325bb43198b\", \"gwp_asan_unittests\": \"1609fed052244afa1476aedd13d66ea439ce7cb8\", \"interactive_ui_tests\": \"9a6f10d1fa162ffb5cc7b565f2e800ccf5019923\", \"ipc_tests\": \"9fb28a749976cb1585747bd8ccf26a505837a455\", \"jingle_unittests\": \"d1bab6361824f554247d7c8cdad5bad6782ef5c6\", \"keyboard_unittests\": \"83ccc05bf5d5e7f8df75b1859e2e58c37a3510a6\", \"latency_unittests\": \"899e43eb37ef72088d4b8736bcb5bad57d5e4d35\", \"libjingle_xmpp_unittests\": \"1273fd6ffd72e58d2f89ddd0fccb34842fd29b4e\", \"liburlpattern_unittests\": \"e4eb0233fc6b48447eb6b77ffabf93d9e3d7fabf\", \"media_blink_unittests\": \"da59a7243fbbeaaf31cad7e2bc083724e8595b64\", \"media_unittests\": \"6fc4461b5834df09389d818d235fa4b9b1debc4f\", \"message_center_unittests\": \"837136399183db567b2426118183ab5b150ca7ec\", \"midi_unittests\": \"5341be1a104491272787a4cbedd00d8edfc3a9fa\", \"mojo_core_unittests\": \"a4d9bb6cb50fd2464a6988883a6e26c1c203c4a3\", \"mojo_unittests\": \"1b390285f3d88eb31385c04680e2d7f102bdccd1\", \"nacl_helper_nonsfi_unittests\": \"962a7e9af637643d6fb2d76177574d7c37bb8b6e\", \"nacl_loader_unittests\": \"02e4c8547a7ca15b80d27a895fef01e45d24bfc4\", \"native_theme_unittests\": \"d048b60b3b759ca0d7bff40ac63f63d0b952f1d7\", \"net_unittests\": \"728b5b7ae0e847b45b21669a097119744355bb2f\", \"ozone_gl_unittests\": \"f1cf7e819257882cfe52ba10a45c6614b9a4ae01\", \"ozone_unittests\": \"aa55ab1c9bba4c31fb0f9554b70641c0dfd7e469\", \"ozone_x11_unittests\": \"89bb65116ddf9e2b870d9924b79f32820a63b299\", \"pdf_unittests\": \"897301dc3ecf75bbfe213e0c8fba1f0fd0dfde0a\", \"perfetto_unittests\": \"9fbee8f815ce6e7b13fe926d2dbddd1a2950c28e\", \"ppapi_unittests\": \"998f05656010884df2d35803338f29ef279c6c27\", \"printing_unittests\": \"e0e4d9e5cabf7f14ea7f4e4ba800535f312d408d\", \"remoting_unittests\": \"1068d08f27a27446e633786e58e05e8dd1cef21d\", \"sandbox_linux_unittests\": \"e6ffe02aa4b2724c7ecaa72d213344eda1fb5f7a\", \"service_manager_unittests\": \"2099ce6d684286a6df0d26514ce0b1b4b35d4f4d\", \"services_unittests\": \"4847be9b9363f9b50cb2cdcc6a1927fdebfea6cc\", \"shell_dialogs_unittests\": \"05f1c088ac162a80a33a908675ddc7c02fbe7a27\", \"shell_encryption_unittests\": \"985353019a6e903e741adfe896a93b991af5c0e3\", \"skia_unittests\": \"d5debb7149d7ef87b89c286c0c92ff099a15b955\", \"snapshot_unittests\": \"c09ba95754a507e7e352fbf7adf9a89a4edb2fed\", \"sql_unittests\": \"188bbd4d7ae0536ac8c64f69765838ec91d03ea7\", \"storage_unittests\": \"5813c6865f64ef2b62ebde65d60ff6dbe2ead894\", \"sync_integration_tests\": \"ee5a3c3382829c23a5c8defb1ec9faffeb665699\", \"traffic_annotation_auditor_unittests\": \"b5bd167184cbe8bc0fe0c36a2d51172684dbcf1b\", \"ui_base_unittests\": \"193849a886a82ca3ecb520500b6d75b7bb4609c4\", \"ui_chromeos_unittests\": \"beae9c15fa9f42565a3a5249cbe32d3e8f6d6f3b\", \"ui_touch_selection_unittests\": \"d0ecbffdb71c4298c8d7934cc44d50574322b7c7\", \"unit_tests\": \"7b79b34489676cd58b11a9d4330759e3906395bf\", \"url_unittests\": \"fe452c3ea410d6a058a00d48b7370d9b557f494e\", \"usage_time_limit_unittests\": \"99ba512483c97a2734095d7fbf14b41afc5f3a17\", \"views_unittests\": \"2efe77a157e3afc9ad8c61678dc427a13318a286\", \"viz_unittests\": \"2d3d9b214b5c8208bb1eff11c925b02f2ee995ba\", \"wayland_client_perftests\": \"2da6f5e82218d1e3f25e7a96a6b2af1d91e13638\", \"wayland_client_tests\": \"6b80345fdefb023cf536d36da8a385524d5850e2\", \"wm_unittests\": \"21bd6d0d537b48dd42c04554bc258bf4c5030d06\", \"wtf_unittests\": \"af84a5da07ade4b6664da028e6e682f6fc2db189\", \"zlib_unittests\": \"26f811adb430e92efed4c3ad17ef711909680cf6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_d3ee47a27de6e242d9ac174bcd2903a12a57590a5abb70af1d9c4989bd7f5749_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-123-mph6\"], \"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\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/PrerenderBrowserTest.GrantBroadcastChannel/MPArch\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614696282391319",
"status": "COMPLETED",
"status_changed_ts": "1614696282964892",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45843",
"builder:linux-chromeos-rel",
"buildset:commit/git/0ba9af69f17d98936091451a9f7ec0ffd81f0bc8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ba9af69f17d98936091451a9f7ec0ffd81f0bc8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019292026738786768",
"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/8853861964879379600/+/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:520c3741a18c9f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614699733564941",
"url": "https://ci.chromium.org/b/8853861964879379600",
"utcnow_ts": "1614699734142098"
}
}
[15:42:14.149] Invocation finished in 57m50.010304997s with status FAILED