Open gitloy opened 2 years ago
gitloy
commented
2 years ago I will add that I can ssh into the appliance and from there I can successfully ping both the FQDN and IP address of our vcenter/psc
And the time between the VMs is within a second or so.
AlchemicalChef
commented
2 years ago I'm having the same issue here as well, none of the previous troubleshooting steps have worked.
I think we're on our own, VIC is EOL
This is my error log.
Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.247Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:52] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.247Z DEBUG op=1759.19: [NewOperation] op=1759.19 [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:53] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.247Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.247Z INFO op=1759.19: render: html/index.html Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.249Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [2.313119ms] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.249Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:52] [2.615171ms] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.407Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:52] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.407Z DEBUG op=1759.20: [NewOperation] op=1759.20 [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:53] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.407Z ERROR op=1759.20: Request path /favicon.ico not found in map[/:{} /index.html:{}] Jan 25 10:38:08 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:08.407Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:52] [182.385µs] Jan 25 10:38:26 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:26.468Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.ThumbprintHandler:33] Jan 25 10:38:26 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:26.468Z DEBUG op=1759.21: [NewOperation] op=1759.21 [vic-product/installer/fileserver/routes.ThumbprintHandler:37] Jan 25 10:38:26 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:26.503Z INFO op=1759.21: Thumbprint found Jan 25 10:38:26 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:26.503Z DEBUG [ END ] [vic-product/installer/fileserver/routes.ThumbprintHandler:33] [34.42371ms] Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.191Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:52] Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.191Z DEBUG op=1759.22: [NewOperation] op=1759.22 [vic-product/installer/fileserver/routes.(IndexHTMLRenderer).IndexHandler:53] Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.246Z DEBUG [BEGIN] [vic-product/installer/lib.(LoginInfo).VerifyLogin:43] Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.246Z INFO op=1759.22: server URL: vcenter.lysanthyia.com Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.246Z DEBUG op=1759.22: Creating VMOMI session with thumbprint 80:B4:53:8A:80:14:9C:E6:85:96:12:12:5A:36:89:C3:E1:D5:45:34 Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.309Z DEBUG Session Environment Info: Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.309Z DEBUG op=1759.22: vSphere resource cache populating... Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.313Z DEBUG op=1759.22: Cached dc: Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.319Z DEBUG op=1759.22: Cached cluster: Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.334Z DEBUG op=1759.22: Cached host: Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.343Z DEBUG op=1759.22: Cached pool: Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.346Z DEBUG op=1759.22: Cached folders: Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.346Z DEBUG op=1759.22: Error count populating vSphere cache: (1) Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.346Z DEBUG [ END ] [vic-product/installer/lib.(LoginInfo).VerifyLogin:43] [99.47539ms] Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.346Z INFO op=1759.22: Validation succeeded Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.692Z DEBUG op=1759.22: successfully attached the product tag Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.695Z INFO op=1759.22: vCenter user: administrator@vsphere.local Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.695Z INFO op=1759.22: PSC instance: vcenter.lysanthyia.com Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.695Z INFO op=1759.22: PSC domain: vsphere.local Jan 25 10:38:31 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:31.696Z INFO op=1759.22: PSC Out of the box users. CreateUsers: False, FoundCreateUsers: true, Prefix: vic Jan 25 10:38:31 localhost wait_for_file.sh[167]: File /etc/vmware/psc/admiral/psc-config.properties does not yet exist. sleeping 30 seconds... Jan 25 10:38:31 localhost dbus-daemon[170]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.18' (uid=0 pid=2053 comm="hostnamectl status --static ") Jan 25 10:38:31 localhost systemd[1]: Starting Hostname Service... Jan 25 10:38:31 localhost dbus-daemon[170]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 25 10:38:31 localhost systemd[1]: Started Hostname Service. Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:33.690Z INFO op=1759.22: Error running PSC register command for harbor: [0][I][2022-01-25T10:38:31.877Z][1][SsoPscCommand][register][Initializing...] Jan 25 10:38:33 localhost start_fileserver.sh[1758]: [1][I][2022-01-25T10:38:31.883Z][1][SsoPscCommand][register][Registering...] Jan 25 10:38:33 localhost start_fileserver.sh[1758]: [2][I][2022-01-25T10:38:31.902Z][1][SsoPscCommand][deleteFile][Removal of file: '/etc/vmware/psc/harbor/psc-config.keystore' result: true] Jan 25 10:38:33 localhost start_fileserver.sh[1758]: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". Jan 25 10:38:33 localhost start_fileserver.sh[1758]: SLF4J: Defaulting to no-operation (NOP) logger implementation Jan 25 10:38:33 localhost start_fileserver.sh[1758]: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Exception in thread "main" com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientException: Server returned 'request expired' less than 0 seconds after request was issued, but it shouldn't have expired for at least 600 seconds. Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:156) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminFacade.registerOrRetrieveSolutionUser(SsoAdminFacade.java:172) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.admiral.auth.idm.psc.saml.util.SsoPscCommand.register(SsoPscCommand.java:83) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.execute(PscCommand.java:43) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.main(PscCommand.java:56) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Caused by: com.vmware.vim.sso.client.exception.TimeSynchronizationException: Server returned 'request expired' less than 0 seconds after request was issued, but it shouldn't have expired for at least 600 seconds. Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.checkTimeSyncronization(SecurityTokenServiceImpl.java:902) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.executeRoundtrip(SecurityTokenServiceImpl.java:858) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl.acquireToken(SecurityTokenServiceImpl.java:149) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:140) Jan 25 10:38:33 localhost start_fileserver.sh[1758]: ... 4 more Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:33.692Z DEBUG op=1759.22: exit status 1 Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:33.696Z ERROR op=1759.22: Install failed: Failed to register with PSC. Please check the PSC settings provided and try again Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:33.696Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45] Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:33.696Z INFO op=1759.22: render: html/index.html Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:33.697Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [857.124µs] Jan 25 10:38:33 localhost start_fileserver.sh[1758]: Jan 25 2022 10:38:33.697Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52] [2.505682144s] Jan 25 10:39:01 localhost wait_for_file.sh[167]: File /etc/vmware/psc/admiral/psc-config.properties does not yet exist. sleeping 30 seconds...
gitloy
commented
2 years ago @AlchemicalChef From what I've seen on the vmware subreddit I'm pretty sure no one is working this project anymore.
AlchemicalChef
commented
2 years ago Yeah they want you to use Tanzu instead, but it's way more effort than I want to put in.
lazarin
commented
2 years ago Seems like both error logs reveal different problems,
@AlchemicalChef can you try to sync the clock on all machines, probably there's some time lag between them
@gitloy not sure in that case, just seeing that there's Unexpected status code: 404 in library which is trying to connect to the psc :(
Summary
Installation failed: Failed to register with PSC. Please check the PSC settings provided and try again
Environment information
vSphere and vCenter Server version
vSphere: VMware ESXi, 6.7.0, 15160138 vCenter: Server 6.7.0.48000
VIC Appliance version
vic-v1.5.8-7942-4cc5e276.ova
Configuration
Details
When working through the Complete VIC appliance installation step I end up with the following error. All default options were used for the set up of the appliance.
Error: "Installation failed: Failed to register with PSC. Please check the PSC settings provided and try again"
Steps to reproduce
This occurs each time I enter the necessary details
Logs
Below I believe is the relevant log detail
The lines that stick out for me contain
Error running PSC register command for harbor Failed to load class "org.slf4j.impl.StaticLoggerBinder
Jan 19 15:40:24 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:24.348Z INFO op=1762.32: PSC Out of the box users. CreateUsers: True, FoundCreateUsers: true, Prefix : vic
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:26.823Z INFO op=1762.32: Error running PSC register command for harbor: [0][I][2022-01-19T15:40:24.5 38Z][1][SsoPscCommand][register][Initializing...]
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: [1][I][2022-01-19T15:40:24.546Z][1][SsoPscCommand][register][Registering...] Jan 19 15:40:26 localhost start_fileserver.sh[1759]: [2][I][2022-01-19T15:40:24.570Z][1][SsoPscCommand][deleteFile][Removal of file: '/etc/vmware/psc/harbor/psc-config.keys tore' result: true]
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: SLF4J: Defaulting to no-operation (NOP) logger implementation Jan 19 15:40:26 localhost start_fileserver.sh[1759]: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Exception in thread "main" com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientException: General SSO failure. Invalid host, port or tenant. Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClient FactoryImpl.java:148) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminFacade.registerOrRetrieveSolutionUser(SsoAdminFacade. java:172) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.admiral.auth.idm.psc.saml.util.SsoPscCommand.register(SsoPscCommand.java:83) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.execute(PscCommand.java:43) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.main(PscCommand.java:56) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Caused by: com.vmware.vim.sso.admin.exception.InternalError: General failure. Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.impl.VmomiClientCommand.execute(VmomiClientCommand.java:211) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.impl.VmomiClientCommand.executeEnsuringNoDomainError(VmomiClientComman d.java:217) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.impl.AdminClientImpl.createServiceContent(AdminClientImpl.java:341) Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.impl.AdminClientImpl.(AdminClientImpl.java:107)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.VmomiClientFactory.createAdminClient(VmomiClientFactory.java:64)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.VmomiClientFactory.createAdminClient(VmomiClientFactory.java:54)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClient
FactoryImpl.java:123)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: ... 4 more
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Caused by: com.vmware.vim.vmomi.client.common.UnexpectedStatusCodeException: Unexpected status code: 404
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.common.Response$Status.getStatus(Response.java:58)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.http.impl.HttpExchangeBase.parseResponse(HttpExchangeBase.java:150)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.http.impl.HttpExchange.run(HttpExchange.java:48)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.http.impl.HttpProtocolBindingBase.executeRunnable(HttpProtocolBindingBase.java:2
26)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.http.impl.HttpProtocolBindingImpl.send(HttpProtocolBindingImpl.java:110)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.common.impl.MethodInvocationHandlerImpl$CallExecutor.sendCall(MethodInvocationHa
ndlerImpl.java:613)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.common.impl.MethodInvocationHandlerImpl$CallExecutor.executeCall(MethodInvocatio
nHandlerImpl.java:594)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.common.impl.MethodInvocationHandlerImpl.completeCall(MethodInvocationHandlerImpl
.java:345)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.common.impl.MethodInvocationHandlerImpl.invokeOperation(MethodInvocationHandlerI
mpl.java:305)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.vmomi.client.common.impl.MethodInvocationHandlerImpl.invoke(MethodInvocationHandlerImpl.java:
179)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.sun.proxy.$Proxy24.retrieveServiceContent(Unknown Source)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.impl.AdminClientImpl$1.actionCommand(AdminClientImpl.java:339)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.impl.AdminClientImpl$1.actionCommand(AdminClientImpl.java:334)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: at com.vmware.vim.sso.admin.client.vmomi.impl.VmomiClientCommand.execute(VmomiClientCommand.java:103)
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: ... 10 more
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:26.826Z DEBUG op=1762.32: exit status 1
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:26.831Z ERROR op=1762.32: Install failed: Failed to register with PSC. Please check the PSC settings
provided and try again
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:26.831Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45]
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:26.831Z INFO op=1762.32: render: html/index.html
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:26.834Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [3.171027ms]
Jan 19 15:40:26 localhost start_fileserver.sh[1759]: Jan 19 2022 15:40:26.834Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52]
Troubleshooting attempted
I have tried using both the FQDN and IP address for our vcenter with embedded PSC. My admin account is the only one which allows me to get this far in the configuration process. Using the root account for vcenter returns invalid credentials, but they do work with the vcenter:5480 management console.