[06:21:10.938] New invocation is queued and will start shortly
[06:21:12.084] Starting the invocation (attempt 1)
[06:21:12.120] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:21:12.120] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:21:12.120] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021769804713606544",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021769804713606544",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c",
"gitiles_ref:refs/heads/master"
]
}
[06:21:12.804] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612333272202608",
"id": "8856339742725614160",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612333272658911",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45101",
"builder:linux-chromeos-rel",
"buildset:commit/git/5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021769804713606544",
"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/8856339742725614160/+/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": "1612333272659025",
"url": "https://ci.chromium.org/b/8856339742725614160",
"utcnow_ts": "1612333272796025"
}
}
[06:21:12.804] Task URL: https://ci.chromium.org/b/8856339742725614160
[06:21:12.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:2:0) after 1m0s
[06:21:41.602] Received PubSub notification, asking Buildbucket for the build status
[06:21:41.815] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:12.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:2:0)
[06:22:12.924] Timer tick, asking Buildbucket for the build status
[06:22:13.005] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:13.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:4:0) after 1m0s
[06:23:13.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:4:0)
[06:23:13.066] Timer tick, asking Buildbucket for the build status
[06:23:13.158] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:13.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:5:0) after 1m0s
[06:24:13.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:5:0)
[06:24:13.205] Timer tick, asking Buildbucket for the build status
[06:24:13.516] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:13.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:6:0) after 1m0s
[06:25:13.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:6:0)
[06:25:13.546] Timer tick, asking Buildbucket for the build status
[06:25:13.613] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:7:0) after 1m0s
[06:26:13.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:7:0)
[06:26:13.669] Timer tick, asking Buildbucket for the build status
[06:26:13.792] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:13.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:8:0) after 1m0s
[06:27:13.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:8:0)
[06:27:13.875] Timer tick, asking Buildbucket for the build status
[06:27:14.236] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:14.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:9:0) after 1m0s
[06:28:14.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:9:0)
[06:28:14.378] Timer tick, asking Buildbucket for the build status
[06:28:14.697] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:14.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:10:0) after 1m0s
[06:29:14.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:10:0)
[06:29:14.752] Timer tick, asking Buildbucket for the build status
[06:29:14.862] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:14.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:11:0) after 1m0s
[06:30:14.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:11:0)
[06:30:14.933] Timer tick, asking Buildbucket for the build status
[06:30:14.995] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:14.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:12:0) after 1m0s
[06:31:15.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:12:0)
[06:31:15.054] Timer tick, asking Buildbucket for the build status
[06:31:15.179] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:15.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:13:0) after 1m0s
[06:32:15.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:13:0)
[06:32:15.251] Timer tick, asking Buildbucket for the build status
[06:32:15.443] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:15.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:14:0) after 1m0s
[06:33:15.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:14:0)
[06:33:15.584] Timer tick, asking Buildbucket for the build status
[06:33:15.842] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:15.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:15:0) after 1m0s
[06:34:15.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:15:0)
[06:34:15.917] Timer tick, asking Buildbucket for the build status
[06:34:16.208] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:16.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:16:0) after 1m0s
[06:35:16.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:16:0)
[06:35:16.367] Timer tick, asking Buildbucket for the build status
[06:35:16.461] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:16.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:17:0) after 1m0s
[06:36:16.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:17:0)
[06:36:16.600] Timer tick, asking Buildbucket for the build status
[06:36:16.719] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:16.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:18:0) after 1m0s
[06:37:17.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:18:0)
[06:37:17.094] Timer tick, asking Buildbucket for the build status
[06:37:17.165] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:17.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:19:0) after 1m0s
[06:38:17.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:19:0)
[06:38:17.382] Timer tick, asking Buildbucket for the build status
[06:38:17.462] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:17.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:20:0) after 1m0s
[06:39:17.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:20:0)
[06:39:17.726] Timer tick, asking Buildbucket for the build status
[06:39:17.914] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:17.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:21:0) after 1m0s
[06:40:18.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:21:0)
[06:40:18.125] Timer tick, asking Buildbucket for the build status
[06:40:18.201] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:18.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:22:0) after 1m0s
[06:41:18.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:22:0)
[06:41:18.241] Timer tick, asking Buildbucket for the build status
[06:41:18.355] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:18.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:23:0) after 1m0s
[06:42:18.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:23:0)
[06:42:18.376] Timer tick, asking Buildbucket for the build status
[06:42:18.544] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:18.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:24:0) after 1m0s
[06:43:18.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:24:0)
[06:43:18.581] Timer tick, asking Buildbucket for the build status
[06:43:18.734] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:18.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:25:0) after 1m0s
[06:44:18.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:25:0)
[06:44:18.747] Timer tick, asking Buildbucket for the build status
[06:44:18.796] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:18.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:26:0) after 1m0s
[06:45:18.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:26:0)
[06:45:18.818] Timer tick, asking Buildbucket for the build status
[06:45:19.033] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:19.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:27:0) after 1m0s
[06:46:19.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:27:0)
[06:46:19.086] Timer tick, asking Buildbucket for the build status
[06:46:19.432] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:19.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:28:0) after 1m0s
[06:47:19.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:28:0)
[06:47:19.586] Timer tick, asking Buildbucket for the build status
[06:47:19.652] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:19.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:29:0) after 1m0s
[06:48:19.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:29:0)
[06:48:19.777] Timer tick, asking Buildbucket for the build status
[06:48:19.840] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:19.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:30:0) after 1m0s
[06:49:19.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:30:0)
[06:49:19.936] Timer tick, asking Buildbucket for the build status
[06:49:19.992] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:19.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:31:0) after 1m0s
[06:50:20.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:31:0)
[06:50:20.113] Timer tick, asking Buildbucket for the build status
[06:50:20.189] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:20.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:32:0) after 1m0s
[06:51:20.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:32:0)
[06:51:20.193] Timer tick, asking Buildbucket for the build status
[06:51:20.359] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:20.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:33:0) after 1m0s
[06:52:20.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:33:0)
[06:52:20.439] Timer tick, asking Buildbucket for the build status
[06:52:20.497] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:20.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:34:0) after 1m0s
[06:53:20.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:34:0)
[06:53:20.518] Timer tick, asking Buildbucket for the build status
[06:53:20.581] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:20.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:35:0) after 1m0s
[06:54:20.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:35:0)
[06:54:20.733] Timer tick, asking Buildbucket for the build status
[06:54:20.799] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:20.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:36:0) after 1m0s
[06:55:20.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:36:0)
[06:55:20.923] Timer tick, asking Buildbucket for the build status
[06:55:21.085] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:21.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:37:0) after 1m0s
[06:56:21.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:37:0)
[06:56:21.305] Timer tick, asking Buildbucket for the build status
[06:56:21.431] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:21.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:38:0) after 1m0s
[06:57:21.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:38:0)
[06:57:21.484] Timer tick, asking Buildbucket for the build status
[06:57:21.544] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:21.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:39:0) after 1m0s
[06:58:21.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:39:0)
[06:58:21.615] Timer tick, asking Buildbucket for the build status
[06:58:21.706] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:21.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:40:0) after 1m0s
[06:59:22.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:40:0)
[06:59:22.350] Timer tick, asking Buildbucket for the build status
[06:59:22.612] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:22.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:41:0) after 1m0s
[07:00:22.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:41:0)
[07:00:22.798] Timer tick, asking Buildbucket for the build status
[07:00:22.992] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:22.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:42:0) after 1m0s
[07:01:23.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:42:0)
[07:01:23.205] Timer tick, asking Buildbucket for the build status
[07:01:23.329] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:23.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:43:0) after 1m0s
[07:02:23.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:43:0)
[07:02:23.385] Timer tick, asking Buildbucket for the build status
[07:02:23.480] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:23.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:44:0) after 1m0s
[07:03:23.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:44:0)
[07:03:23.503] Timer tick, asking Buildbucket for the build status
[07:03:24.002] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:24.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:45:0) after 1m0s
[07:04:24.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:45:0)
[07:04:24.026] Timer tick, asking Buildbucket for the build status
[07:04:24.107] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:24.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:46:0) after 1m0s
[07:05:24.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:46:0)
[07:05:24.135] Timer tick, asking Buildbucket for the build status
[07:05:24.196] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:24.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:47:0) after 1m0s
[07:06:24.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:47:0)
[07:06:24.226] Timer tick, asking Buildbucket for the build status
[07:06:24.522] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:24.522] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:48:0) after 1m0s
[07:07:24.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:48:0)
[07:07:24.548] Timer tick, asking Buildbucket for the build status
[07:07:25.037] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:25.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:49:0) after 1m0s
[07:08:25.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:49:0)
[07:08:25.059] Timer tick, asking Buildbucket for the build status
[07:08:25.636] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:25.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:50:0) after 1m0s
[07:09:25.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:50:0)
[07:09:25.671] Timer tick, asking Buildbucket for the build status
[07:09:26.390] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:26.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:51:0) after 1m0s
[07:10:26.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:51:0)
[07:10:26.465] Timer tick, asking Buildbucket for the build status
[07:10:27.023] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:27.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:52:0) after 1m0s
[07:11:27.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:52:0)
[07:11:27.162] Timer tick, asking Buildbucket for the build status
[07:11:27.357] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:27.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:53:0) after 1m0s
[07:12:27.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:53:0)
[07:12:27.385] Timer tick, asking Buildbucket for the build status
[07:12:27.528] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:27.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:54:0) after 1m0s
[07:13:27.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:54:0)
[07:13:27.550] Timer tick, asking Buildbucket for the build status
[07:13:27.707] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:27.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:55:0) after 1m0s
[07:14:27.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:55:0)
[07:14:27.729] Timer tick, asking Buildbucket for the build status
[07:14:27.866] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:27.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:56:0) after 1m0s
[07:15:27.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:56:0)
[07:15:27.916] Timer tick, asking Buildbucket for the build status
[07:15:28.351] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:28.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:57:0) after 1m0s
[07:16:28.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:57:0)
[07:16:28.424] Timer tick, asking Buildbucket for the build status
[07:16:28.735] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:28.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:58:0) after 1m0s
[07:17:28.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:58:0)
[07:17:28.831] Timer tick, asking Buildbucket for the build status
[07:17:29.051] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:29.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:59:0) after 1m0s
[07:18:29.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:59:0)
[07:18:29.074] Timer tick, asking Buildbucket for the build status
[07:18:29.833] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:29.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:60:0) after 1m0s
[07:19:29.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:60:0)
[07:19:29.913] Timer tick, asking Buildbucket for the build status
[07:19:30.019] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:30.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:61:0) after 1m0s
[07:20:30.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:61:0)
[07:20:30.086] Timer tick, asking Buildbucket for the build status
[07:20:30.151] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:30.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:62:0) after 1m0s
[07:21:30.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:62:0)
[07:21:30.194] Timer tick, asking Buildbucket for the build status
[07:21:30.377] Build 8856339742725614160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:30.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021769804713606544:63:0) after 1m0s
[07:22:21.902] Received PubSub notification, asking Buildbucket for the build status
[07:22:21.989] Build 8856339742725614160: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:22:21.989] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612336940948555",
"created_by": "project:chromium",
"created_ts": "1612333272202608",
"failure_reason": "BUILD_FAILURE",
"id": "8856339742725614160",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_browsertests** failed because of:\\n\\n- All/SitePerProcessBrowserTest.CrossProcessInertSubframe/0\"}, \"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\": \"5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c\", \"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-43-n7n2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45101, \"got_angle_revision\": \"6cc845bb0a603587ac36b900558d483e05f02f8c\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c\", \"got_revision_cp\": \"refs/heads/master@{#849987}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c\", \"swarm_hashes_refs/heads/master(at){#849987}_without_patch\": {\"absl_hardening_tests\": \"4642a82ee14699f369f2b4c138e14eb6d0f48157\", \"accessibility_unittests\": \"7d33ca91db629ca26c4a346451bb6c7742367542\", \"angle_unittests\": \"2299a011c137318d8915f7c34521691a0fee4275\", \"app_list_unittests\": \"da8e4bd679d378f337ee0cc5cde3054cbe131025\", \"app_shell_unittests\": \"c873f6c353e3ad0cc6151ba01628c48c6d2c521e\", \"ash_unittests\": \"7bae32ac8891fb0771ab3937dd31d8578b10e14c\", \"aura_unittests\": \"48356208c0baf0b900d7b622f8c9510868c460a2\", \"base_unittests\": \"b5ddb867b89f8235b02101c944233aca01fe8f87\", \"base_util_unittests\": \"ee517618b73b6c0d85a144c532645c8740a67157\", \"blink_common_unittests\": \"15ceca815fc2298b869f0611ef5f1d4e9b3e9b20\", \"blink_fuzzer_unittests\": \"8019f390bee6b3ee7494ce3d14fe9222149efa59\", \"blink_heap_unittests\": \"f0b62624b3edd807fa9433f8d99ac74389cc9257\", \"blink_platform_unittests\": \"ffebe6e08637be19693e344dc950ab20330f8e47\", \"blink_unittests\": \"a7beafda228beb9a2ec9d496a789b1827b1b21e2\", \"boringssl_crypto_tests\": \"039068d5fe9ae21727c5f1a66e6a7fb6277ba9ab\", \"boringssl_ssl_tests\": \"0e80e5f100e36fb62a75e69de43cf2e88f5790aa\", \"browser_tests\": \"209eb63710594242cf4154bbcd5e71040762ab48\", \"capture_unittests\": \"1dc69ac48702584ad45728351ccaeaae872b84f3\", \"cast_unittests\": \"6e79d1d68985d1ec38d62f0d0b0541cc39924b59\", \"cc_unittests\": \"8946f796723da70f964204867a1875a5850a9e26\", \"chrome_app_unittests\": \"46717dd30ca76ccf22a9ed3a26c509eb1f6755a3\", \"chromedriver_unittests\": \"9c1582126556a7ad7062aab5feb70b33105bfc25\", \"chromeos_components_unittests\": \"ff13320adc24e991ea8eabb54d6e2d68081bfed1\", \"chromeos_unittests\": \"42037f87639003664abe46e3be8b0b264f7a6778\", \"components_browsertests\": \"f36334484d25181a4b1801fa829405582dd3fbef\", \"components_unittests\": \"3dc3771c9bef9b25e992652b7df50a40489018c2\", \"compositor_unittests\": \"083bf93b73fb752aa5505baf00adb1febe839645\", \"content_browsertests\": \"ee6687ed74854f3d0cf782710dac00bceb4f4503\", \"content_unittests\": \"5b15fd157be70f923177fab68e0b7ed1e22c8a16\", \"crashpad_tests\": \"fe57e361b7708bd3089383ea99d4b48155ed63e1\", \"crypto_unittests\": \"9de4dab01588737445639bb883db27aa5bc6f40d\", \"dbus_unittests\": \"dc349397260db8df038d10d90aad6024d6038ca2\", \"device_unittests\": \"07750464f099f2a64d15c92ab8e22959355d7434\", \"display_unittests\": \"f4a1c36bcf1e180a191749b13e100334f4b46f7c\", \"events_unittests\": \"45a8154ad567ab7f109d5232431dcf7dda357f52\", \"exo_unittests\": \"48532bdcc5261df7e5ba424ae9aa869b3a03cdb5\", \"extensions_browsertests\": \"c61de2dc3a507df0ed2305f68850fcf37cd5551e\", \"extensions_unittests\": \"c6729114790832d6c2775028c8f0d44a4bf6bba3\", \"filesystem_service_unittests\": \"7b0040678de5d4d6947a7b8cab96a4852ca9f3d1\", \"gcm_unit_tests\": \"da292976f1152bec26346af58ab5f42dea0b57be\", \"gfx_unittests\": \"d67f5eca4eeef13d195df7d8ebf4b1c39e772a5b\", \"gin_unittests\": \"40ebc6311d03777bc403a69afd07318ca04c54b1\", \"gl_unittests_ozone\": \"de87805a720ee4a434d0847e2c761710c05389f8\", \"google_apis_unittests\": \"5d7ad4ec86c9d24d1e07f21912a7416c48c0b3f2\", \"gpu_unittests\": \"7ffe650435d79c3a6759dcbb7ffb7bf49c0c6f9c\", \"gwp_asan_unittests\": \"3a59c7226333afd89b4e9c87c6bec83669fad56f\", \"interactive_ui_tests\": \"579f9574869c29ad1246f453e5c94b88a6f8b59d\", \"ipc_tests\": \"926d749852a61bc6a48d4588d907c9a2da54905f\", \"jingle_unittests\": \"894e0d95c6e62e7f7be40a1dcf49ca2ebb83207a\", \"keyboard_unittests\": \"7f8358c518e900dead0e6e7ff0c9838733c53637\", \"latency_unittests\": \"e9f9ea4bad8ef4e9620c394b99581338d8e71c25\", \"libjingle_xmpp_unittests\": \"03d5d09d59ee3c1ae1e4d7eeef070e5546837c73\", \"liburlpattern_unittests\": \"77acefe0e6a4bbddcb44461af9e829f5177a7126\", \"media_blink_unittests\": \"36a8d33aaedd9c7027d34ca0646847e12c112a4c\", \"media_unittests\": \"d7cf8270b9cfc0231810c277d00627228997c279\", \"message_center_unittests\": \"4d8c907aa1ee539b256520aca5a08f9e30cb576d\", \"midi_unittests\": \"ab5fd331f3682489dcfb7f51031b5e842927df3e\", \"mojo_core_unittests\": \"27af55139833906963a6e0f3e89e16f77fa0c5a2\", \"mojo_unittests\": \"70a876bdb76e9d2dcf2b686b2bc11c5507713cac\", \"nacl_helper_nonsfi_unittests\": \"e919a3c86056a1658fb24a7f31f951964c95bdeb\", \"nacl_loader_unittests\": \"db3774098744ece3b4789cc54aa57b95c09a38a0\", \"native_theme_unittests\": \"2ca29dd32455d3fbac5e0670cc3a121b8a60d19c\", \"net_unittests\": \"2eaa1e508b7badc4296d7c75e1f6892719973f87\", \"ozone_gl_unittests\": \"69a753e5f8f320765ca4bdb648de6d71185b008e\", \"ozone_unittests\": \"f0539271fd7eda3aa136856ab2d1226a3095b203\", \"ozone_x11_unittests\": \"871f8a92e96176168c7235966bc02dbdba1ce268\", \"pdf_unittests\": \"bc702ad2cfbec5c2700017838e6f6fdc354960c7\", \"perfetto_unittests\": \"9192d075680f45e43383d5a8742e7ff333ec5afe\", \"ppapi_unittests\": \"adb5ec76f90416ced905e8150f0fa96f295b8c20\", \"printing_unittests\": \"989137b8026ccd5c4b4a78ff01dd3199f411e887\", \"remoting_unittests\": \"0d78152e5bcca16e00bfecadcd39cbdef708b22b\", \"sandbox_linux_unittests\": \"254278cb4dabe918d527a768eefcfb1c36c3217f\", \"service_manager_unittests\": \"8998892793052088975598cd9e19306b6ab18abc\", \"services_unittests\": \"79d19034b29b6026b42f15bc46f4276f41e5e8d3\", \"shell_dialogs_unittests\": \"f0cb503e98f55fdfd986a59973cb827d7dace90e\", \"shell_encryption_unittests\": \"328fbb00725998dcdfddf416eefede53c812b177\", \"skia_unittests\": \"f7b86b9837d2684230246ab810354b513e5cf257\", \"snapshot_unittests\": \"dd8d18c3bb96393033b81bf868d9b27cb631ab16\", \"sql_unittests\": \"3f8e8a0c9a3d4c4e6607d98c2c3fe02c442cbeca\", \"storage_unittests\": \"9c2b3fab765a610f95eaf5b11f4a66d239a1355b\", \"sync_integration_tests\": \"6c0c717ae3fc6584e01618dbd2a1723b93abe921\", \"traffic_annotation_auditor_unittests\": \"5b15af4775cfcc8b91cc7cc26ea15d86af7a123c\", \"ui_base_unittests\": \"5f62927817194fe0d9f04c23e7bd0365ad5e92df\", \"ui_chromeos_unittests\": \"997acc98d8e74df5d28fe9ea348c4781f9b1cf3c\", \"ui_touch_selection_unittests\": \"edab2ab49859c3647d56c7423155ea7bdb73c90f\", \"unit_tests\": \"b58e347fef119e08583bf1b458e76378f091a0c6\", \"url_unittests\": \"d99ea02081d1606968576b43bc83088b8bdd2b88\", \"usage_time_limit_unittests\": \"ea01c826db081b342345f8fb2680c094e2cc20ba\", \"views_unittests\": \"571d21d1b596c2bbb25ed720ce79b2acfeaf9187\", \"viz_unittests\": \"847957de6f4970dbc451d4997c6623188200712d\", \"wayland_client_perftests\": \"c3a9e97926a1e50916cdfaa6da1281f836e0f453\", \"wm_unittests\": \"9bdbe423335359a3a3d82c35756d357dbc79e532\", \"wtf_unittests\": \"f583d74da7359a930f4daa59e43b591145a761e7\", \"zlib_unittests\": \"43f3c91e914694611d90c229f43777f3a28bfdcc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4e62a2152674f2c3254d7105a557302458cce07b755aab1e21dcbd6c7ddeb832_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9f247f4686c5cf47ea762220cc037b3e853f43c1442c10d387faa806e919f9a9_v2\", \"builder_a16dce727623db190129e730193443d0ae3080534f372fd612ff56e110b9028d_v2\", \"builder_b60086edde2d210abd33c4d80848a46b6d9127aed49992e1b3da5a4d0158dc58_v2\", \"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"builder_fe5305c96c631c9bcc74a68d877d31a94a8d3c28663b28d2fadfc8f798de4ec0_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-43-n7n2\"], \"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\": [\"5676-c76598c\"], \"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/SitePerProcessBrowserTest.CrossProcessInertSubframe/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612333296574328",
"status": "COMPLETED",
"status_changed_ts": "1612333297010283",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45101",
"builder:linux-chromeos-rel",
"buildset:commit/git/5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e5d91bc8aac1e6c528ad45d22645a2d17e9d49c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021769804713606544",
"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/8856339742725614160/+/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:517f5ed762710410",
"user_agent:luci-scheduler"
],
"updated_ts": "1612336941645757",
"url": "https://ci.chromium.org/b/8856339742725614160",
"utcnow_ts": "1612336941981771"
}
}
[07:22:21.990] Invocation finished in 1h1m11.066223998s with status FAILED