search

vmware-archive / photon-controller

Photon Controller
Other
27 stars 4 forks source link

The 'photon-setup lightwave install -name lw-1 -lw_domain..' parameter flavoured command is bugged out #128

Open tactical-drone opened 7 years ago

tactical-drone commented 7 years ago

When I execute the command it fails with:

2017-05-31 10:10:26 ERROR Error [Task: Lightwave Installation][Code:1] : Failed to deploy appliance
2017-05-31 10:10:26 ERROR Error [Task: Lightwave Installation][Code:1] : Lightwave Installation failed

Inspecting ESXi events reveals that a user root@10.0.5.1 is attempting to log into ESXi. 10.0.5.1 is the gateway which through the photon-controller-installer.ova (the command) is coming from.

image

The photon-setup lightwave command does not take a ESXi username like the config file does? So use the config file right? Wrong. Nowhere does it show you what the config should look like for a lightwave only install.

So I am trying to recover my cluster (#127 ) by reinstalling lightwave. Using a config file lw-1 works (even though I guessed the config file) but the cluster won't work with it. It's like the step where the controller register at lightwave is missing. Maybe I can restart that by enabling configure-guest on the controllers.... let me try that yes....

brb

tactical-drone commented 7 years ago

Trying to hack in a user that looks like this one also fails. @ is not allowed in the username ;/

image

tactical-drone commented 7 years ago

Actually running photon-setup lightwave install -config also uses root@10.0.5.1 to log in and this time it works. The problem must be something else then. I triple checked the password I supply via cmd line. My best guess is that the password field is not honoured.

tactical-drone commented 7 years ago

The log on ESxi looks like this. As can be seen it should be clear that these logs could never make any sense what so ever. It looks like spaghetti. "Login rejected but accepted but not found but yes but no but who knows these days." 

pam_unix(vmware-authd:auth): authentication failure; logname= uid=0 euid=0 tty= ruser= rhost=  user=root
[LikewiseGetDomainJoinInfo:354] QueryInformation(): ERROR_FILE_NOT_FOUND (2/0):
Rejected password for user root from 10.0.5.1
2017-05-31T11:48:30.849Z verbose hostd[CCC2B70] [Originator@6876 sub=PropertyProvider opID=d635343f] RecordOp REMOVE: latestPage[510], session[52514ea3-e684-3649-47d6-ce187320786c]52ec7d1b-e7dc-bbf1-8fa9-d95768739edc. Applied change to temp map.
2017-05-31T11:48:30.849Z verbose hostd[CCC2B70] [Originator@6876 sub=PropertyProvider opID=d635343f] RecordOp ADD: latestPage[610], session[52514ea3-e684-3649-47d6-ce187320786c]52ec7d1b-e7dc-bbf1-8fa9-d95768739edc. Applied change to temp map.
2017-05-31T11:48:30.849Z verbose hostd[CCC2B70] [Originator@6876 sub=PropertyProvider opID=d635343f] RecordOp ASSIGN: latestEvent, ha-eventmgr. Applied change to temp map.
2017-05-31T11:48:30.849Z info hostd[CCC2B70] [Originator@6876 sub=Vimsvc.ha-eventmgr opID=d635343f] Event 610 : Cannot login root@10.0.5.1
2017-05-31T11:48:33.850Z info hostd[CCC2B70] [Originator@6876 sub=Solo.Vmomi] Activation [N5Vmomi10ActivationE:0x0c61d530] : Invoke done [login] on [vim.SessionManager:ha-sessionmgr]
2017-05-31T11:48:33.850Z verbose hostd[CCC2B70] [Originator@6876 sub=Solo.Vmomi] Arg userName:
--> "root"
2017-05-31T11:48:33.850Z verbose hostd[CCC2B70] [Originator@6876 sub=Solo.Vmomi] Arg password:
--> (not shown)
-->
2017-05-31T11:48:33.850Z verbose hostd[CCC2B70] [Originator@6876 sub=Solo.Vmomi] Arg locale:
--> ""
2017-05-31T11:48:33.850Z info hostd[CCC2B70] [Originator@6876 sub=Solo.Vmomi] Throw vim.fault.InvalidLogin
2017-05-31T11:48:33.850Z info hostd[CCC2B70] [Originator@6876 sub=Solo.Vmomi] Result:
--> (vim.fault.InvalidLogin) {
-->    faultCause = (vmodl.MethodFault) null,
-->    faultMessage = <unset>
-->    msg = ""
--> }
[LikewiseGetDomainJoinInfo:354] QueryInformation(): ERROR_FILE_NOT_FOUND (2/0):
Accepted password for user root from 10.0.5.1
2017-05-31T11:48:33.931Z verbose hostd[D185B70] [Originator@6876 sub=PropertyProvider opID=d6353442] RecordOp REMOVE: latestPage[511], session[52514ea3-e684-3649-47d6-ce187320786c]52ec7d1b-e7dc-bbf1-8fa9-d95768739edc. Applied change to temp map.
2017-05-31T11:48:33.931Z verbose hostd[D185B70] [Originator@6876 sub=PropertyProvider opID=d6353442] RecordOp ADD: latestPage[611], session[52514ea3-e684-3649-47d6-ce187320786c]52ec7d1b-e7dc-bbf1-8fa9-d95768739edc. Applied change to temp map.
2017-05-31T11:48:33.931Z verbose hostd[D185B70] [Originator@6876 sub=PropertyProvider opID=d6353442] RecordOp ASSIGN: latestEvent, ha-eventmgr. Applied change to temp map.
2017-05-31T11:48:33.931Z info hostd[D185B70] [Originator@6876 sub=Vimsvc.ha-eventmgr opID=d6353442] Event 611 : User root@10.0.5.1 logged in as VMware-client/5.1.0
2017-05-31T11:48:33.931Z verbose hostd[D185B70] [Originator@6876 sub=PropertyProvider opID=d6353442] RecordOp ADD: sessionList["52ed957d-21dd-3e17-8c76-8b040ec3d316"], ha-sessionmgr. Applied change to temp map.
2017-05-31T11:48:33.933Z verbose hostd[CC40B70] [Originator@6876 sub=PropertyProvider opID=d6353443 user=root] RecordOp ASSIGN: info, haTask--vim.ServiceInstance.retrieveInternalContent-152517515. Applied change to temp map.
2017-05-31T11:48:33.933Z verbose hostd[CC40B70] [Originator@6876 sub=PropertyProvider opID=d6353443 user=root] RecordOp ASSIGN: info, haTask--vim.ServiceInstance.retrieveInternalContent-152517515. Applied change to temp map.
2017-05-31T11:48:33.936Z verbose hostd[D081B70] [Originator@6876 sub=PropertyProvider opID=d6353445 user=root] RecordOp ASSIGN: info, haTask--vim.ServiceInstance.currentTime-152517516. Applied change to temp map.
2017-05-31T11:48:33.936Z verbose hostd[D081B70] [Originator@6876 sub=PropertyProvider opID=d6353445 user=root] RecordOp ASSIGN: info, haTask--vim.ServiceInstance.currentTime-152517516. Applied change to temp map.
2017-05-31T11:48:33.951Z info hostd[D081B70] [Originator@6876 sub=Vimsvc.TaskManager opID=d635344e user=root] Task Created : haTask--vim.SearchIndex.findByInventoryPath-152517517
2017-05-31T11:48:33.951Z verbose hostd[D081B70] [Originator@6876 sub=PropertyProvider opID=d635344e user=root] RecordOp ADD: recentTask["haTask--vim.SearchIndex.findByInventoryPath-152517517"], ha-taskmgr. Applied change to temp map.
2017-05-31T11:48:33.951Z verbose hostd[D081B70] [Originator@6876 sub=PropertyProvider opID=d635344e user=root] RecordOp ASSIGN: info, haTask--vim.SearchIndex.findByInventoryPath-152517517. Applied change to temp map.
2017-05-31T11:48:33.951Z info hostd[D081B70] [Originator@6876 sub=Solo.SearchIndex opID=d635344e user=root] RefToNamedManagedEntity: name mismatch, found = vm, input = host
2017-05-31T11:48:33.951Z info hostd[D081B70] [Originator@6876 sub=Solo.SearchIndex opID=d635344e user=root] RefToNamedManagedEntity: name mismatch, found = ulysses-2.photon.ctlab.com, input = Resources
2017-05-31T11:48:33.951Z info hostd[D081B70] [Originator@6876 sub=Vimsvc.TaskManager opID=d635344e user=root] Task Completed : haTask--vim.SearchIndex.findByInventoryPath-152517517 Status success
2017-05-31T11:48:33.951Z verbose hostd[D081B70] [Originator@6876 sub=PropertyProvider opID=d635344e user=root] RecordOp ASSIGN: info, haTask--vim.SearchIndex.findByInventoryPath-152517517. Applied change to temp map.
2017-05-31T11:48:33.977Z verbose hostd[CC40B70] [Originator@6876 sub=PropertyProvider opID=d635345c user=root] RecordOp ASSIGN: info, haTask-ha-compute-res-vim.EnvironmentBrowser.queryConfigTarget-152517518. Applied change to temp map.
2017-05-31T11:48:33.980Z info hostd[CC40B70] [Originator@6876 sub=Hostsvc.DvsTracker opID=d635345c user=root] FetchUplinkDVPortgroups: added 0 items
2017-05-31T11:48:33.992Z verbose hostd[96F19D0] [Originator@6876 sub=PropertyProvider opID=d6353460 user=root] RecordOp ASSIGN: info, haTask--vim.SessionManager.logout-152517519. Applied change to temp map.
2017-05-31T11:48:33.992Z verbose hostd[96F19D0] [Originator@6876 sub=PropertyProvider opID=d6353460 user=root] RecordOp REMOVE: sessionList["52ed957d-21dd-3e17-8c76-8b040ec3d316"], ha-sessionmgr. Applied change to temp map.
2017-05-31T11:48:33.992Z verbose hostd[96F19D0] [Originator@6876 sub=PropertyProvider opID=d6353460 user=root] RecordOp REMOVE: latestPage[512], session[52514ea3-e684-3649-47d6-ce187320786c]52ec7d1b-e7dc-bbf1-8fa9-d95768739edc. Applied change to temp map.
2017-05-31T11:48:33.992Z verbose hostd[96F19D0] [Originator@6876 sub=PropertyProvider opID=d6353460 user=root] RecordOp ADD: latestPage[612], session[52514ea3-e684-3649-47d6-ce187320786c]52ec7d1b-e7dc-bbf1-8fa9-d95768739edc. Applied change to temp map.
2017-05-31T11:48:33.992Z verbose hostd[96F19D0] [Originator@6876 sub=PropertyProvider opID=d6353460 user=root] RecordOp ASSIGN: latestEvent, ha-eventmgr. Applied change to temp map.
2017-05-31T11:48:33.992Z info hostd[96F19D0] [Originator@6876 sub=Vimsvc.ha-eventmgr opID=d6353460 user=root] Event 612 : User root@10.0.5.1 logged out (login time: Wednesday, 31 May, 2017 11:48:33, number of API invocations: 0, user agent: VMware-client/5.1.0)
2017-05-31T11:48:33.992Z verbose hostd[96F19D0] [Originator@6876 sub=PropertyProvider opID=d6353460 user=root] RecordOp ASSIGN: info, haTask--vim.SessionManager.logout-152517519. Applied change to temp map.
2017-05-31T11:48:46.884Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=d62e7d21] RecordOp REMOVE: recentTask["haTask-ha-root-pool-vim.ResourcePool.importVApp-152517455"], ha-root-pool. Sent notification immediately.
2017-05-31T11:48:46.884Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=d62e7d21] RecordOp REMOVE: recentTask["haTask-ha-root-pool-vim.ResourcePool.importVApp-152517455"], ha-taskmgr. Applied change to temp map.
2017-05-31T11:48:46.885Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=d62e7d21] RecordOp REMOVE: recentTask["haTask--vim.FileManager.delete-152517485"], ha-taskmgr. Applied change to temp map.
[LikewiseGetDomainJoinInfo:354] QueryInformation(): ERROR_FILE_NOT_FOUND (2/0):
2017-05-31T11:49:22.634Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=643B1688-00019217-3471 user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517520. Applied change to temp map.
2017-05-31T11:49:22.634Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=643B1688-00019217-3471 user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517520. Applied change to temp map.
2017-05-31T11:49:23.986Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=643B1688-0001921F-3479 user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517521. Applied change to temp map.
2017-05-31T11:49:23.986Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=643B1688-0001921F-3479 user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517521. Applied change to temp map.
2017-05-31T11:49:24.440Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=643B1688-00019220-347a user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517522. Applied change to temp map.
2017-05-31T11:49:24.440Z verbose hostd[D1C6B70] [Originator@6876 sub=PropertyProvider opID=643B1688-00019220-347a user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517522. Applied change to temp map.
2017-05-31T11:49:25.534Z verbose hostd[D103B70] [Originator@6876 sub=PropertyProvider opID=643B1688-00019227-3481 user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517523. Applied change to temp map.
2017-05-31T11:49:25.534Z verbose hostd[D103B70] [Originator@6876 sub=PropertyProvider opID=643B1688-00019227-3481 user=root] RecordOp ASSIGN: info, haTask--vmodl.query.PropertyCollector.Filter.destroy-152517523. Applied change to temp map.
2017-05-31T11:49:34.172Z info hostd[D081B70] [Originator@6876 sub=Libs opID=643B1688-00019224-347e]
2017-05-31T11:49:34.172Z info hostd[D081B70] [Originator@6876 sub=VsanSimsStubImpl opID=643B1688-00019224-347e] Calling vim.host.VsanSystemEx.GetVsanRuntimeInfo
2017-05-31T11:49:34.173Z verbose hostd[CC40B70] [Originator@6876 sub=PropertyProvider] RecordOp ASSIGN: runtime.healthSystemRuntime, ha-host. Sent notification immediately.
2017-05-31T11:49:46.886Z verbose hostd[D081B70] [Originator@6876 sub=PropertyProvider opID=d62e7d21] RecordOp REMOVE: recentTask["haTask--vim.SearchIndex.findByInventoryPath-152517494"], ha-taskmgr. Applied change to temp map.

Notice that in the same breath this computer is telling me (in chronological order):

  1. pam_unix(vmware-authd:auth): authentication failure; logname= uid=0 euid=0 tty= ruser= rhost= user=root
  2. Rejected password for user root from 10.0.5.1
  3. Event 610 : Cannot login root@10.0.5.1
  4. Accepted password for user root from 10.0.5.1