Installation failed: Failed to register with PSC.

Question

Installation failed: Failed to register with PSC.

BurningTheSky opened this issue 5 years ago · 2 comments

Summary

I have deployed v1.5.4 but when I go to register the VIC with my VCSA I get the error "Installation failed: Failed to register with PSC. Please check the PSC settings provided and try again". I have tried multiple times and I have also tried with the curl json method but get the same result. I'm not using an external PSC and the VIC VM can ping the vCenter. I have tried the FQDN and IP of the vCenter but get the same result.

Details

VCSA is 6.7 14070654
ESXi is 6.7 13981272

-- Logs begin at Tue 2020-02-04 20:55:45 UTC, end at Tue 2020-02-04 21:10:01 UTC. --
Feb 04 20:58:03 vic.domain.local systemd[1]: Starting VIC Appliance Web Server...
Feb 04 20:58:03 vic.domain.local systemd[1]: Started VIC Appliance Web Server.
Feb 04 20:58:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:04.020Z INFO op=1616.2: Current UID/GID = 0/0
Feb 04 20:58:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:04.022Z INFO op=1616.2: Loading certificate /storage/data/certs/server.crt and key /storage/data/certs/server.key
Feb 04 20:58:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:04.024Z INFO op=1616.2: Loaded certificate
Feb 04 20:58:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:04.040Z INFO op=1616.2: Starting fileserver server on :9443
Feb 04 20:58:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:04.041Z INFO op=1616.2: Starting redirect server on :80
Feb 04 20:58:06 vic.domain.local start_fileserver.sh[1614]: 2020/02/04 20:58:06 http: TLS handshake error from 192.168.0.30:6591: remote error: tls: unknown certificate
Feb 04 20:58:06 vic.domain.local start_fileserver.sh[1614]: 2020/02/04 20:58:06 http: TLS handshake error from 192.168.0.30:6592: remote error: tls: unknown certificate
Feb 04 20:58:19 vic.domain.local start_fileserver.sh[1614]: 2020/02/04 20:58:19 http: TLS handshake error from 192.168.0.30:6607: remote error: tls: unknown certificate
Feb 04 20:58:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:19.200Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52]
Feb 04 20:58:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:19.200Z DEBUG op=1616.3: [NewOperation] op=1616.3 [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:53]
Feb 04 20:58:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:19.200Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45]
Feb 04 20:58:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:19.200Z INFO op=1616.3: render: html/index.html
Feb 04 20:58:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:19.201Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [1.33266ms]
Feb 04 20:58:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:19.201Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52] [1.422742ms]
Feb 04 20:58:55 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:55.260Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.ThumbprintHandler:33]
Feb 04 20:58:55 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:55.260Z DEBUG op=1616.4: [NewOperation] op=1616.4 [vic-product/installer/fileserver/routes.ThumbprintHandler:37]
Feb 04 20:58:55 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:55.360Z INFO op=1616.4: Thumbprint found
Feb 04 20:58:55 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:55.360Z DEBUG [ END ] [vic-product/installer/fileserver/routes.ThumbprintHandler:33] [100.246731ms]
Feb 04 20:58:58 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:58.925Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52]
Feb 04 20:58:58 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:58.925Z DEBUG op=1616.5: [NewOperation] op=1616.5 [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:53]
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.056Z DEBUG [BEGIN] [vic-product/installer/lib.(*LoginInfo).VerifyLogin:43]
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.056Z INFO op=1616.5: server URL: vcenter.domain.local
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.056Z DEBUG op=1616.5: Creating VMOMI session with thumbprint 02:27:94:29:40:54:64:2D:CF:54:8C:F5:C0:7C:92:ED:D7:57:3D:F4
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.164Z DEBUG Session Environment Info:
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.164Z DEBUG op=1616.5: vSphere resource cache populating...
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.179Z DEBUG op=1616.5: Cached dc:
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.233Z DEBUG op=1616.5: Cached folders:
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.233Z DEBUG op=1616.5: Error count populating vSphere cache: (3)
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.233Z DEBUG [ END ] [vic-product/installer/lib.(*LoginInfo).VerifyLogin:43] [177.52019ms]
Feb 04 20:58:59 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:58:59.233Z INFO op=1616.5: Validation succeeded
Feb 04 20:59:00 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:00.443Z DEBUG op=1616.5: successfully attached the product tag
Feb 04 20:59:00 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:00.447Z INFO op=1616.5: vCenter user: administrator@vsphere.local
Feb 04 20:59:00 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:00.447Z INFO op=1616.5: PSC instance: vcenter.domain.local
Feb 04 20:59:00 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:00.447Z INFO op=1616.5: PSC domain: vsphere.local
Feb 04 20:59:00 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:00.448Z INFO op=1616.5: PSC Out of the box users. CreateUsers: True, FoundCreateUsers: true, Prefix: vic
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:04.375Z INFO op=1616.5: Error running PSC register command for harbor: [0][I][2020-02-04T20:59:00.630Z][1][SsoPscCommand][register][Initializing...]
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: [1][I][2020-02-04T20:59:00.641Z][1][SsoPscCommand][register][Registering...]
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: [2][I][2020-02-04T20:59:00.671Z][1][SsoPscCommand][deleteFile][Removal of file: '/etc/vmware/psc/harbor/psc-config.keystore' result: false]
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: SLF4J: Defaulting to no-operation (NOP) logger implementation
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: 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.
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:156)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminFacade.registerOrRetrieveSolutionUser(SsoAdminFacade.java:172)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.util.SsoPscCommand.register(SsoPscCommand.java:83)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.execute(PscCommand.java:43)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.main(PscCommand.java:56)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: 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.
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.checkTimeSyncronization(SecurityTokenServiceImpl.java:902)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.executeRoundtrip(SecurityTokenServiceImpl.java:858)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl.acquireToken(SecurityTokenServiceImpl.java:149)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:140)
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: ... 4 more
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:04.376Z DEBUG op=1616.5: exit status 1
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:04.384Z ERROR op=1616.5: Install failed: Failed to register with PSC. Please check the PSC settings provided and try again
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:04.384Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45]
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:04.384Z INFO op=1616.5: render: html/index.html
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:04.385Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [719.743µs]
Feb 04 20:59:04 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 20:59:04.385Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52] [5.459981768s]
Feb 04 21:00:39 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:00:39.927Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52]
Feb 04 21:00:39 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:00:39.927Z DEBUG op=1616.6: [NewOperation] op=1616.6 [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:53]
Feb 04 21:00:39 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:00:39.927Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45]
Feb 04 21:00:39 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:00:39.927Z INFO op=1616.6: render: html/index.html
Feb 04 21:00:39 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:00:39.927Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [622.839µs]
Feb 04 21:00:39 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:00:39.927Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52] [737.171µs]
Feb 04 21:01:14 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:14.973Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.ThumbprintHandler:33]
Feb 04 21:01:14 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:14.973Z DEBUG op=1616.7: [NewOperation] op=1616.7 [vic-product/installer/fileserver/routes.ThumbprintHandler:37]
Feb 04 21:01:14 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:14.996Z INFO op=1616.7: Thumbprint found
Feb 04 21:01:14 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:14.996Z DEBUG [ END ] [vic-product/installer/fileserver/routes.ThumbprintHandler:33] [23.414328ms]
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.196Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52]
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.196Z DEBUG op=1616.8: [NewOperation] op=1616.8 [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:53]
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.235Z DEBUG [BEGIN] [vic-product/installer/lib.(*LoginInfo).VerifyLogin:43]
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.235Z INFO op=1616.8: server URL: vcenter.domain.local
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.235Z DEBUG op=1616.8: Creating VMOMI session with thumbprint 02:27:94:29:40:54:64:2D:CF:54:8C:F5:C0:7C:92:ED:D7:57:3D:F4
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.340Z DEBUG Session Environment Info:
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.340Z DEBUG op=1616.8: vSphere resource cache populating...
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.351Z DEBUG op=1616.8: Cached dc:
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.400Z DEBUG op=1616.8: Cached folders:
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.400Z DEBUG op=1616.8: Error count populating vSphere cache: (3)
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.400Z DEBUG [ END ] [vic-product/installer/lib.(*LoginInfo).VerifyLogin:43] [165.369139ms]
Feb 04 21:01:18 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:18.400Z INFO op=1616.8: Validation succeeded
Feb 04 21:01:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:19.310Z DEBUG op=1616.8: successfully attached the product tag
Feb 04 21:01:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:19.313Z INFO op=1616.8: vCenter user: administrator@vsphere.local
Feb 04 21:01:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:19.313Z INFO op=1616.8: PSC instance: vcenter.domain.local
Feb 04 21:01:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:19.313Z INFO op=1616.8: PSC domain: vsphere.local
Feb 04 21:01:19 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:19.314Z INFO op=1616.8: PSC Out of the box users. CreateUsers: True, FoundCreateUsers: true, Prefix: vic
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:22.177Z INFO op=1616.8: Error running PSC register command for harbor: [0][I][2020-02-04T21:01:19.457Z][1][SsoPscCommand][register][Initializing...]
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: [1][I][2020-02-04T21:01:19.464Z][1][SsoPscCommand][register][Registering...]
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: [2][I][2020-02-04T21:01:19.488Z][1][SsoPscCommand][deleteFile][Removal of file: '/etc/vmware/psc/harbor/psc-config.keystore' result: true]
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: SLF4J: Defaulting to no-operation (NOP) logger implementation
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: 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.
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:156)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminFacade.registerOrRetrieveSolutionUser(SsoAdminFacade.java:172)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.util.SsoPscCommand.register(SsoPscCommand.java:83)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.execute(PscCommand.java:43)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.main(PscCommand.java:56)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: 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.
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.checkTimeSyncronization(SecurityTokenServiceImpl.java:902)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.executeRoundtrip(SecurityTokenServiceImpl.java:858)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl.acquireToken(SecurityTokenServiceImpl.java:149)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:140)
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: ... 4 more
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:22.178Z DEBUG op=1616.8: exit status 1
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:22.191Z ERROR op=1616.8: Install failed: Failed to register with PSC. Please check the PSC settings provided and try again
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:22.191Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45]
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:22.191Z INFO op=1616.8: render: html/index.html
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:22.191Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [566.795µs]
Feb 04 21:01:22 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:01:22.191Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52] [3.995728962s]
Feb 04 21:09:17 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:17.293Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52]
Feb 04 21:09:17 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:17.293Z DEBUG op=1616.9: [NewOperation] op=1616.9 [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:53]
Feb 04 21:09:17 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:17.293Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45]
Feb 04 21:09:17 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:17.293Z INFO op=1616.9: render: html/index.html
Feb 04 21:09:17 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:17.294Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [743.692µs]
Feb 04 21:09:17 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:17.294Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52] [882.104µs]
Feb 04 21:09:52 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:52.378Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.ThumbprintHandler:33]
Feb 04 21:09:52 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:52.378Z DEBUG op=1616.10: [NewOperation] op=1616.10 [vic-product/installer/fileserver/routes.ThumbprintHandler:37]
Feb 04 21:09:52 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:52.402Z INFO op=1616.10: Thumbprint found
Feb 04 21:09:52 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:52.402Z DEBUG [ END ] [vic-product/installer/fileserver/routes.ThumbprintHandler:33] [24.971937ms]
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.138Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52]
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.138Z DEBUG op=1616.11: [NewOperation] op=1616.11 [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:53]
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.177Z DEBUG [BEGIN] [vic-product/installer/lib.(*LoginInfo).VerifyLogin:43]
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.177Z INFO op=1616.11: server URL: 192.168.0.13
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.177Z DEBUG op=1616.11: Creating VMOMI session with thumbprint 02:27:94:29:40:54:64:2D:CF:54:8C:F5:C0:7C:92:ED:D7:57:3D:F4
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.282Z DEBUG Session Environment Info:
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.282Z DEBUG op=1616.11: vSphere resource cache populating...
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.297Z DEBUG op=1616.11: Cached dc:
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.346Z DEBUG op=1616.11: Cached folders:
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.346Z DEBUG op=1616.11: Error count populating vSphere cache: (3)
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.346Z DEBUG [ END ] [vic-product/installer/lib.(*LoginInfo).VerifyLogin:43] [169.669314ms]
Feb 04 21:09:56 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:56.346Z INFO op=1616.11: Validation succeeded
Feb 04 21:09:57 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:57.366Z DEBUG op=1616.11: successfully attached the product tag
Feb 04 21:09:57 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:57.370Z INFO op=1616.11: vCenter user: administrator@vsphere.local
Feb 04 21:09:57 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:57.370Z INFO op=1616.11: PSC instance: vcenter.domain.local
Feb 04 21:09:57 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:57.371Z INFO op=1616.11: PSC domain: vsphere.local
Feb 04 21:09:57 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:09:57.371Z INFO op=1616.11: PSC Out of the box users. CreateUsers: True, FoundCreateUsers: true, Prefix: vic
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:10:01.047Z INFO op=1616.11: Error running PSC register command for harbor: [0][I][2020-02-04T21:09:57.518Z][1][SsoPscCommand][register][Initializing...]
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: [1][I][2020-02-04T21:09:57.525Z][1][SsoPscCommand][register][Registering...]
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: [2][I][2020-02-04T21:09:57.549Z][1][SsoPscCommand][deleteFile][Removal of file: '/etc/vmware/psc/harbor/psc-config.keystore' result: true]
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: SLF4J: Defaulting to no-operation (NOP) logger implementation
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: 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.
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:156)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminFacade.registerOrRetrieveSolutionUser(SsoAdminFacade.java:172)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.util.SsoPscCommand.register(SsoPscCommand.java:83)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.execute(PscCommand.java:43)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.util.PscCommand.main(PscCommand.java:56)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: 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.
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.checkTimeSyncronization(SecurityTokenServiceImpl.java:902)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl$RequestResponseProcessor.executeRoundtrip(SecurityTokenServiceImpl.java:858)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.vim.sso.client.impl.SecurityTokenServiceImpl.acquireToken(SecurityTokenServiceImpl.java:149)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: at com.vmware.admiral.auth.idm.psc.saml.sso.admin.SsoAdminClientFactoryImpl.createSSOAdminClient(SsoAdminClientFactoryImpl.java:140)
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: ... 4 more
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:10:01.049Z DEBUG op=1616.11: exit status 1
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:10:01.056Z ERROR op=1616.11: Install failed: Failed to register with PSC. Please check the PSC settings provided and try again
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:10:01.056Z DEBUG [BEGIN] [vic-product/installer/fileserver/routes.RenderTemplate:45]
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:10:01.056Z INFO op=1616.11: render: html/index.html
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:10:01.057Z DEBUG [ END ] [vic-product/installer/fileserver/routes.RenderTemplate:45] [675.711µs]
Feb 04 21:10:01 vic.domain.local start_fileserver.sh[1614]: Feb 4 2020 21:10:01.057Z DEBUG [ END ] [vic-product/installer/fileserver/routes.(*IndexHTMLRenderer).IndexHandler:52] [4.918567835s]

Answer 1 · 2020-02-05T16:25:35.000Z

Hi @BurningTheSky,

Can you setup time synchronization on both vic appliance and vsphere machines? Seems there's a time drift.
See point 3. in https://vmware.github.io/vic-product/assets/files/html/1.5/vic_vsphere_admin/ts_appliance_running_errors.html

Answer 2 · 2020-02-05T18:33:01.000Z

Perfect! Thanks, I didn;t realise I was having NTP issues!