[ovirt-users] Failing to connect to console via vnc (was: HostedEngine Deployment Woes)

Sun Jan 11 03:11:11 EST 2015

----- Original Message -----
> From: "Mikola Rose" <mrose at power-soft.com>
> To: "Yedidyah Bar David" <didi at redhat.com>
> Cc: users at ovirt.org
> Sent: Thursday, January 8, 2015 9:24:51 PM
> Subject: Re: Failing to connect to console via vnc (was: HostedEngine Deployment Woes)
> 
> Hi again Didi,
> 
> 
> I decided to try a bare metal install and migrate to a self hosted.  The bare
> metal install worked great but same issue conencting to the VM via VNC.
> Just prompts for password seems to connect then auto closes any vnc client
> I use.  From the logs it sasys authentication cancelled.  The password to
> connect was in the hosted-engine —deploy at the end so I didn’t think I
> needed to set it again.

This is a temporary password, by default valid for 3 hours.

The one you set with 'hosted-engine --add-console-password' is temporary
too, valid for 2 minutes.

Did vnc console connection work during deploy?

Do you have engine up and running? If so, can you get the console through
the web admin?

> 
> The VDSM logs for this session;
> 
> Thread-60::DEBUG::2015-01-08
> 11:14:53,712::domainMonitor::192::Storage.DomainMonitorThread::(_monitorDomain)
> Refreshing domain ac1ae7f8-cb48-4251-a246-e68efa9772f0
> Thread-60::DEBUG::2015-01-08
> 11:14:53,718::fileSD::152::Storage.StorageDomain::(__init__) Reading domain
> in path
> /rhev/data-center/mnt/192.168.1.32:_Volumes_Raid1/ac1ae7f8-cb48-4251-a246-e68efa9772f0
> Thread-60::DEBUG::2015-01-08
> 11:14:53,719::persistentDict::192::Storage.PersistentDict::(__init__)
> Created a persistent dict with FileMetadataRW backend
> Thread-60::DEBUG::2015-01-08
> 11:14:53,721::persistentDict::234::Storage.PersistentDict::(refresh) read
> lines (FileMetadataRW)=['CLASS=Data', 'DESCRIPTION=hosted_storage',
> 'IOOPTIMEOUTSEC=10', 'LEASERETRIES=3', 'LEASETIMESEC=60', 'LOCKPOLICY=ON',
> 'LOCKRENEWALINTERVALSEC=5', 'MASTER_VERSION=1',
> 'POOL_DESCRIPTION=hosted_datacenter',
> 'POOL_DOMAINS=ac1ae7f8-cb48-4251-a246-e68efa9772f0:Active',
> 'POOL_SPM_ID=-1', 'POOL_SPM_LVER=0',
> 'POOL_UUID=c4a7ea33-d530-492c-a7fa-13418b6c6f0a',
> 'REMOTE_PATH=192.168.1.32:/Volumes/Raid1', 'ROLE=Master',
> 'SDUUID=ac1ae7f8-cb48-4251-a246-e68efa9772f0', 'TYPE=NFS', 'VERSION=3',
> '_SHA_CKSUM=78daa16373d371fb33ae30943059b955b3d3ebdb']
> Thread-60::DEBUG::2015-01-08
> 11:14:53,723::fileSD::611::Storage.StorageDomain::(imageGarbageCollector)
> Removing remnants of deleted images []
> Thread-60::INFO::2015-01-08
> 11:14:53,724::sd::384::Storage.StorageDomain::(_registerResourceNamespaces)
> Resource namespace ac1ae7f8-cb48-4251-a246-e68efa9772f0_imageNS already
> registered
> Thread-60::INFO::2015-01-08
> 11:14:53,724::sd::392::Storage.StorageDomain::(_registerResourceNamespaces)
> Resource namespace ac1ae7f8-cb48-4251-a246-e68efa9772f0_volumeNS already
> registered
> Thread-60::DEBUG::2015-01-08
> 11:14:53,728::fileSD::261::Storage.Misc.excCmd::(getReadDelay) '/bin/dd
> if=/rhev/data-center/mnt/192.168.1.32:_Volumes_Raid1/ac1ae7f8-cb48-4251-a246-e68efa9772f0/dom_md/metadata
> iflag=direct of=/dev/null bs=4096 count=1' (cwd None)
> Thread-60::DEBUG::2015-01-08
> 11:14:53,736::fileSD::261::Storage.Misc.excCmd::(getReadDelay) SUCCESS:
> <err> = '0+1 records in\n0+1 records out\n480 bytes (480 B) copied,
> 0.000292092 s, 1.6 MB/s\n'; <rc> = 0
> VM Channels Listener::DEBUG::2015-01-08
> 11:14:59,682::vmChannels::95::vds::(_handle_timeouts) Timeout on fileno 12.
> Thread-60::DEBUG::2015-01-08
> 11:15:03,744::fileSD::261::Storage.Misc.excCmd::(getReadDelay) '/bin/dd
> if=/rhev/data-center/mnt/192.168.1.32:_Volumes_Raid1/ac1ae7f8-cb48-4251-a246-e68efa9772f0/dom_md/metadata
> iflag=direct of=/dev/null bs=4096 count=1' (cwd None)
> Thread-60::DEBUG::2015-01-08
> 11:15:03,753::fileSD::261::Storage.Misc.excCmd::(getReadDelay) SUCCESS:
> <err> = '0+1 records in\n0+1 records out\n480 bytes (480 B) copied,
> 0.00036382 s, 1.3 MB/s\n'; <rc> = 0
> Thread-60::DEBUG::2015-01-08
> 11:15:13,762::fileSD::261::Storage.Misc.excCmd::(getReadDelay) '/bin/dd
> if=/rhev/data-center/mnt/192.168.1.32:_Volumes_Raid1/ac1ae7f8-cb48-4251-a246-e68efa9772f0/dom_md/metadata
> iflag=direct of=/dev/null bs=4096 count=1' (cwd None)
> Thread-60::DEBUG::2015-01-08
> 11:15:13,770::fileSD::261::Storage.Misc.excCmd::(getReadDelay) SUCCESS:
> <err> = '0+1 records in\n0+1 records out\n480 bytes (480 B) copied,
> 0.000338997 s, 1.4 MB/s\n'; <rc> = 0

