Upon initial deployment of vSphere Replication, the plugin (com.vmware.vcHms) is not registered within the vCenter Server (Home > Administration > Client Plugins).
The following symptoms are present:
- No stale extensions for vSphere Replication are present on the Platform Services Controller
- Registering the vSphere Replication plugin against vCenter errors out with: "Bad exit code: 1"
- No vSphere Replication (hms.log) log file is created after attempting to register
- Running lsdoctor (KB80469) observed no Lookup Service errors
- In /var/log/vmware/sso/lookupServer.log, the following message is present when attempting to register the plugin:
[2020-09-25T09:45:25.482Z pool-2-thread-4 vsphere.local 376753fb-####-####-####-########275 INFO com.vmware.identity.idm.server.provider.vmwdirectory.VMwareDirectoryProvider] Cannot find solution user [vpxd-########-####-####-####-########[email protected]] in [CN=ServicePrincipals,DC=example,DC=local]
[...]
[2020-09-25T09:45:25.482Z pool-2-thread-4 INFO com.vmware.vim.lookup.vlsi.VlsiSecurityChecker] Operation create is not permitted for user {Name: vpxd-37ee30a5-####-####-####-########cce, Domain: example.local}
- vCenter 6.x - /var/log/vmware/sso/lookupServer.log
- vCenter 7.x - /var/log/vmware/lookupsvc/lookupServer.log
Trying to register vSphere Replication Appliance, fails with the error:
ERROR
Operation Failed
A general system error occurred: Failed to register VRMS.
Operation ID: ########-####-####-####-########d798
4/12/24, 12:03:48 PM -0600
/var/log/vmware/lookupsvc/lookupserver-default.log: [2024-04-16T16:11:52.845Z pool-2-thread-5 INFO com.vmware.vim.lookup.vlsi.VlsiSecurityChecker] Operation create is not permitted for user <Anonymous>
[2024-04-16T16:11:52.845Z pool-2-thread-5 INFO com.vmware.vim.lookup.vlsi.VlsiSecurityChecker] Operation create is not permitted for user <Anonymous>
[2024-04-16T16:11:52.896Z pool-2-thread-5 INFO com.vmware.vim.lookup.vlsi.VlsiSecurityChecker] Operation create is not permitted for user {Name: vpxd-2b90446f-####-####-####-########ef0, Domain: example.local}/var/log/vmware/vpxd/lookupserver-default.log:
2024-04-19T18:08:20.961Z warning vpxd[06358] [Originator@6876 sub=vmomi.soapStub[0] opID=1ce7966d] SOAP request returned HTTP failure; <SSL(<io_obj p:0x00007f9450c45458, h:7, <TCP '127.0.0.1 : 50746'>, <TCP '127.0.0.1 : 443'>>), /lookupservice/sdk>, method: create; code: 500(Internal Server Error)
2024-04-19T18:08:20.962Z warning vpxd[06358] [Originator@6876 sub=LSClient opID=1ce7966d] Service registration stub privilege error during lookup service RPC: N5Vmomi5Fault13SecurityError9ExceptionE(Fault cause: vmodl.fault.SecurityError
--> )
--> [context]zKq7AVECAQAAAG0mVQEbdnB4ZAAA9tg3bGlidm1hY29yZS5zbwAAjXgsAAtsLQAT6TIBnZcYbGlidm1vbWkuc28AAUtyDAF+GhUBbiQVAX66FAGpuhABqLUQAiSPAmxpYmxvb2t1cC10eXBlcy5zbwADhOmDdnB4ZAADKO6DA3QcgIRxUgUBbGlidmltLXR5cGVzLnNvAIPuyGEBg+nLYAGDKs1gAYNY3GABg7sJYAGDhrNgAQCnSSMANZ8jALRkNwWHfwBsaWJwdGhyZWFkLnNvLjAABi82D2xpYmMuc28uNgA=[/context]
2024-04-19T18:08:21.010Z warning vpxd[06358] [Originator@6876 sub=Vmomi opID=1ce7966d] VMOMI activation LRO failed; <<5263d1dd-####-####-####-########049, <TCP '127.0.0.1 : 8085'>, <TCP '127.0.0.1 : 45920'>>,
ExtensionManager, vim.ExtensionManager.registerExtension>, N5Vmomi5Fault13SecurityError9ExceptionE(Fault cause: vmodl.fault.SecurityError
--> )
--> [context]zKq7AVECAQAAAG0mVQEbdnB4ZAAA9tg3bGlidm1hY29yZS5zbwAAjXgsAAtsLQAT6TIBnZcYbGlidm1vbWkuc28AAUtyDAF+GhUBbiQVAX66FAGpuhABqLUQAiSPAmxpYmxvb2t1cC10eXBlcy5zbwADOeyDdnB4ZAADKO6DA3QcgIRxUgUBbGlidmltLXR5cGVzL
nNvAIPuyGEBg+nLYAGDKs1gAYNY3GABg7sJYAGDhrNgAQCnSSMANZ8jALRkNwWHfwBsaWJwdGhyZWFkLnNvLjAABi82D2xpYmMuc28uNgA=[/context]
2024-04-19T18:08:21.012Z info vpxd[06358] [Originator@6876 sub=vpxLro opID=1ce7966d] [VpxLRO] -- FINISH lro-1484155
2024-04-19T18:08:21.021Z info vpxd[06320] [Originator@6876 sub=vpxLro opID=65f5f5ba] [VpxLRO] -- BEGIN lro-1484166 -- SessionManager -- vim.SessionManager.logout -- 5263d1dd-####-####-####-########049(525b4295
-####-####-####-########77a)
2024-04-19T18:08:21.022Z info vpxd[06320] [Originator@6876 sub=vpxLro opID=65f5f5ba] [VpxLRO] -- FINISH lro-1484166
2024-04-19T18:08:21.026Z info vpxd[06358] [Originator@6876 sub=Default opID=1ce7966d] [VpxLRO] -- ERROR lro-1484155 -- ExtensionManager -- vim.ExtensionManager.registerExtension: vmodl.fault.SecurityError:
--> Result:
--> (vmodl.fault.SecurityError) {
--> faultCause = (vmodl.MethodFault) null,
--> faultMessage = <unset>
2024-04-19T17:09:47.975Z warning vpxd[06345] [Originator@6876 sub=Vmomi opID=406cc114] VMOMI activation LRO failed; <<52457667-####-####-####-########dbf, <TCP '127.0.0.1 : 8085'>, <
TCP '127.0.0.1 : 45920'>>, ProxyService, vim.ProxyService.addEndpoint>, N3Vim5Fault13AlreadyExists9ExceptionE(Fault cause: vim.fault.AlreadyExists
--> )
--> [context]zKq7AVECAQAAAG0mVQEUdnB4ZAAA9tg3bGlidm1hY29yZS5zbwAAjXgsAAtsLQAT6TIBwaFvdnB4ZAABChV+AWAufgE8L36CkpcFAWxpYnZpbS10eXBlcy5zbwCB7shhAYHpy2ABgSrNYAGBWNxgAYG7CWABgYazYAEAp0kjAD
WfIwC0ZDcDh38AbGlicHRocmVhZC5zby4wAAQvNg9saWJjLnNvLjYA[/context]
2024-04-19T17:09:47.977Z info vpxd[06345] [Originator@6876 sub=vpxLro opID=406cc114] [VpxLRO] -- FINISH lro-1461161
2024-04-19T17:09:47.977Z info vpxd[06345] [Originator@6876 sub=Default opID=406cc114] [VpxLRO] -- ERROR lro-1461161 -- ProxyService -- vim.ProxyService.addEndpoint: vim.fault.AlreadyExists:
--> Result:
--> (vim.fault.AlreadyExists) {
--> faultCause = (vmodl.MethodFault) null,
--> faultMessage = <unset>,
--> name = "vcenter.example.com:8089"
--> msg = ""
--> }
--> Args:
-->
--> Arg endpoint:
--> (vim.ProxyService.LocalTunnelSpec) {
--> serverNamespace = "vcenter.example.com:8089",
--> accessMode = "httpOnly",
--> port = 8089
--> }
2024-04-19T17:09:48.048Z info vpxd[06905] [Originator@6876 sub=vpxLro opID=7951b773] [VpxLRO] -- BEGIN lro-1461163 -- ExtensionManager -- vim.ExtensionManager.registerExtension -- 524
57667-####-####-####-########dbf(5246bea9-####-####-####-########d19)
2024-04-19T17:09:48.049Z error vpxd[06905] [Originator@6876 sub=MoExtensionMgr opID=7951b773] Extension with key com.vmware.vcHms not found
/var/log/vmware/hms/hms-configtool.log:
2024-04-10 12:05:31.045 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | vSphere Replication Appliance configuration error:vCenter Server extension configuration problem.
2024-04-10 12:05:31.045 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | Details: Unable to register extension in vCenter Server.
2024-04-10 12:05:31.045 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | null
com.vmware.hms.config.error.VrConfigException: Unable to register extension in vCenter Server.
at com.vmware.hms.config.helper.VcHelper.registerExtension(VcHelper.java:287) ~[vr-config-8.7.0.jar:?]
at com.vmware.hms.config.VrConfig.expressSetup(VrConfig.java:347) ~[vr-config-8.7.0.jar:?]
at com.vmware.hms.config.cli.command.ExpressSetup.run(ExpressSetup.java:58) ~[vr-config-tool-8.7.0.jar:?]
at com.vmware.hms.config.cli.command.CommandBase.run(CommandBase.java:305) ~[vr-config-tool-8.7.0.jar:?]
at com.vmware.hms.config.cli.App.run(App.java:151) ~[vr-config-tool-8.7.0.jar:?]
at com.vmware.hms.config.cli.App.main(App.java:211) ~[vr-config-tool-8.7.0.jar:?]
Caused by: com.vmware.jvsl.cfg.ConfigException: Internal error.
at com.vmware.jvsl.cfg.RegisterExtensionCommand.execute(RegisterExtensionCommand.java:153) ~[jvsl-ext-reg-8.7.0.jar:?]
at com.vmware.hms.config.helper.VcHelper.registerExtension(VcHelper.java:280) ~[vr-config-8.7.0.jar:?]
... 5 more
Caused by: com.vmware.vim.binding.vmodl.fault.SecurityError: Access to perform the operation was denied.
at jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[?:?]
at jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) ~[?:?]
at jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) ~[?:?]
at java.lang.reflect.Constructor.newInstance(Unknown Source) ~[?:?]
at java.lang.Class.newInstance(Unknown Source) ~[?:?]
at com.vmware.vim.vmomi.core.types.impl.ComplexTypeImpl.newInstance(ComplexTypeImpl.java:174) ~[vlsi-core-8.7.0.jar:?]
at com.vmware.vim.vmomi.core.types.impl.DefaultDataObjectFactory.newDataObject(DefaultDataObjectFactory.java:25) ~[vlsi-core-8.7.0.jar:?]
at com.vmware.vim.vmomi.core.soap.impl.unmarshaller.ComplexStackContext.<init>(ComplexStackContext.java:30) ~[vlsi-core-8.7.0.jar:?]
at com.vmware.vim.vmomi.core.soap.impl.unmarshaller.UnmarshallerImpl$UnmarshallSoapFaultContext.parse(UnmarshallerImpl.java:167) ~[vlsi-core-8.7.0.jar:?]
at com.vmware.vim.vmomi.core.soap.impl.unmarshaller.UnmarshallerImpl$UnmarshallSoapFaultContext.unmarshall(UnmarshallerImpl.java:105) ~[vlsi-core-8.7.0.jar:?]
at com.vmware.vim.vmomi.core.soap.impl.unmarshaller.UnmarshallerImpl.unmarshalSoapFault(UnmarshallerImpl.java:92) ~[vlsi-core-8.7.0.jar:?]
at com.vmware.vim.vmomi.core.soap.impl.unmarshaller.UnmarshallerImpl.unmarshalSoapFault(UnmarshallerImpl.java:86) ~[vlsi-core-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.common.impl.SoapFaultStackContext.setValue(SoapFaultStackContext.java:41) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.common.impl.ResponseUnmarshaller.processNextElement(ResponseUnmarshaller.java:127) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.common.impl.ResponseUnmarshaller.unmarshal(ResponseUnmarshaller.java:70) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.common.impl.ResponseImpl.unmarshalResponse(ResponseImpl.java:284) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.common.impl.ResponseImpl.setResponse(ResponseImpl.java:241) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.http.impl.HttpExchangeBase.parseResponse(HttpExchangeBase.java:286) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.http.impl.HttpExchange.invokeWithinScope(HttpExchange.java:54) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.http.impl.TracingScopedRunnable.run(TracingScopedRunnable.java:24) ~[vlsi-client-8.7.0.jar:?]
at com.vmware.vim.vmomi.client.http.impl.HttpExchangeBase.run(HttpExchangeBase.java:60) ~[vlsi-client-8.7.0.jar:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) ~[?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) ~[?:?]
at java.lang.Thread.run(Unknown Source) ~[?:?]
2024-04-10 12:05:31.051 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.hms.config.helper.VcHelper.registerExtension(VcHelper.java:287)
2024-04-10 12:05:31.051 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.hms.config.VrConfig.expressSetup(VrConfig.java:347)
2024-04-10 12:05:31.051 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.hms.config.cli.command.ExpressSetup.run(ExpressSetup.java:58)
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.hms.config.cli.command.CommandBase.run(CommandBase.java:305)
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.hms.config.cli.App.run(App.java:151)
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.hms.config.cli.App.main(App.java:211)
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | Caused by: com.vmware.jvsl.cfg.ConfigException: Internal error.
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.jvsl.cfg.RegisterExtensionCommand.execute(RegisterExtensionCommand.java:153)
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | at com.vmware.hms.config.helper.VcHelper.registerExtension(VcHelper.java:280)
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | ... 5 more
2024-04-10 12:05:31.052 ERROR com.vmware.hms.config.cli.App [main] (..jvsl.util.LoggingErrorStream) [] | Caused by: (vmodl.fault.SecurityError) {
faultCause = null,
faultMessage = null
}