[23:14:05.693] New invocation is queued and will start shortly
[23:14:06.787] Starting the invocation (attempt 1)
[23:14:06.830] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:14:06.830] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:14:06.830] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019803541470548176",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"98fc87e7d654059e9ddc0c29504ec4e70bfe74cb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019803541470548176",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/98fc87e7d654059e9ddc0c29504ec4e70bfe74cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/98fc87e7d654059e9ddc0c29504ec4e70bfe74cb",
"gitiles_ref:refs/heads/master"
]
}
[23:14:08.066] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614208446945894",
"id": "8854373479494847456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"98fc87e7d654059e9ddc0c29504ec4e70bfe74cb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614208447769052",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45707",
"builder:linux-chromeos-rel",
"buildset:commit/git/98fc87e7d654059e9ddc0c29504ec4e70bfe74cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/98fc87e7d654059e9ddc0c29504ec4e70bfe74cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019803541470548176",
"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/8854373479494847456/+/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": "1614208447769147",
"url": "https://ci.chromium.org/b/8854373479494847456",
"utcnow_ts": "1614208448050167"
}
}
[23:14:08.066] Task URL: https://ci.chromium.org/b/8854373479494847456
[23:14:08.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:2:0) after 1m0s
[23:14:10.482] Received PubSub notification, asking Buildbucket for the build status
[23:14:10.611] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:08.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:2:0)
[23:15:08.125] Timer tick, asking Buildbucket for the build status
[23:15:08.191] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:08.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:4:0) after 1m0s
[23:16:08.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:4:0)
[23:16:08.268] Timer tick, asking Buildbucket for the build status
[23:16:08.578] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:08.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:5:0) after 1m0s
[23:17:08.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:5:0)
[23:17:08.586] Timer tick, asking Buildbucket for the build status
[23:17:08.704] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:08.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:6:0) after 1m0s
[23:18:08.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:6:0)
[23:18:08.709] Timer tick, asking Buildbucket for the build status
[23:18:08.841] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:08.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:7:0) after 1m0s
[23:19:08.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:7:0)
[23:19:08.923] Timer tick, asking Buildbucket for the build status
[23:19:09.065] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:09.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:8:0) after 1m0s
[23:20:09.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:8:0)
[23:20:09.100] Timer tick, asking Buildbucket for the build status
[23:20:09.255] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:09.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:9:0) after 1m0s
[23:21:09.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:9:0)
[23:21:09.292] Timer tick, asking Buildbucket for the build status
[23:21:09.379] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:09.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:10:0) after 1m0s
[23:22:09.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:10:0)
[23:22:09.405] Timer tick, asking Buildbucket for the build status
[23:22:09.502] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:09.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:11:0) after 1m0s
[23:23:09.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:11:0)
[23:23:09.488] Timer tick, asking Buildbucket for the build status
[23:23:09.606] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:09.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:12:0) after 1m0s
[23:24:09.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:12:0)
[23:24:09.624] Timer tick, asking Buildbucket for the build status
[23:24:10.088] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:10.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:13:0) after 1m0s
[23:25:10.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:13:0)
[23:25:10.094] Timer tick, asking Buildbucket for the build status
[23:25:10.183] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:10.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:14:0) after 1m0s
[23:26:10.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:14:0)
[23:26:10.261] Timer tick, asking Buildbucket for the build status
[23:26:10.334] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:10.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:15:0) after 1m0s
[23:27:10.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:15:0)
[23:27:10.364] Timer tick, asking Buildbucket for the build status
[23:27:10.472] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:10.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:16:0) after 1m0s
[23:28:10.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:16:0)
[23:28:10.516] Timer tick, asking Buildbucket for the build status
[23:28:10.620] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:10.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:17:0) after 1m0s
[23:29:10.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:17:0)
[23:29:10.618] Timer tick, asking Buildbucket for the build status
[23:29:10.723] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:10.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:18:0) after 1m0s
[23:30:10.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:18:0)
[23:30:10.778] Timer tick, asking Buildbucket for the build status
[23:30:10.876] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:10.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:19:0) after 1m0s
[23:31:10.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:19:0)
[23:31:10.875] Timer tick, asking Buildbucket for the build status
[23:31:10.941] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:10.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:20:0) after 1m0s
[23:32:10.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:20:0)
[23:32:10.947] Timer tick, asking Buildbucket for the build status
[23:32:11.031] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:11.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:21:0) after 1m0s
[23:33:11.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:21:0)
[23:33:11.407] Timer tick, asking Buildbucket for the build status
[23:33:11.480] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:11.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:22:0) after 1m0s
[23:34:11.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:22:0)
[23:34:11.587] Timer tick, asking Buildbucket for the build status
[23:34:12.567] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:12.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:23:0) after 1m0s
[23:35:12.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:23:0)
[23:35:12.969] Timer tick, asking Buildbucket for the build status
[23:35:13.092] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:13.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:24:0) after 1m0s
[23:36:13.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:24:0)
[23:36:13.189] Timer tick, asking Buildbucket for the build status
[23:36:13.250] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:13.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:25:0) after 1m0s
[23:37:13.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:25:0)
[23:37:13.371] Timer tick, asking Buildbucket for the build status
[23:37:13.436] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:13.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:26:0) after 1m0s
[23:38:13.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:26:0)
[23:38:13.472] Timer tick, asking Buildbucket for the build status
[23:38:13.658] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:13.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:27:0) after 1m0s
[23:39:13.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:27:0)
[23:39:13.643] Timer tick, asking Buildbucket for the build status
[23:39:13.727] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:13.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:28:0) after 1m0s
[23:40:13.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:28:0)
[23:40:13.825] Timer tick, asking Buildbucket for the build status
[23:40:13.918] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:13.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:29:0) after 1m0s
[23:41:13.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:29:0)
[23:41:13.911] Timer tick, asking Buildbucket for the build status
[23:41:14.088] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:14.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:30:0) after 1m0s
[23:42:14.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:30:0)
[23:42:14.182] Timer tick, asking Buildbucket for the build status
[23:42:14.317] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:14.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:31:0) after 1m0s
[23:43:14.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:31:0)
[23:43:14.359] Timer tick, asking Buildbucket for the build status
[23:43:14.505] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:14.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:32:0) after 1m0s
[23:44:14.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:32:0)
[23:44:14.717] Timer tick, asking Buildbucket for the build status
[23:44:14.806] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:14.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:33:0) after 1m0s
[23:45:14.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:33:0)
[23:45:14.913] Timer tick, asking Buildbucket for the build status
[23:45:15.118] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:15.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:34:0) after 1m0s
[23:46:15.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:34:0)
[23:46:15.172] Timer tick, asking Buildbucket for the build status
[23:46:15.268] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:15.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:35:0) after 1m0s
[23:47:15.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:35:0)
[23:47:15.659] Timer tick, asking Buildbucket for the build status
[23:47:15.769] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:15.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:36:0) after 1m0s
[23:48:15.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:36:0)
[23:48:15.793] Timer tick, asking Buildbucket for the build status
[23:48:15.892] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:15.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:37:0) after 1m0s
[23:49:15.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:37:0)
[23:49:15.932] Timer tick, asking Buildbucket for the build status
[23:49:16.017] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:16.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:38:0) after 1m0s
[23:50:16.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:38:0)
[23:50:16.138] Timer tick, asking Buildbucket for the build status
[23:50:16.230] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:16.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:39:0) after 1m0s
[23:51:16.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:39:0)
[23:51:16.294] Timer tick, asking Buildbucket for the build status
[23:51:16.449] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:16.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:40:0) after 1m0s
[23:52:16.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:40:0)
[23:52:16.515] Timer tick, asking Buildbucket for the build status
[23:52:16.692] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:16.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:41:0) after 1m0s
[23:53:16.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:41:0)
[23:53:16.711] Timer tick, asking Buildbucket for the build status
[23:53:16.792] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:16.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:42:0) after 1m0s
[23:54:16.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:42:0)
[23:54:16.856] Timer tick, asking Buildbucket for the build status
[23:54:16.982] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:16.982] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:43:0) after 1m0s
[23:55:17.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:43:0)
[23:55:17.069] Timer tick, asking Buildbucket for the build status
[23:55:17.151] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:17.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:44:0) after 1m0s
[23:56:17.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:44:0)
[23:56:17.203] Timer tick, asking Buildbucket for the build status
[23:56:18.589] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:18.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:45:0) after 1m0s
[23:57:18.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:45:0)
[23:57:18.977] Timer tick, asking Buildbucket for the build status
[23:57:19.069] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:19.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:46:0) after 1m0s
[23:58:19.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:46:0)
[23:58:19.257] Timer tick, asking Buildbucket for the build status
[23:58:19.919] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:19.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:47:0) after 1m0s
[23:59:20.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:47:0)
[23:59:20.044] Timer tick, asking Buildbucket for the build status
[23:59:20.178] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:20.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:48:0) after 1m0s
[00:00:20.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:48:0)
[00:00:20.322] Timer tick, asking Buildbucket for the build status
[00:00:21.086] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:21.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:49:0) after 1m0s
[00:01:21.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:49:0)
[00:01:21.106] Timer tick, asking Buildbucket for the build status
[00:01:21.236] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:21.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:50:0) after 1m0s
[00:02:21.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:50:0)
[00:02:21.496] Timer tick, asking Buildbucket for the build status
[00:02:21.922] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:21.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:51:0) after 1m0s
[00:03:22.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:51:0)
[00:03:22.101] Timer tick, asking Buildbucket for the build status
[00:03:22.193] Build 8854373479494847456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:22.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019803541470548176:52:0) after 1m0s
[00:03:26.596] Received PubSub notification, asking Buildbucket for the build status
[00:03:26.683] Build 8854373479494847456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:03:26.683] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614211405809196",
"created_by": "project:chromium",
"created_ts": "1614208446945894",
"id": "8854373479494847456",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"98fc87e7d654059e9ddc0c29504ec4e70bfe74cb\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"98fc87e7d654059e9ddc0c29504ec4e70bfe74cb\", \"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-172-x07d\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45707, \"got_angle_revision\": \"9b9d683e9cc3ceaa07cc501e5667779db791c0cd\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"98fc87e7d654059e9ddc0c29504ec4e70bfe74cb\", \"got_revision_cp\": \"refs/heads/master@{#857411}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd0373f013b5c9672c05e79a48bb1e9fb5f50fa4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33333}\", \"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\": \"98fc87e7d654059e9ddc0c29504ec4e70bfe74cb\", \"swarm_hashes_refs/heads/master(at){#857411}_without_patch\": {\"absl_hardening_tests\": \"cd96667c1587461056e69508ff48dcdbc41beef0\", \"accessibility_unittests\": \"34a0d120fc99034be8aabf1974363c6a554491f4\", \"angle_unittests\": \"90cd97187405d225cb552d7f73fa6af45f4b568a\", \"app_list_unittests\": \"d5535e85485575ed06891a9cc3591f6fe913aa65\", \"app_shell_unittests\": \"2a31d9c77bb67b3fecd49fbbd01004271915dff2\", \"ash_unittests\": \"a7be750b604a61e05eae59ed1394a57a32a758e2\", \"aura_unittests\": \"d0889e35c9ca86c82dc7730ab7073137e33e3006\", \"base_unittests\": \"8021fed51745f994587732a27169ffe7385f441b\", \"base_util_unittests\": \"2a55cc449614df39e6e77267104e62543ac3813c\", \"blink_common_unittests\": \"581cc6ec25b3140b4e854363ef8000925cabcb5b\", \"blink_fuzzer_unittests\": \"592ad22a35310bd4981719f1f5823de9a8e5a8c1\", \"blink_heap_unittests\": \"c833299b225f8492c3c3384ce490a5bbca652412\", \"blink_platform_unittests\": \"b40b7b4db6570e99d8f18527b2f14f6f5c509848\", \"blink_unittests\": \"61879cf426eaaeae40801d87a239fd213bf44c8b\", \"boringssl_crypto_tests\": \"082f44af609607260b283256a3a27440393f1c1f\", \"boringssl_ssl_tests\": \"fb35928d6629b0f5272401ca23a10e321f4bcf06\", \"browser_tests\": \"29282724623c35c16b77587564639e4901d5b23b\", \"capture_unittests\": \"da84b33dfdd0812e07e5750f8a053b46ef005bb6\", \"cast_unittests\": \"27a509f7d7d738a950ae7757811947946f84116e\", \"cc_unittests\": \"eacc58ffd289ffd413e40089c2205eced1cb4a30\", \"chrome_app_unittests\": \"29adb2647d07189ffed3cb2007c52b2ef985bc83\", \"chromedriver_unittests\": \"b71370150beee022ccb84d6c7df7415a8659d827\", \"chromeos_components_unittests\": \"ac916071f583239bd528c202a71abe52c0f89a55\", \"chromeos_unittests\": \"f1ba988646093d5512942590e4043454e62c1f1f\", \"color_unittests\": \"778b7e184b4f150ef705e55d9de800ef9988b7e8\", \"components_browsertests\": \"83e931de860e3a25816d0c6193a2e23be4805db7\", \"components_unittests\": \"c6c4095cd2c612c8b314cc35b11463b53d5632f2\", \"compositor_unittests\": \"0f9617882b62f80fb6b5bf9425628af23b528078\", \"content_browsertests\": \"3f23ae4f759057d7d42ff9553c14887e31171272\", \"content_unittests\": \"72605ce2fadebd6c4faf03621be4582ee8c2da4d\", \"crashpad_tests\": \"57dbb8b0fae495a3a042197cb4c9cfcec6f7497b\", \"crypto_unittests\": \"484cd20c1bef5b1a2ef61527d563c19ad2332bed\", \"dbus_unittests\": \"2aa2a62032af5d2132cb61df8d0ac1f745aba881\", \"device_unittests\": \"6aded426ca7652e46c5031ae7c414c913fd6598a\", \"display_unittests\": \"7be9611289b19863ec8118a9fcb2903d48cc945c\", \"events_unittests\": \"a74f54e136312133fabbb9488d8b35453fcb28ff\", \"exo_unittests\": \"78da868733200ae62fd225d72ea810f896244345\", \"extensions_browsertests\": \"7cbd5adf28aa677bc07f3023a2e519a801d3b6a1\", \"extensions_unittests\": \"e291e5d5d664b23da55264059cc17f5da81a3c10\", \"filesystem_service_unittests\": \"76af7b4b530d085215e090e842cd17f1249dbdb1\", \"gcm_unit_tests\": \"0296924308ccad0d6324d1f305fd16c05fb971a7\", \"gfx_unittests\": \"723e9fd8a8071947ac5955cdb321309315b6c7df\", \"gin_unittests\": \"aae9ce9ebdb0a5739775aa963c8ba79df988224b\", \"gl_unittests_ozone\": \"4dbbec5fccf1767dc8963e75d6eb8495312536a2\", \"google_apis_unittests\": \"ffdf5401be9ace28587e6768751ea0041692297c\", \"gpu_unittests\": \"0e8063cd9f3b2abadc22da320b867f6cf4b67e0f\", \"gwp_asan_unittests\": \"95835da84587ab14b5a7ab39550afa573d86ea32\", \"interactive_ui_tests\": \"4e0e6d035885c74f11eb6e7b04f5989266b835a6\", \"ipc_tests\": \"23fd387962b48a4041eaa5fda91e5e6964aeaf05\", \"jingle_unittests\": \"03b0bf98dc063cff3b1066eb818728b4ab02bcb3\", \"keyboard_unittests\": \"8d0d4b2d8bc3d9f0146d6fba31122fcb68001912\", \"latency_unittests\": \"e4e632d1cefec6696c2123f4be795285b89cfdc3\", \"libjingle_xmpp_unittests\": \"65ec11e1b2182d7359342783b3ac14dfcbc53b5a\", \"liburlpattern_unittests\": \"42b0a88208764aa5e21d3e0689a08f9c20ec647f\", \"media_blink_unittests\": \"7820bba75403f02e11d7cadb7193de5e5fd13489\", \"media_unittests\": \"b05ce3f6455b712df03611a844d65b43298c80ef\", \"message_center_unittests\": \"a02be5d6f61c6d1fca4445faba00019b7fb5743a\", \"midi_unittests\": \"2aebfaccbebe596bc35d945266aa119aa6ce1658\", \"mojo_core_unittests\": \"4d45d9576ffec044a495007438f37d90d432edd8\", \"mojo_unittests\": \"fe8326c0d982ec69ece6ae3fe0668da5b5c13ae9\", \"nacl_helper_nonsfi_unittests\": \"434785a1b340f7ec23401ed9431e2c72ef3b3b2e\", \"nacl_loader_unittests\": \"cd8503002eb770e2ede01269c517e8cb4a6c20c3\", \"native_theme_unittests\": \"929efe93b3637ca6f7e1e916540beeca2c737bf1\", \"net_unittests\": \"f5934e9bf5dd4f33c52f4098d79536b9d1a307fa\", \"ozone_gl_unittests\": \"6d6ffa19bdb8ae4b01bcf5de1b12968e9c7b96b8\", \"ozone_unittests\": \"a2695e7b41a544117e46c7712801a8578b4fe62d\", \"ozone_x11_unittests\": \"90160dc9faedb6714880eb44547eff52192fa97c\", \"pdf_unittests\": \"beba146fb5a03ffe0990a39464d005cdfe53af47\", \"perfetto_unittests\": \"aa2cb2c2728d0051f5a47ea6e9050dabd4771ea2\", \"ppapi_unittests\": \"5ba7aea7b82448e618d15d3ce4cc1b17dfa01f3c\", \"printing_unittests\": \"db92f5b93b70921f233b70bd115f0d72a540b607\", \"remoting_unittests\": \"9dae679c1c34224b2edc0406a89f608ccff699ad\", \"sandbox_linux_unittests\": \"4748d9619fd4caba3815d3bba45dfa76a9840667\", \"service_manager_unittests\": \"35b651024047b25c5bf501ec36be520831b853ff\", \"services_unittests\": \"257f4393f3009bf3a786ef61f13777dc2281e711\", \"shell_dialogs_unittests\": \"0b9909d2e3bd4b6127940f7e883fa01bbffe54fa\", \"shell_encryption_unittests\": \"36468858c6c78c5e4a422179a1a2cc008831d889\", \"skia_unittests\": \"e63ea6bc7306eda18e6179bacfaedc737ae855d9\", \"snapshot_unittests\": \"cc822f9a7eadd58a8653e9d11e78a7fa5e37a2b0\", \"sql_unittests\": \"39a465502a9b70473b1c2a33633899a85b81fc5f\", \"storage_unittests\": \"c9006ce0089ef743c2710202d581d5797e4fba19\", \"sync_integration_tests\": \"7e220e2df1d8ce84dab607de93bfdbf5735c0459\", \"traffic_annotation_auditor_unittests\": \"afb299396b5692c30bec1729dba458eb9003bb7d\", \"ui_base_unittests\": \"4a2dbbd47b78f76fd46205ec90c39ab30ea98bc1\", \"ui_chromeos_unittests\": \"0501d4ab6120ef995e1e72e4ab079a4d83bcd215\", \"ui_touch_selection_unittests\": \"19ed94bca33bed63299fc9b2e8ec17771062738c\", \"unit_tests\": \"f3d9bf3a847931779421b0d322fe699e14a3aad0\", \"url_unittests\": \"b6c38091328b52924c503916151c7e45d88589b6\", \"usage_time_limit_unittests\": \"e631f86c8983fc3d869e9e6ac2712325833d3abb\", \"views_unittests\": \"18717aa62f5a3cdb38b19f776b640d0d2cbe16c7\", \"viz_unittests\": \"04d8559da1324f17c9ec0a66f4d460fbd681fd12\", \"wayland_client_perftests\": \"467cba9a1cd21d01673af6d7fb1d08988aeb0d07\", \"wayland_client_tests\": \"c2dc3f00d9348acde2a65b38cc2297d738a77962\", \"wm_unittests\": \"552e0f8b076a3861335df8ca889c5bbbf24a0210\", \"wtf_unittests\": \"27bf065372bf31e08e6a54d03a9a597738e042f6\", \"zlib_unittests\": \"d5d2a381ad6ac84885eb933314c1cdf54009f4c2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_541cf43b1bca5745fae9eb808cc6f212921580c436c428e330e275f831e0e73a_v2\", \"builder_800f6f487d95d787b5e050c93b44c7c7276a85aefb5f9344834f1f03ffd0e322_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_be71ec2e45ff41f29ecdc3611cfb7c276cc0ff3138424d653aa2b7cfb51218d3_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-172-x07d\"], \"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\": [\"5709-d3c4474\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614208449663816",
"status": "COMPLETED",
"status_changed_ts": "1614211406159929",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45707",
"builder:linux-chromeos-rel",
"buildset:commit/git/98fc87e7d654059e9ddc0c29504ec4e70bfe74cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/98fc87e7d654059e9ddc0c29504ec4e70bfe74cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019803541470548176",
"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/8854373479494847456/+/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:51ef23be813cd710",
"user_agent:luci-scheduler"
],
"updated_ts": "1614211406164883",
"url": "https://ci.chromium.org/b/8854373479494847456",
"utcnow_ts": "1614211406673132"
}
}
[00:03:26.683] Invocation finished in 49m21.005904237s with status SUCCEEDED