This all seems unrelated, result of some monitoring.

When I try to login with an invalid password, I get in vdsm.log:

libvirtEventLoop::DEBUG::2015-01-11 10:04:41,423::clientIF::555::vm.Vm::(dispatchLibvirtEvents) vmId=`7d43e8bd-7b09-4bd1-9254-6780da07da5a`::graphics event phase 0 localAddr {'node': '0.0.0.0', 'service': '5900', 'family': 0} remoteAddr {'node': '10.36.4.232', 'service': '50218', 'family': 0}authScheme vnc subject []
libvirtEventLoop::DEBUG::2015-01-11 10:04:42,150::clientIF::555::vm.Vm::(dispatchLibvirtEvents) vmId=`7d43e8bd-7b09-4bd1-9254-6780da07da5a`::graphics event phase 1 localAddr {'node': '0.0.0.0', 'service': '5900', 'family': 0} remoteAddr {'node': '10.36.4.232', 'service': '50218', 'family': 0}authScheme vnc subject []
libvirtEventLoop::DEBUG::2015-01-11 10:04:47,476::clientIF::555::vm.Vm::(dispatchLibvirtEvents) vmId=`7d43e8bd-7b09-4bd1-9254-6780da07da5a`::graphics event phase 2 localAddr {'node': '0.0.0.0', 'service': '5900', 'family': 0} remoteAddr {'node': '10.36.4.232', 'service': '50218', 'family': 0}authScheme vnc subject []
Thread-530134::DEBUG::2015-01-11 10:04:49,477::guestagent::356::vm.Vm::(desktopLock) vmId=`7d43e8bd-7b09-4bd1-9254-6780da07da5a`::desktopLock called

If you do not see something similar, you might have some problem earlier.

Best,
-- 
Didi