- Users - oVirt List Archives

Recommendations for Storage Pool sizes
by Groten, Ryan 18 Nov '14

18 Nov '14

--_000_61A8344D117D814CA4DA393472F38CF4019BEC6D4ACD1001M360cor_ Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: quoted-printable I've been looking around for any best practices/recommendations on how larg= e a storage domain can be but can't seem to find anything. For example, right now I have a 3TB domain made up of 3 1TB luns. That dom= ain has about 200 thin disks created from it. When I want to add more space, is there any reason I should not simply incr= ease the existing pool? Or is it better to create a new pool? Thanks, Ryan --_000_61A8344D117D814CA4DA393472F38CF4019BEC6D4ACD1001M360cor_ Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" xmlns:o=3D"urn:schemas-micr= osoft-com:office:office" xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:dt=3D"uuid:C2F41010-65B3-11d1-A29F-00AA00C14882" xmlns:m=3D"http://sc= hemas.microsoft.com/office/2004/12/omml" xmlns=3D"http://www.w3.org/TR/REC-= html40"><head><META HTTP-EQUIV=3D"Content-Type" CONTENT=3D"text/html; chars= et=3Dus-ascii"><meta name=3DGenerator content=3D"Microsoft Word 14 (filtere= d medium)"><style></style></head><body lang=3DEN-CA link=3Dblue vli= nk=3Dpurple><div class=3DWordSection1>I’ve been = looking around for any best practices/recommendations on how large a storag= e domain can be but can’t seem to find anything.<o:p></o:p></p= ><o:p> </o:p><spa= n style=3D'font-size:10.0pt;font-family:"Century Gothic","sans-serif"'>For = example, right now I have a 3TB domain made up of 3 1TB luns.  That do= main has about 200 thin disks created from it.<o:p></o:p><o:p> </o:p>When I want= to add more space, is there any reason I should not simply increase the ex= isting pool?  Or is it better to create a new pool?<o:p></o:p><= /p><o:p> </o:p><s= pan style=3D'font-size:10.0pt;font-family:"Century Gothic","sans-serif"'>Th= anks,<o:p></o:p>Ryan<o:p></o:p><= p class=3DMsoNormal><o:p> </o:p><o:p>&= nbsp;</o:p></div></body></html>= --_000_61A8344D117D814CA4DA393472F38CF4019BEC6D4ACD1001M360cor_--

1 0

Ubuntu/Debian support?
by Itamar Heim 18 Nov '14

18 Nov '14

So, a quick non-scientific poll... Motivation: It has been suggested that adding ubuntu or debian distro support could broaden the reach of the oVirt community. To date, there has been some work towards this goal, but it is not "coming to a conclusion". Questions that comes to mind: 1) Is this still an issue? 2) Can we afford to dilute the focus we have as it is hard enough to stabilize the currently supported distro's? is it worth the potential impact? 3) Would it have maintainers catering to it so it won't be left behind / delay development? 4) Why bother with host support, ovirt-node can be used? 5) Why bother with engine support, a virtual appliance or a docker image could be used? 6) if we do it, should we focus on Ubuntu or Debian distro first? 7) if we do it, should we focus on host or engine first? Thoughts? Thanks, Itamar

7 8

separate ovirtmgmt from glusterfs traffic
by Demeter Tibor 18 Nov '14

18 Nov '14

------=_Part_2135316_487269704.1415878900070 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit Hi, I have a 3 node gluster based cluster. There are 3x3 Nic with bonding. Sometimes the management is very slow because high gluster traffic. Is it possible to separate ovirtmgmt network from glusterfs traffic? I have only one interface (ovirtmgmt) for all of services (migration, etc). I just wondering I will make three VLANs for these services and use the QoS function. But how can specify glusterfs for use an other vlan for gluster traffic an a other one for management? Also, I have more questions about this: - If I specify a VLAN on ovirtmgmt, then what happend on nodes? I'm afraid the nodes will lost the connection with each other nodes. - Is it possible on a live system? What will happen with the mounted glustefs based datastores? - What does "vm network" mean on ovirtmgmt interface? Can I use this for seperate network traffic? My plan is - vlan.101 ovirtmgmt - vlan 102 glusterfs - vlan 103 migration - vlan 104 display What is the recommended procedure of this? Thanks in advance, Regards, Tibor ------=_Part_2135316_487269704.1415878900070 Content-Type: text/html; charset=utf-8 Content-Transfer-Encoding: quoted-printable <html><body><div style=3D"font-family: times new roman, new york, times, se= rif; font-size: 12pt; color: #000000"><div>Hi,</div><div> </div><div>I h= ave a 3 node gluster based cluster. There are 3x3 Nic with bonding. Sometim= es the management is very slow because high gluster traffic. </div><di= v> </div><div>Is it possible to separate ovirtmgmt network from glusterf= s traffic?</div><div>I have only one interface (ovirtmgmt) for all of servi= ces (migration, etc).</div><div>I just wondering I will make three VLANs fo= r these services and use the QoS function. But how can specify glusterfs fo= r use an other vlan for gluster traffic an a other one for management?&nbsp= ;</div><div>Also, I have more questions about this:</div><div> </div><di= v>- If I specify a VLAN on ovirtmgmt, then what happend on nodes? I'm afrai= d the nodes will lost the connection with each other nodes.</div><div>- Is = it possible on a live system? What will happen with the mounted glustefs ba= sed datastores?</div><div>- What does "vm network" mean on ovirtmgmt interf= ace? Can I use this for seperate network traffic?</div><div> </div><div>= </div><div>My plan is </div><div>- vlan.101 ovirtmgmt</div><div>- = vlan 102 glusterfs</div><div>- vlan 103 migration</div><div>- vlan 104 disp= lay</div><div> </div><div>What is the recommended procedure of this?&nbs= p;</div><div> </div><div>Thanks in advance,</div><div> </div><div>Reg= ards,</div><div> </div><div>Tibor</div><div> </div><div> </div></d= iv></body></html> ------=_Part_2135316_487269704.1415878900070--

4 7

Optaplanner Problem
by s k 18 Nov '14

18 Nov '14

--_d447f566-9b4b-4058-b5e2-f041e4e7bf42_ Content-Type: text/plain; charset="iso-8859-7" Content-Transfer-Encoding: quoted-printable Hello=2C I upgraded oVirt to version 3.5.0 today and I also installed Optaplanner in= a separate VM. While the installation was successful the Optimizer tab in = oVirt Engine doesn't report the correct information. Although there are VMs= that are shutdown and the hosts are not balanced in terms of memory usage = the Optimized tab reports the following: SOLUTION STATUS Status: Waiting for data... Solution is being refreshed every 30 seconds. Freeze solution VMS THAT SHOULD BE STARTED No VM starts are requested at this moment. MIGRATION / START STEPS No migrations are needed at this moment. The state is stable. TARGET STATE host vm memory [all in GB] used available =20 No information is displayed in TARGET STATE. = --_d447f566-9b4b-4058-b5e2-f041e4e7bf42_ Content-Type: text/html; charset="iso-8859-7" Content-Transfer-Encoding: quoted-printable <html> <head> <style></style></head> <body class=3D'hmmessage'><div dir=3D'ltr'>Hello=2C<div> </div><div> = </div><div>I upgraded oVirt to version 3.5.0 today and I also installed Opt= aplanner in a separate VM. While the installation was successful the Optimi= zer tab in oVirt Engine doesn't report the correct information. Although th= ere are VMs that are shutdown and the hosts are not balanced in terms of me= mory usage the Optimized tab reports the following:</div><div> </div><di= v> </div><div><div>SOLUTION STATUS</div><div> </div><div>&nbsp=3BStat= us: Waiting for data...</div><div> </div><div>&nbsp=3BSolution is being = refreshed every 30 seconds.</div><div> </div><div>&nbsp=3BFreeze solutio= n</div><div> </div><div>VMS THAT SHOULD BE STARTED</div><div> </div><= div>No VM starts are requested at this moment.</div><div> </div><div>MIG= RATION / START STEPS</div><div> </div><div>No migrations are needed at t= his moment. The state is stable.</div><div> </div><div>TARGET STATE</div= ><div> </div><div>host vm = memory [all in GB] &nbsp=3B</div><div>&nbsp=3B used available &nbsp=3B</div></div><div> </div><div> </div><div> = </div><div>No information is displayed in TARGET STATE.</div> </= div></body> </html>= --_d447f566-9b4b-4058-b5e2-f041e4e7bf42_--

4 4

iptables management
by Chris Adams 18 Nov '14

18 Nov '14

During setup, I allowed the script to change iptables rules. Is this necessary? Also, is it an "active" management (where oVirt will make changes), or just a one-time thing? I ask because I have some other iptables setup I want (such as limited SSH access), and I don't want to make changes to iptables that oVirt will override later or anything like that. -- Chris Adams <cma(a)cmadams.net>

3 4

Re: [ovirt-users] Fake power management?
by mots 17 Nov '14

17 Nov '14

------=_Part_31_861888835.1416222010953 Content-Type: multipart/alternative; boundary="=_cjtqlQp4QSYnJhuKs0Xg4VLTs9O15oco497qtVs9HKP3duB5" This is a multi-part message in MIME format. Your mail reader does not understand MIME message format. --=_cjtqlQp4QSYnJhuKs0Xg4VLTs9O15oco497qtVs9HKP3duB5 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable Yes, pacemaker manages the engine. That part is working fine, the engine = restarts on the remaining node without problems.=20=0D=0AIt's just that t= he guests don't come back up until the powered down node has been fenced = manually.=0D=0A=0D=0A-----Urspr=C3=BCngliche Nachricht-----=0D=0A> Von:Ba= rak Azulay <bazulay(a)redhat.com <mailto:bazulay@redhat.com> >=0D=0A> Gesen= det: Mon 17 November 2014 11:35=0D=0A> An: Patrick Lottenbach <pl(a)a-bot.c= h <mailto:pl@a-bot.ch> >=0D=0A> CC: users(a)ovirt.org <mailto:users@ovirt.o= rg>=20=0D=0A> Betreff: Re: [ovirt-users] Fake power management=3F=0D=0A>=20= =0D=0A>=20=0D=0A>=20=0D=0A> ----- Original Message -----=0D=0A> > From: "= mots" <mots(a)nepu.moe <mailto:mots@nepu.moe> >=0D=0A> > To: users(a)ovirt.or= g <mailto:users@ovirt.org>=20=0D=0A> > Sent: Friday, November 14, 2014 4:= 54:08 PM=0D=0A> > Subject: [ovirt-users] Fake power management=3F=0D=0A> = >=20=0D=0A> > Fake power management=3F Hello,=0D=0A> >=20=0D=0A> > I'm bu= ilding a small demonstration system for our sales team to take to a=0D=0A= > > customer so that they can show them our solutions.=0D=0A> > Hardware:= Two Intel NUC's, a 4 port switch and a laptop.=0D=0A> > Engine: Runs as = a VM on one of the NUCs, which one it runs on is determined=0D=0A> > by p= acemaker.=0D=0A> > Storage: Also managed by pacemaker, it's drbd backed a= nd accessed with iscsi.=0D=0A> > oVirt version: 3.5=0D=0A> > OS: CentOS 6= =2E6=0D=0A> >=20=0D=0A> > The idea is to have our sales representative (o= r the potential customer=0D=0A> > himself) randomly pull the plug on one = of the NUCs to show that the system=0D=0A> > stays operational when part = of the hardware fails.=0D=0A>=20=0D=0A> I assume you are aware that the e= ngine might fence the node it is running on ...=20=0D=0A> Or do you use p= acemaker to run the engine as well =3F=0D=0A>=20=0D=0A> > My problem is t= hat I don't have any way to implement power management, so the=0D=0A> > E= ngine can't fence nodes and won't restart guests that were running on the= =0D=0A> > node which lost power. In pacemaker I can just configure fencin= g over SSH or=0D=0A> > even disable the requirement to do so completely. = Is there something similar=0D=0A> > for oVirt, so that the Engine will co= nsider a node which it can't connect to=0D=0A> > to be powered down=3F=0D= =0A> >=20=0D=0A> > Regards,=0D=0A> >=20=0D=0A> > mots=0D=0A> >=20=0D=0A> = > _______________________________________________=0D=0A> > Users mailing = list=0D=0A> > Users(a)ovirt.org <mailto:Users@ovirt.org>=20=0D=0A> > http:/= /lists.ovirt.org/mailman/listinfo/users <http://lists.ovirt.org/mailman/l= istinfo/users>=20=0D=0A> >=20=0D=0A>=20=0D=0A=0D=0A --=_cjtqlQp4QSYnJhuKs0Xg4VLTs9O15oco497qtVs9HKP3duB5 Content-Type: text/html; charset=utf-8 Content-Transfer-Encoding: quoted-printable <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN" "http://ww= w.w3.org/TR/html4/loose.dtd"><html>=0A<head>=0A <meta name=3D"Generator"= content=3D"Zarafa WebApp v7.1.10-44973">=0A <meta http-equiv=3D"Content= -Type" content=3D"text/html; charset=3Dutf-8">=0A <title>AW: [ovirt-user= s] Fake power management=3F</title>=0A</head>=0A<body>=0AYes, pacemaker manages the engine. That part is working = fine, the engine restarts on the remaining node without problems. It'= s just that the guests don't come back up until the powered down node has= been fenced manually. <pre style=3D"white-space: -moz-pre-wrap= ; white-space: -pre-wrap; white-space: -o-pre-wrap; white-space: pre-wrap= ; word-wrap: break-word;" wrap=3D"">-----Urspr=C3=BCngliche Nachricht----= - > Von:Barak Azulay <<a href=3D"mailto:bazulay@redhat.com">bazu= lay(a)redhat.com</a>> > Gesendet: Mon 17 November 2014 11:35 &g= t; An: Patrick Lottenbach <<a href=3D"mailto:pl@a-bot.ch">pl(a)a-bot.ch<= /a>> > CC: <a href=3D"mailto:users@ovirt.org">users(a)ovirt.org</a= > > Betreff: Re: [ovirt-users] Fake power management=3F > <br= >> > > ----- Original Message ----- > > From: "= mots" <<a href=3D"mailto:mots@nepu.moe">mots(a)nepu.moe</a>> > = > To: <a href=3D"mailto:users@ovirt.org">users(a)ovirt.org</a> > &= gt; Sent: Friday, November 14, 2014 4:54:08 PM > > Subject: [ovi= rt-users] Fake power management=3F > > > > Fake power = management=3F Hello, > > > > I'm building a small demo= nstration system for our sales team to take to a > > customer so= that they can show them our solutions. > > Hardware: Two Intel = NUC's, a 4 port switch and a laptop. > > Engine: Runs as a VM on= one of the NUCs, which one it runs on is determined > > by pace= maker. > > Storage: Also managed by pacemaker, it's drbd backed = and accessed with iscsi. > > oVirt version: 3.5 > > OS:= CentOS 6.6 > > > > The idea is to have our sales repr= esentative (or the potential customer > > himself) randomly pull= the plug on one of the NUCs to show that the system > > stays o= perational when part of the hardware fails. > > I assume you= are aware that the engine might fence the node it is running on ... = > Or do you use pacemaker to run the engine as well =3F > &g= t; > My problem is that I don't have any way to implement power manage= ment, so the > > Engine can't fence nodes and won't restart gues= ts that were running on the > > node which lost power. In pacema= ker I can just configure fencing over SSH or > > even disable th= e requirement to do so completely. Is there something similar > &gt= ; for oVirt, so that the Engine will consider a node which it can't conne= ct to > > to be powered down=3F > > > > Regar= ds, > > > > mots > > > > __________= _____________________________________ > > Users mailing list = > > <a href=3D"mailto:Users@ovirt.org">Users(a)ovirt.org</a> > = > <a href=3D"http://lists.ovirt.org/mailman/listinfo/users" target=3D"= _blank">http://lists.ovirt.org/mailman/listinfo/users</a> > > <b= r>> </pre>=0A</body>=0A</html> --=_cjtqlQp4QSYnJhuKs0Xg4VLTs9O15oco497qtVs9HKP3duB5-- ------=_Part_31_861888835.1416222010953 Content-Type: application/pgp-signature; name=signature.asc Content-Transfer-Encoding: 7bit Content-Description: OpenPGP digital signature Content-Disposition: attachment; filename="signature.asc" -----BEGIN PGP SIGNATURE----- Version: CIPHERMAIL (2.8.6-4) iQEcBAEBCAAGBQJUadU6AAoJELfzdzVzTtoKVGUH/2KQF6ZTNH7DpnXmG7K4wo9c nB924coImSQfGkFJjvIisRSG6vTnwk+z0uFjptulLl4o3NoPaDLs0IlvjpSvyGwf zFXLbOOls/SBVKOY5Nx7iAjEwIH4DXe06v2dYwJngQGnuyQv7pMDNUAz4uMXCup8 QjV39XbOYXQoLIfHUcGOJB824f2+LgRTrDr4HdZcyE0SHUfJaque3jdCDveXZFkB d0c0RKgB/r8wYBz+T7zIfutxtvKHYp1P5WmpbQ1J1Fr8XZKWgC7l3f7ubJyeldvK wyqZqokR+s1yptnUfLJUJEthnqFK0l+Fq2Vm9vuVpZsvwcn5QgQg+Kn1hmWCT7A= =qpmj -----END PGP SIGNATURE----- ------=_Part_31_861888835.1416222010953--

2 1

ovirt local datacenter/cluster using zfs local storage for storage domain?
by Mathew Gancarz 17 Nov '14

17 Nov '14

--_000_EE5DB373DE433D4A87A60EE0284C92F926421EFDRISXMBX03adsuhn_ Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: quoted-printable Hello all, I'm exploring using oVirt 3.5 as the management engine for a new cluster I = am building. I don't really need high availability and I have 3 servers wit= h fast SSDs on local storage I'd like to use, preferably using ZFS to ensur= e reliability of the storage. I set up a brand new CentOS 6.6 minimal install and have been able to get o= Virt up using the all-in-one plugin. I can use local storage (the default E= XT4 LVM filesystem CentOS sets up) to setup a local data center for each of= the servers but have run into issues when I try to provision a ZFS filesys= tem as a local storage domain. I'm using ZFS on Linux 0.6.3. I first had multipath.conf issues, which prevented me from even setting up = a zpool using the local disks. After blacklisting the local disks in /etc/m= ultipath.conf, I was able to get zfs up and running and create the local do= mains, but I get errors when I try to create a storage Domain of type: Data= / Local on Host and point at the ZFS path (/vmstore/isos) (PS: I'm not try= ing to create an ISO domain, it's just a directory name isos) The error message that comes up is "Error while executing action New Local = Storage Domain: Storage Domain target is unsupported". Has anyone tried this before? I am able to set up an NFS export of the ZFS = folder as a Storage Domain (using directions here: http://virt.guru/2014/02= /25/installing-ovirt-with-shared-local-storage/) but if possible, I'd like = to skip the NFS layer if I can and just go directly to the hardware. The supervdsm.log shows: MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,350::supervdsmServer::1= 01::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', (= 'qemu', 'kvm'), u'/vmstore/isos', 5) {} MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,356::supervdsmServer::1= 08::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,436::supervdsmServer::1= 01::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', (= 'qemu', 'kvm'), u'/vmstore/isos', 5) {} MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,441::supervdsmServer::1= 08::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,519::supervdsmServer::1= 01::SuperVdsm.ServerCallback::(wrapper) call hbaRescan with () {} MainProcess|Thread-1736::INFO::2014-11-17 15:50:31,520::hba::54::Storage.HB= A::(rescan) Rescanning HBAs MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,520::supervdsmServer::1= 08::SuperVdsm.ServerCallback::(wrapper) return hbaRescan with None MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,100::supervdsmServer::1= 01::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', (= 'qemu', 'kvm'), u'/rhev/data-center/mnt/_vmstore_isos', 5) {} MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,105::supervdsmServer::1= 08::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,278::supervdsmServer::1= 01::SuperVdsm.ServerCallback::(wrapper) call hbaRescan with () {} MainProcess|Thread-1744::INFO::2014-11-17 15:50:32,279::hba::54::Storage.HB= A::(rescan) Rescanning HBAs MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,279::supervdsmServer::1= 08::SuperVdsm.ServerCallback::(wrapper) return hbaRescan with None And the vdsm.log shows: Thread-1732::DEBUG::2014-11-17 15:50:31,032::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::moving= from state init -> state preparing Thread-1732::INFO::2014-11-17 15:50:31,032::logUtils::44::dispatcher::(wrap= per) Run and protect: repoStats(options=3DNone) Thread-1732::INFO::2014-11-17 15:50:31,033::logUtils::47::dispatcher::(wrap= per) Run and protect: repoStats, Return response: {u'158ef830-da69-48b4-95b= 0-3615d6fb5b00': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00= 0627117', 'lastCheck': '8.2', 'valid': True}} Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::1191::Storage.TaskManage= r.Task::(prepare) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::finished: = {u'158ef830-da69-48b4-95b0-3615d6fb5b00': {'code': 0, 'version': 3, 'acquir= ed': True, 'delay': '0.000627117', 'lastCheck': '8.2', 'valid': True}} Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::moving= from state preparing -> state finished Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourceManager::940::Storage.= ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources = {} Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourceManager::977::Storage.= ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1732::DEBUG::2014-11-17 15:50:31,034::task::993::Storage.TaskManager= .Task::(_decref) Task=3D`82d29b1b-0c33-4078-887b-476d95f4b1a1`::ref 0 abort= ing False Thread-1732::DEBUG::2014-11-17 15:50:31,042::stompReactor::163::yajsonrpc.S= tompServer::(send) Sending response JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,060::stompReactor::98::B= roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'= SEND'> JsonRpcServer::DEBUG::2014-11-17 15:50:31,062::__init__::504::jsonrpc.JsonR= pcServer::(serve_requests) Waiting for request Thread-1733::DEBUG::2014-11-17 15:50:31,066::stompReactor::163::yajsonrpc.S= tompServer::(send) Sending response JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,338::stompReactor::98::B= roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'= SEND'> JsonRpcServer::DEBUG::2014-11-17 15:50:31,341::__init__::504::jsonrpc.JsonR= pcServer::(serve_requests) Waiting for request Thread-1734::DEBUG::2014-11-17 15:50:31,341::__init__::467::jsonrpc.JsonRpc= Server::(_serveRequest) Calling 'StoragePool.connectStorageServer' in bridg= e with {u'connectionParams': [{u'id': u'00000000-0000-0000-0000-00000000000= 0', u'connection': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'= 1', u'password': u'', u'port': u''}], u'storagepoolID': u'00000000-0000-000= 0-0000-000000000000', u'domainType': 4} Thread-1734::DEBUG::2014-11-17 15:50:31,344::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::moving= from state init -> state preparing Thread-1734::INFO::2014-11-17 15:50:31,345::logUtils::44::dispatcher::(wrap= per) Run and protect: connectStorageServer(domType=3D4, spUUID=3Du'00000000= -0000-0000-0000-000000000000', conList=3D[{u'connection': u'/vmstore/isos',= u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': '******', u'id': u'= 00000000-0000-0000-0000-000000000000', u'port': u''}], options=3DNone) ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,346::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,346::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,347::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,347::__init__::3= 75::IOProcess::(_processLogs) (2321) Got request for method 'access' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,347::__init__::3= 75::IOProcess::(_processLogs) (2321) Queuing response Thread-1734::DEBUG::2014-11-17 15:50:31,357::hsm::2389::Storage.HSM::(__pre= fetchDomains) local _path: /vmstore/isos Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::2396::Storage.HSM::(__pre= fetchDomains) Found SD uuids: () Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::2452::Storage.HSM::(conne= ctStorageServer) knownSDs: {158ef830-da69-48b4-95b0-3615d6fb5b00: storage.l= ocalFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c325a070: storage.nfsSD.fi= ndDomain} Thread-1734::INFO::2014-11-17 15:50:31,358::logUtils::47::dispatcher::(wrap= per) Run and protect: connectStorageServer, Return response: {'statuslist':= [{'status': 0, 'id': u'00000000-0000-0000-0000-000000000000'}]} Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::1191::Storage.TaskManage= r.Task::(prepare) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::finished: = {'statuslist': [{'status': 0, 'id': u'00000000-0000-0000-0000-000000000000'= }]} Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::moving= from state preparing -> state finished Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourceManager::940::Storage.= ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources = {} Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourceManager::977::Storage.= ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1734::DEBUG::2014-11-17 15:50:31,360::task::993::Storage.TaskManager= .Task::(_decref) Task=3D`4088bef4-489c-41a4-bcd4-d5f1906378b9`::ref 0 abort= ing False Thread-1734::DEBUG::2014-11-17 15:50:31,360::__init__::498::jsonrpc.JsonRpc= Server::(_serveRequest) Return 'StoragePool.connectStorageServer' in bridge= with [{'status': 0, 'id': u'00000000-0000-0000-0000-000000000000'}] Thread-1734::DEBUG::2014-11-17 15:50:31,361::stompReactor::163::yajsonrpc.S= tompServer::(send) Sending response JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,425::stompReactor::98::B= roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'= SEND'> JsonRpcServer::DEBUG::2014-11-17 15:50:31,428::__init__::504::jsonrpc.JsonR= pcServer::(serve_requests) Waiting for request Thread-1735::DEBUG::2014-11-17 15:50:31,428::__init__::467::jsonrpc.JsonRpc= Server::(_serveRequest) Calling 'StoragePool.connectStorageServer' in bridg= e with {u'connectionParams': [{u'id': u'deb5a580-6994-4db9-9899-cd05c39c2ef= a', u'connection': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'= 1', u'password': u'', u'port': u''}], u'storagepoolID': u'00000000-0000-000= 0-0000-000000000000', u'domainType': 4} Thread-1735::DEBUG::2014-11-17 15:50:31,431::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::moving= from state init -> state preparing Thread-1735::INFO::2014-11-17 15:50:31,431::logUtils::44::dispatcher::(wrap= per) Run and protect: connectStorageServer(domType=3D4, spUUID=3Du'00000000= -0000-0000-0000-000000000000', conList=3D[{u'connection': u'/vmstore/isos',= u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': '******', u'id': u'= deb5a580-6994-4db9-9899-cd05c39c2efa', u'port': u''}], options=3DNone) ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,433::__init__::3= 75::IOProcess::(_processLogs) (2322) Got request for method 'access' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:31,434::__init__::3= 75::IOProcess::(_processLogs) (2322) Queuing response Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::2389::Storage.HSM::(__pre= fetchDomains) local _path: /vmstore/isos Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::2396::Storage.HSM::(__pre= fetchDomains) Found SD uuids: () Thread-1735::DEBUG::2014-11-17 15:50:31,443::hsm::2452::Storage.HSM::(conne= ctStorageServer) knownSDs: {158ef830-da69-48b4-95b0-3615d6fb5b00: storage.l= ocalFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c325a070: storage.nfsSD.fi= ndDomain} Thread-1735::INFO::2014-11-17 15:50:31,443::logUtils::47::dispatcher::(wrap= per) Run and protect: connectStorageServer, Return response: {'statuslist':= [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05c39c2efa'}]} Thread-1735::DEBUG::2014-11-17 15:50:31,443::task::1191::Storage.TaskManage= r.Task::(prepare) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::finished: = {'statuslist': [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05c39c2efa'= }]} Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::moving= from state preparing -> state finished Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourceManager::940::Storage.= ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources = {} Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourceManager::977::Storage.= ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::993::Storage.TaskManager= .Task::(_decref) Task=3D`1cdc582f-bca2-49c1-a48d-4463f53f0481`::ref 0 abort= ing False Thread-1735::DEBUG::2014-11-17 15:50:31,445::__init__::498::jsonrpc.JsonRpc= Server::(_serveRequest) Return 'StoragePool.connectStorageServer' in bridge= with [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05c39c2efa'}] Thread-1735::DEBUG::2014-11-17 15:50:31,445::stompReactor::163::yajsonrpc.S= tompServer::(send) Sending response JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,451::stompReactor::98::B= roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'= SEND'> Thread-1736::DEBUG::2014-11-17 15:50:31,454::__init__::467::jsonrpc.JsonRpc= Server::(_serveRequest) Calling 'StorageDomain.create' in bridge with {u'na= me': u'test', u'domainType': 4, u'domainClass': 1, u'typeArgs': u'/vmstore/= isos', u'version': u'3', u'storagedomainID': u'c46aa2c4-c405-45eb-b7fd-71f6= 27d1c546'} JsonRpcServer::DEBUG::2014-11-17 15:50:31,454::__init__::504::jsonrpc.JsonR= pcServer::(serve_requests) Waiting for request Thread-1736::DEBUG::2014-11-17 15:50:31,459::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::moving= from state init -> state preparing Thread-1736::INFO::2014-11-17 15:50:31,459::logUtils::44::dispatcher::(wrap= per) Run and protect: createStorageDomain(storageType=3D4, sdUUID=3Du'c46aa= 2c4-c405-45eb-b7fd-71f627d1c546', domainName=3Du'test', typeSpecificArg=3Du= '/vmstore/isos', domClass=3D1, domVersion=3Du'3', options=3DNone) Thread-1736::DEBUG::2014-11-17 15:50:31,459::misc::741::Storage.SamplingMet= hod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage= ) Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::743::Storage.SamplingMet= hod::(__call__) Got in to sampling method Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::741::Storage.SamplingMet= hod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::743::Storage.SamplingMet= hod::(__call__) Got in to sampling method Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsi::403::Storage.ISCSI::(re= scan) Performing SCSI scan, this will take up to 30 seconds Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsiadm::92::Storage.Misc.exc= Cmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m session -R (cwd None) Thread-1736::DEBUG::2014-11-17 15:50:31,517::misc::751::Storage.SamplingMet= hod::(__call__) Returning last result Thread-1736::DEBUG::2014-11-17 15:50:31,521::multipath::110::Storage.Misc.e= xcCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cwd None) Thread-1736::DEBUG::2014-11-17 15:50:31,705::multipath::110::Storage.Misc.e= xcCmd::(rescan) SUCCESS: <err> =3D ''; <rc> =3D 0 Thread-1736::DEBUG::2014-11-17 15:50:31,706::lvm::489::Storage.OperationMut= ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operat= ion mutex Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::491::Storage.OperationMut= ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the o= peration mutex Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::500::Storage.OperationMut= ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operat= ion mutex Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::502::Storage.OperationMut= ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the o= peration mutex Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::520::Storage.OperationMut= ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operat= ion mutex Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::522::Storage.OperationMut= ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the o= peration mutex Thread-1736::DEBUG::2014-11-17 15:50:31,709::misc::751::Storage.SamplingMet= hod::(__call__) Returning last result Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::137::Storage.StorageDomai= nCache::(_findDomain) looking for unfetched domain c46aa2c4-c405-45eb-b7fd-= 71f627d1c546 Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::154::Storage.StorageDomai= nCache::(_findUnfetchedDomain) looking for domain c46aa2c4-c405-45eb-b7fd-7= 1f627d1c546 Thread-1736::DEBUG::2014-11-17 15:50:31,710::lvm::365::Storage.OperationMut= ex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex Thread-1736::DEBUG::2014-11-17 15:50:31,712::lvm::288::Storage.Misc.excCmd:= :(cmd) /usr/bin/sudo -n /sbin/lvm vgs --config ' devices { preferred_names = =3D ["^/dev/mapper/"] ignore_suspended_devices=3D1 write_cache_state=3D0 di= sable_after_error_count=3D3 obtain_device_list_from_udev=3D0 filter =3D [ '= \''r|.*|'\'' ] } global { locking_type=3D1 prioritise_write_locks=3D1 w= ait_for_locks=3D1 use_lvmetad=3D0 } backup { retain_min =3D 50 retain_d= ays =3D 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreski= ppedcluster -o uuid,name,attr,size,free,extent_size,extent_count,free_count= ,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name c46aa2c4-c405-45eb-= b7fd-71f627d1c546 (cwd None) Thread-1736::DEBUG::2014-11-17 15:50:32,049::lvm::288::Storage.Misc.excCmd:= :(cmd) FAILED: <err> =3D ' Volume group "c46aa2c4-c405-45eb-b7fd-71f627d1c= 546" not found\n Skipping volume group c46aa2c4-c405-45eb-b7fd-71f627d1c54= 6\n'; <rc> =3D 5 Thread-1736::WARNING::2014-11-17 15:50:32,052::lvm::370::Storage.LVM::(_rel= oadvgs) lvm vgs failed: 5 [] [' Volume group "c46aa2c4-c405-45eb-b7fd-71f6= 27d1c546" not found', ' Skipping volume group c46aa2c4-c405-45eb-b7fd-71f6= 27d1c546'] Thread-1736::DEBUG::2014-11-17 15:50:32,052::lvm::407::Storage.OperationMut= ex::(_reloadvgs) Operation 'lvm reload operation' released the operation mu= tex ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,060::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,061::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,062::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,062::__init__::3= 75::IOProcess::(_processLogs) (2323) Got request for method 'glob' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,062::__init__::3= 75::IOProcess::(_processLogs) (2323) Queuing response ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,064::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,065::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,065::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,065::__init__::3= 75::IOProcess::(_processLogs) (2324) Got request for method 'glob' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,066::__init__::3= 75::IOProcess::(_processLogs) (2324) Queuing response ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,067::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,068::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,069::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,069::__init__::3= 75::IOProcess::(_processLogs) (2325) Got request for method 'glob' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,069::__init__::3= 75::IOProcess::(_processLogs) (2325) Queuing response ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,079::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,082::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,082::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,083::__init__::3= 75::IOProcess::(_processLogs) (2326) Got request for method 'glob' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,083::__init__::3= 75::IOProcess::(_processLogs) (2326) Queuing response ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,085::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,086::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,086::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,087::__init__::3= 75::IOProcess::(_processLogs) (2327) Got request for method 'glob' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,087::__init__::3= 75::IOProcess::(_processLogs) (2327) Queuing response ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,089::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,089::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,090::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,090::__init__::3= 75::IOProcess::(_processLogs) (2328) Got request for method 'glob' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,090::__init__::3= 75::IOProcess::(_processLogs) (2328) Queuing response ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,092::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3= 75::IOProcess::(_processLogs) (2329) Got request for method 'glob' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,093::__init__::3= 75::IOProcess::(_processLogs) (2329) Queuing response Thread-1736::ERROR::2014-11-17 15:50:32,095::sdc::143::Storage.StorageDomai= nCache::(_findDomain) domain c46aa2c4-c405-45eb-b7fd-71f627d1c546 not found Traceback (most recent call last): File "/usr/share/vdsm/storage/sdc.py", line 141, in _findDomain dom =3D findMethod(sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 171, in _findUnfetchedDomain raise se.StorageDomainDoesNotExist(sdUUID) StorageDomainDoesNotExist: Storage domain does not exist: (u'c46aa2c4-c405-= 45eb-b7fd-71f627d1c546',) Thread-1736::INFO::2014-11-17 15:50:32,096::localFsSD::73::Storage.StorageD= omain::(create) sdUUID=3Dc46aa2c4-c405-45eb-b7fd-71f627d1c546 domainName=3D= test remotePath=3D/vmstore/isos domClass=3D1 ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,097::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,098::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,098::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,098::__init__::3= 75::IOProcess::(_processLogs) (2330) Got request for method 'access' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,099::__init__::3= 75::IOProcess::(_processLogs) (2330) Queuing response ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,107::__init__::3= 75::IOProcess::(_processLogs) Receiving request... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,107::__init__::3= 75::IOProcess::(_processLogs) Queuing request in the thread pool... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,108::__init__::3= 75::IOProcess::(_processLogs) Extracting request information... ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,108::__init__::3= 75::IOProcess::(_processLogs) (2331) Got request for method 'touch' ioprocess communication (5073)::DEBUG::2014-11-17 15:50:32,108::__init__::3= 75::IOProcess::(_processLogs) (2331) Queuing response Thread-1736::ERROR::2014-11-17 15:50:32,109::fileSD::92::Storage.fileSD::(v= alidateFileSystemFeatures) Underlying file system doesn't supportdirect IO Thread-1736::ERROR::2014-11-17 15:50:32,109::task::866::Storage.TaskManager= .Task::(_setError) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Unexpecte= d error Traceback (most recent call last): File "/usr/share/vdsm/storage/task.py", line 873, in _run return fn(*args, **kargs) File "/usr/share/vdsm/logUtils.py", line 45, in wrapper res =3D f(*args, **kwargs) File "/usr/share/vdsm/storage/hsm.py", line 2683, in createStorageDomain domVersion) File "/usr/share/vdsm/storage/localFsSD.py", line 84, in create cls._preCreateValidation(sdUUID, mntPoint, remotePath, version) File "/usr/share/vdsm/storage/localFsSD.py", line 51, in _preCreateValida= tion fileSD.validateFileSystemFeatures(sdUUID, domPath) File "/usr/share/vdsm/storage/fileSD.py", line 94, in validateFileSystemF= eatures raise se.StorageDomainTargetUnsupported() StorageDomainTargetUnsupported: Storage Domain target is unsupported: () Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::885::Storage.TaskManager= .Task::(_run) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Task._run: 54f= 143dd-2229-4bc1-b639-9017ea3ecdd0 (4, u'c46aa2c4-c405-45eb-b7fd-71f627d1c54= 6', u'test', u'/vmstore/isos', 1, u'3') {} failed - stopping task Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::1217::Storage.TaskManage= r.Task::(stop) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::stopping in s= tate preparing (force False) Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::993::Storage.TaskManager= .Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::ref 1 abort= ing True Thread-1736::INFO::2014-11-17 15:50:32,111::task::1171::Storage.TaskManager= .Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::aborting: T= ask is aborted: 'Storage Domain target is unsupported' - code 399 Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::1176::Storage.TaskManage= r.Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Prepare: a= borted: Storage Domain target is unsupported Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::993::Storage.TaskManager= .Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::ref 0 abort= ing True Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::928::Storage.TaskManager= .Task::(_doAbort) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::Task._doAb= ort: force False Thread-1736::DEBUG::2014-11-17 15:50:32,112::resourceManager::977::Storage.= ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::moving= from state preparing -> state aborting Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::550::Storage.TaskManager= .Task::(__state_aborting) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::_a= borting: recover policy none Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b639-9017ea3ecdd0`::moving= from state aborting -> state failed Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourceManager::940::Storage.= ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources = {} Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourceManager::977::Storage.= ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1736::ERROR::2014-11-17 15:50:32,114::dispatcher::76::Storage.Dispat= cher::(wrapper) {'status': {'message': 'Storage Domain target is unsupporte= d: ()', 'code': 399}} Thread-1736::DEBUG::2014-11-17 15:50:32,114::stompReactor::163::yajsonrpc.S= tompServer::(send) Sending response JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:32,212::stompReactor::98::B= roker.StompAdapter::(handle_frame) Handling message <StompFrame command=3D'= SEND'> Thread-1744::DEBUG::2014-11-17 15:50:32,215::__init__::467::jsonrpc.JsonRpc= Server::(_serveRequest) Calling 'StoragePool.disconnectStorageServer' in br= idge with {u'connectionParams': [{u'id': u'deb5a580-6994-4db9-9899-cd05c39c= 2efa', u'connection': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt':= u'1', u'password': u'', u'port': u''}], u'storagepoolID': u'00000000-0000-= 0000-0000-000000000000', u'domainType': 4} JsonRpcServer::DEBUG::2014-11-17 15:50:32,215::__init__::504::jsonrpc.JsonR= pcServer::(serve_requests) Waiting for request Thread-1744::DEBUG::2014-11-17 15:50:32,220::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::moving= from state init -> state preparing Thread-1744::INFO::2014-11-17 15:50:32,221::logUtils::44::dispatcher::(wrap= per) Run and protect: disconnectStorageServer(domType=3D4, spUUID=3Du'00000= 000-0000-0000-0000-000000000000', conList=3D[{u'connection': u'/vmstore/iso= s', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': '******', u'id':= u'deb5a580-6994-4db9-9899-cd05c39c2efa', u'port': u''}], options=3DNone) Thread-1744::DEBUG::2014-11-17 15:50:32,221::misc::741::Storage.SamplingMet= hod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage= ) Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::743::Storage.SamplingMet= hod::(__call__) Got in to sampling method Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::741::Storage.SamplingMet= hod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::743::Storage.SamplingMet= hod::(__call__) Got in to sampling method Thread-1744::DEBUG::2014-11-17 15:50:32,222::iscsi::403::Storage.ISCSI::(re= scan) Performing SCSI scan, this will take up to 30 seconds Thread-1744::DEBUG::2014-11-17 15:50:32,223::iscsiadm::92::Storage.Misc.exc= Cmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m session -R (cwd None) Thread-1744::DEBUG::2014-11-17 15:50:32,276::misc::751::Storage.SamplingMet= hod::(__call__) Returning last result Thread-1744::DEBUG::2014-11-17 15:50:32,280::multipath::110::Storage.Misc.e= xcCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cwd None) Thread-1744::DEBUG::2014-11-17 15:50:32,409::multipath::110::Storage.Misc.e= xcCmd::(rescan) SUCCESS: <err> =3D ''; <rc> =3D 0 Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::489::Storage.OperationMut= ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operat= ion mutex Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::491::Storage.OperationMut= ex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the o= peration mutex Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::500::Storage.OperationMut= ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operat= ion mutex Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::502::Storage.OperationMut= ex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the o= peration mutex Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::520::Storage.OperationMut= ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operat= ion mutex Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::522::Storage.OperationMut= ex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the o= peration mutex Thread-1744::DEBUG::2014-11-17 15:50:32,412::misc::751::Storage.SamplingMet= hod::(__call__) Returning last result Thread-1744::INFO::2014-11-17 15:50:32,413::logUtils::47::dispatcher::(wrap= per) Run and protect: disconnectStorageServer, Return response: {'statuslis= t': [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05c39c2efa'}]} Thread-1744::DEBUG::2014-11-17 15:50:32,413::task::1191::Storage.TaskManage= r.Task::(prepare) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::finished: = {'statuslist': [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05c39c2efa'= }]} Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::595::Storage.TaskManager= .Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::moving= from state preparing -> state finished Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourceManager::940::Storage.= ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources = {} Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourceManager::977::Storage.= ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::993::Storage.TaskManager= .Task::(_decref) Task=3D`11fde6d7-ad39-4b40-bb40-00816fe2c131`::ref 0 abort= ing False Thread-1744::DEBUG::2014-11-17 15:50:32,415::__init__::498::jsonrpc.JsonRpc= Server::(_serveRequest) Return 'StoragePool.disconnectStorageServer' in bri= dge with [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05c39c2efa'}] Thread-1744::DEBUG::2014-11-17 15:50:32,416::stompReactor::163::yajsonrpc.S= tompServer::(send) Sending response --_000_EE5DB373DE433D4A87A60EE0284C92F926421EFDRISXMBX03adsuhn_ Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" xmlns:o=3D"urn:schemas-micr= osoft-com:office:office" xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:m=3D"http://schemas.microsoft.com/office/2004/12/omml" xmlns=3D"http:= //www.w3.org/TR/REC-html40"> <head> <meta http-equiv=3D"Content-Type" content=3D"text/html; charset=3Dus-ascii"= > <meta name=3D"Generator" content=3D"Microsoft Word 14 (filtered medium)"> <style></style> </head> <body lang=3D"EN-US" link=3D"blue" vlink=3D"purple"> <div class=3D"WordSection1"> Hello all,<o:p></o:p> <o:p> </o:p> I’m exploring using oVirt 3.5 as the managemen= t engine for a new cluster I am building. I don’t really need high av= ailability and I have 3 servers with fast SSDs on local storage I’d l= ike to use, preferably using ZFS to ensure reliability of the storage.<o:p></o:p> <o:p> </o:p> I set up a brand new CentOS 6.6 minimal install and = have been able to get oVirt up using the all-in-one plugin. I can use local= storage (the default EXT4 LVM filesystem CentOS sets up) to setup a local = data center for each of the servers but have run into issues when I try to provision a ZFS filesystem as a loc= al storage domain. I’m using ZFS on Linux 0.6.3.<o:p></o:p> <o:p> </o:p> I first had multipath.conf issues, which prevented m= e from even setting up a zpool using the local disks. After blacklisting th= e local disks in /etc/multipath.conf, I was able to get zfs up and running = and create the local domains, but I get errors when I try to create a storage Domain of type: Data / Local o= n Host and point at the ZFS path (/vmstore/isos) (PS: I’m not trying = to create an ISO domain, it’s just a directory name isos)<o:p></o:p><= /p> <o:p> </o:p> The error message that comes up is “Error whil= e executing action New Local Storage Domain: Storage Domain target is unsup= ported”.<o:p></o:p> <o:p> </o:p> Has anyone tried this before? I am able to set up an= NFS export of the ZFS folder as a Storage Domain (using directions here: <a href=3D"http://virt.guru/2014/02/25/installing-ovirt-with-shared-local-s= torage/"> http://virt.guru/2014/02/25/installing-ovirt-with-shared-local-storage/</a>= ) but if possible, I’d like to skip the NFS layer if I can and just g= o directly to the hardware.<o:p></o:p> <o:p> </o:p> The supervdsm.log shows:<o:p></o:p> MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,= 350::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call validat= eAccess with ('qemu', ('qemu', 'kvm'), u'/vmstore/isos', 5) {}<o:p></o:p></= p> MainProcess|Thread-1734::DEBUG::2014-11-17 15:50:31,= 356::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return valid= ateAccess with None<o:p></o:p> MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,= 436::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call validat= eAccess with ('qemu', ('qemu', 'kvm'), u'/vmstore/isos', 5) {}<o:p></o:p></= p> MainProcess|Thread-1735::DEBUG::2014-11-17 15:50:31,= 441::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return valid= ateAccess with None<o:p></o:p> MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,= 519::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call hbaResc= an with () {}<o:p></o:p> MainProcess|Thread-1736::INFO::2014-11-17 15:50:31,5= 20::hba::54::Storage.HBA::(rescan) Rescanning HBAs<o:p></o:p> MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:31,= 520::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return hbaRe= scan with None<o:p></o:p> MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,= 100::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call validat= eAccess with ('qemu', ('qemu', 'kvm'), u'/rhev/data-center/mnt/_vmstore_iso= s', 5) {}<o:p></o:p> MainProcess|Thread-1736::DEBUG::2014-11-17 15:50:32,= 105::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return valid= ateAccess with None<o:p></o:p> MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,= 278::supervdsmServer::101::SuperVdsm.ServerCallback::(wrapper) call hbaResc= an with () {}<o:p></o:p> MainProcess|Thread-1744::INFO::2014-11-17 15:50:32,2= 79::hba::54::Storage.HBA::(rescan) Rescanning HBAs<o:p></o:p> MainProcess|Thread-1744::DEBUG::2014-11-17 15:50:32,= 279::supervdsmServer::108::SuperVdsm.ServerCallback::(wrapper) return hbaRe= scan with None<o:p></o:p> <o:p> </o:p> And the vdsm.log shows:<o:p></o:p> Thread-1732::DEBUG::2014-11-17 15:50:31,032::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887= b-476d95f4b1a1`::moving from state init -> state preparing<o:p></o:p></p= > Thread-1732::INFO::2014-11-17 15:50:31,032::logUtils= ::44::dispatcher::(wrapper) Run and protect: repoStats(options=3DNone)<o:p>= </o:p> Thread-1732::INFO::2014-11-17 15:50:31,033::logUtils= ::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {u= '158ef830-da69-48b4-95b0-3615d6fb5b00': {'code': 0, 'version': 3, 'acquired= ': True, 'delay': '0.000627117', 'lastCheck': '8.2', 'valid': True}}<o:p></o:p> Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::1= 191::Storage.TaskManager.Task::(prepare) Task=3D`82d29b1b-0c33-4078-887b-47= 6d95f4b1a1`::finished: {u'158ef830-da69-48b4-95b0-3615d6fb5b00': {'code': 0= , 'version': 3, 'acquired': True, 'delay': '0.000627117', 'lastCheck': '8.2', 'valid': True}}<o:p></o:p> Thread-1732::DEBUG::2014-11-17 15:50:31,033::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`82d29b1b-0c33-4078-887= b-476d95f4b1a1`::moving from state preparing -> state finished<o:p></o:p= > Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourc= eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll= requests {} resources {}<o:p></o:p> Thread-1732::DEBUG::2014-11-17 15:50:31,034::resourc= eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r= equests {}<o:p></o:p> Thread-1732::DEBUG::2014-11-17 15:50:31,034::task::9= 93::Storage.TaskManager.Task::(_decref) Task=3D`82d29b1b-0c33-4078-887b-476= d95f4b1a1`::ref 0 aborting False<o:p></o:p> Thread-1732::DEBUG::2014-11-17 15:50:31,042::stompRe= actor::163::yajsonrpc.StompServer::(send) Sending response<o:p></o:p> JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,0= 60::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message = <StompFrame command=3D'SEND'><o:p></o:p> JsonRpcServer::DEBUG::2014-11-17 15:50:31,062::__ini= t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><= /o:p> Thread-1733::DEBUG::2014-11-17 15:50:31,066::stompRe= actor::163::yajsonrpc.StompServer::(send) Sending response<o:p></o:p> JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,3= 38::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message = <StompFrame command=3D'SEND'><o:p></o:p> JsonRpcServer::DEBUG::2014-11-17 15:50:31,341::__ini= t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><= /o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,341::__init_= _::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StoragePool.connect= StorageServer' in bridge with {u'connectionParams': [{u'id': u'00000000-000= 0-0000-0000-000000000000', u'connection': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': u= '', u'port': u''}], u'storagepoolID': u'00000000-0000-0000-0000-00000000000= 0', u'domainType': 4}<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,344::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd= 4-d5f1906378b9`::moving from state init -> state preparing<o:p></o:p></p= > Thread-1734::INFO::2014-11-17 15:50:31,345::logUtils= ::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType= =3D4, spUUID=3Du'00000000-0000-0000-0000-000000000000', conList=3D[{u'conne= ction': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': '******', u'id': u'00000000-0000-0000-000= 0-000000000000', u'port': u''}], options=3DNone)<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,346::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,346::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,347::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,347::__init__::375::IOProcess::(_processLogs) (2321) Got request for= method 'access'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,347::__init__::375::IOProcess::(_processLogs) (2321) Queuing respons= e<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,357::hsm::23= 89::Storage.HSM::(__prefetchDomains) local _path: /vmstore/isos<o:p></o:p><= /p> Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::23= 96::Storage.HSM::(__prefetchDomains) Found SD uuids: ()<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,358::hsm::24= 52::Storage.HSM::(connectStorageServer) knownSDs: {158ef830-da69-48b4-95b0-= 3615d6fb5b00: storage.localFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c32= 5a070: storage.nfsSD.findDomain}<o:p></o:p> Thread-1734::INFO::2014-11-17 15:50:31,358::logUtils= ::47::dispatcher::(wrapper) Run and protect: connectStorageServer, Return r= esponse: {'statuslist': [{'status': 0, 'id': u'00000000-0000-0000-0000-0000= 00000000'}]}<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::1= 191::Storage.TaskManager.Task::(prepare) Task=3D`4088bef4-489c-41a4-bcd4-d5= f1906378b9`::finished: {'statuslist': [{'status': 0, 'id': u'00000000-0000-= 0000-0000-000000000000'}]}<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,359::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`4088bef4-489c-41a4-bcd= 4-d5f1906378b9`::moving from state preparing -> state finished<o:p></o:p= > Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourc= eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll= requests {} resources {}<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,359::resourc= eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r= equests {}<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,360::task::9= 93::Storage.TaskManager.Task::(_decref) Task=3D`4088bef4-489c-41a4-bcd4-d5f= 1906378b9`::ref 0 aborting False<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,360::__init_= _::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'StoragePool.connectS= torageServer' in bridge with [{'status': 0, 'id': u'00000000-0000-0000-0000= -000000000000'}]<o:p></o:p> Thread-1734::DEBUG::2014-11-17 15:50:31,361::stompRe= actor::163::yajsonrpc.StompServer::(send) Sending response<o:p></o:p> JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,4= 25::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message = <StompFrame command=3D'SEND'><o:p></o:p> JsonRpcServer::DEBUG::2014-11-17 15:50:31,428::__ini= t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><= /o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,428::__init_= _::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StoragePool.connect= StorageServer' in bridge with {u'connectionParams': [{u'id': u'deb5a580-699= 4-4db9-9899-cd05c39c2efa', u'connection': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': u= '', u'port': u''}], u'storagepoolID': u'00000000-0000-0000-0000-00000000000= 0', u'domainType': 4}<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,431::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48= d-4463f53f0481`::moving from state init -> state preparing<o:p></o:p></p= > Thread-1735::INFO::2014-11-17 15:50:31,431::logUtils= ::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType= =3D4, spUUID=3Du'00000000-0000-0000-0000-000000000000', conList=3D[{u'conne= ction': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': '******', u'id': u'deb5a580-6994-4db9-989= 9-cd05c39c2efa', u'port': u''}], options=3DNone)<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,433::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,433::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,433::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,433::__init__::375::IOProcess::(_processLogs) (2322) Got request for= method 'access'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:31,434::__init__::375::IOProcess::(_processLogs) (2322) Queuing respons= e<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::23= 89::Storage.HSM::(__prefetchDomains) local _path: /vmstore/isos<o:p></o:p><= /p> Thread-1735::DEBUG::2014-11-17 15:50:31,442::hsm::23= 96::Storage.HSM::(__prefetchDomains) Found SD uuids: ()<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,443::hsm::24= 52::Storage.HSM::(connectStorageServer) knownSDs: {158ef830-da69-48b4-95b0-= 3615d6fb5b00: storage.localFsSD.findDomain, ac821c1f-b7ca-4534-a10f-9b98c32= 5a070: storage.nfsSD.findDomain}<o:p></o:p> Thread-1735::INFO::2014-11-17 15:50:31,443::logUtils= ::47::dispatcher::(wrapper) Run and protect: connectStorageServer, Return r= esponse: {'statuslist': [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-cd05= c39c2efa'}]}<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,443::task::1= 191::Storage.TaskManager.Task::(prepare) Task=3D`1cdc582f-bca2-49c1-a48d-44= 63f53f0481`::finished: {'statuslist': [{'status': 0, 'id': u'deb5a580-6994-= 4db9-9899-cd05c39c2efa'}]}<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`1cdc582f-bca2-49c1-a48= d-4463f53f0481`::moving from state preparing -> state finished<o:p></o:p= > Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourc= eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll= requests {} resources {}<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,444::resourc= eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r= equests {}<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,444::task::9= 93::Storage.TaskManager.Task::(_decref) Task=3D`1cdc582f-bca2-49c1-a48d-446= 3f53f0481`::ref 0 aborting False<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,445::__init_= _::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'StoragePool.connectS= torageServer' in bridge with [{'status': 0, 'id': u'deb5a580-6994-4db9-9899= -cd05c39c2efa'}]<o:p></o:p> Thread-1735::DEBUG::2014-11-17 15:50:31,445::stompRe= actor::163::yajsonrpc.StompServer::(send) Sending response<o:p></o:p> JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:31,4= 51::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message = <StompFrame command=3D'SEND'><o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,454::__init_= _::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StorageDomain.creat= e' in bridge with {u'name': u'test', u'domainType': 4, u'domainClass': 1, u= 'typeArgs': u'/vmstore/isos', u'version': u'3', u'storagedomainID': u'c46aa2c4-c405-45eb-b7fd-71f627d1c546'}<o:p></o= :p> JsonRpcServer::DEBUG::2014-11-17 15:50:31,454::__ini= t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><= /o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,459::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b63= 9-9017ea3ecdd0`::moving from state init -> state preparing<o:p></o:p></p= > Thread-1736::INFO::2014-11-17 15:50:31,459::logUtils= ::44::dispatcher::(wrapper) Run and protect: createStorageDomain(storageTyp= e=3D4, sdUUID=3Du'c46aa2c4-c405-45eb-b7fd-71f627d1c546', domainName=3Du'tes= t', typeSpecificArg=3Du'/vmstore/isos', domClass=3D1, domVersion=3Du'3', options=3DNone)<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,459::misc::7= 41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto= rage.sdc.refreshStorage)<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::7= 43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>= Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::7= 41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto= rage.iscsi.rescan)<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,460::misc::7= 43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>= Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsi::= 403::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 = seconds<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,461::iscsiad= m::92::Storage.Misc.excCmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m se= ssion -R (cwd None)<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,517::misc::7= 51::Storage.SamplingMethod::(__call__) Returning last result<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,521::multipa= th::110::Storage.Misc.excCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cw= d None)<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,705::multipa= th::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> =3D ''; <rc&= gt; =3D 0<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,706::lvm::48= 9::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op= eration' got the operation mutex<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::49= 1::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op= eration' released the operation mutex<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,707::lvm::50= 0::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op= eration' got the operation mutex<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::50= 2::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op= eration' released the operation mutex<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::52= 0::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op= eration' got the operation mutex<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,708::lvm::52= 2::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op= eration' released the operation mutex<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,709::misc::7= 51::Storage.SamplingMethod::(__call__) Returning last result<o:p></o:p> Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::13= 7::Storage.StorageDomainCache::(_findDomain) looking for unfetched domain c= 46aa2c4-c405-45eb-b7fd-71f627d1c546<o:p></o:p> Thread-1736::ERROR::2014-11-17 15:50:31,709::sdc::15= 4::Storage.StorageDomainCache::(_findUnfetchedDomain) looking for domain c4= 6aa2c4-c405-45eb-b7fd-71f627d1c546<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,710::lvm::36= 5::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' go= t the operation mutex<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:31,712::lvm::28= 8::Storage.Misc.excCmd::(cmd) /usr/bin/sudo -n /sbin/lvm vgs --config ' dev= ices { preferred_names =3D ["^/dev/mapper/"] ignore_suspended_dev= ices=3D1 write_cache_state=3D0 disable_after_error_count=3D3 obtain_device_list_from_udev=3D0 filter =3D [ '\''r|.*|'\'' ] }  glob= al {  locking_type=3D1  prioritise_write_locks=3D1  wait_for= _locks=3D1  use_lvmetad=3D0 }  backup {  retain_min =3D 50&n= bsp; retain_days =3D 0 } ' --noheadings --units b --nosuffix --separator '|= ' --ignoreskippedcluster -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_md= a_size,vg_mda_free,lv_count,pv_count,pv_name c46aa2c4-c405-45eb-b7fd-71f627= d1c546 (cwd None)<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,049::lvm::28= 8::Storage.Misc.excCmd::(cmd) FAILED: <err> =3D '  Volume group = "c46aa2c4-c405-45eb-b7fd-71f627d1c546" not found\n  Skipping= volume group c46aa2c4-c405-45eb-b7fd-71f627d1c546\n'; <rc> =3D 5<o:p></o:p> Thread-1736::WARNING::2014-11-17 15:50:32,052::lvm::= 370::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] ['  Volume group &= quot;c46aa2c4-c405-45eb-b7fd-71f627d1c546" not found', '  Skippin= g volume group c46aa2c4-c405-45eb-b7fd-71f627d1c546']<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,052::lvm::40= 7::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' re= leased the operation mutex<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,060::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,061::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,062::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,062::__init__::375::IOProcess::(_processLogs) (2323) Got request for= method 'glob'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,062::__init__::375::IOProcess::(_processLogs) (2323) Queuing respons= e<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,064::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,065::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,065::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,065::__init__::375::IOProcess::(_processLogs) (2324) Got request for= method 'glob'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,066::__init__::375::IOProcess::(_processLogs) (2324) Queuing respons= e<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,067::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,068::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,069::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,069::__init__::375::IOProcess::(_processLogs) (2325) Got request for= method 'glob'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,069::__init__::375::IOProcess::(_processLogs) (2325) Queuing respons= e<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,079::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,082::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,082::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,083::__init__::375::IOProcess::(_processLogs) (2326) Got request for= method 'glob'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,083::__init__::375::IOProcess::(_processLogs) (2326) Queuing respons= e<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,085::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,086::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,086::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,087::__init__::375::IOProcess::(_processLogs) (2327) Got request for= method 'glob'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,087::__init__::375::IOProcess::(_processLogs) (2327) Queuing respons= e<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,089::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,089::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,090::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,090::__init__::375::IOProcess::(_processLogs) (2328) Got request for= method 'glob'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,090::__init__::375::IOProcess::(_processLogs) (2328) Queuing respons= e<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,092::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,093::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,093::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,093::__init__::375::IOProcess::(_processLogs) (2329) Got request for= method 'glob'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,093::__init__::375::IOProcess::(_processLogs) (2329) Queuing respons= e<o:p></o:p> Thread-1736::ERROR::2014-11-17 15:50:32,095::sdc::14= 3::Storage.StorageDomainCache::(_findDomain) domain c46aa2c4-c405-45eb-b7fd= -71f627d1c546 not found<o:p></o:p> Traceback (most recent call last):<o:p></o:p>   File "/usr/share/vdsm/storage/sdc.py&quo= t;, line 141, in _findDomain<o:p></o:p>     dom =3D findMethod(sdUUID)<o:p></= o:p>   File "/usr/share/vdsm/storage/sdc.py&quo= t;, line 171, in _findUnfetchedDomain<o:p></o:p>     raise se.StorageDomainDoesNotExis= t(sdUUID)<o:p></o:p> StorageDomainDoesNotExist: Storage domain does not e= xist: (u'c46aa2c4-c405-45eb-b7fd-71f627d1c546',)<o:p></o:p> Thread-1736::INFO::2014-11-17 15:50:32,096::localFsS= D::73::Storage.StorageDomain::(create) sdUUID=3Dc46aa2c4-c405-45eb-b7fd-71f= 627d1c546 domainName=3Dtest remotePath=3D/vmstore/isos domClass=3D1<o:p></o= :p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,097::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,098::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,098::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,098::__init__::375::IOProcess::(_processLogs) (2330) Got request for= method 'access'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,099::__init__::375::IOProcess::(_processLogs) (2330) Queuing respons= e<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,107::__init__::375::IOProcess::(_processLogs) Receiving request...<o= :p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,107::__init__::375::IOProcess::(_processLogs) Queuing request in the= thread pool...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,108::__init__::375::IOProcess::(_processLogs) Extracting request inf= ormation...<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,108::__init__::375::IOProcess::(_processLogs) (2331) Got request for= method 'touch'<o:p></o:p> ioprocess communication (5073)::DEBUG::2014-11-17 15= :50:32,108::__init__::375::IOProcess::(_processLogs) (2331) Queuing respons= e<o:p></o:p> Thread-1736::ERROR::2014-11-17 15:50:32,109::fileSD:= :92::Storage.fileSD::(validateFileSystemFeatures) Underlying file system do= esn't supportdirect IO<o:p></o:p> Thread-1736::ERROR::2014-11-17 15:50:32,109::task::8= 66::Storage.TaskManager.Task::(_setError) Task=3D`54f143dd-2229-4bc1-b639-9= 017ea3ecdd0`::Unexpected error<o:p></o:p> Traceback (most recent call last):<o:p></o:p>   File "/usr/share/vdsm/storage/task.py&qu= ot;, line 873, in _run<o:p></o:p>     return fn(*args, **kargs)<o:p></o= :p>   File "/usr/share/vdsm/logUtils.py",= line 45, in wrapper<o:p></o:p>     res =3D f(*args, **kwargs)<o:p></= o:p>   File "/usr/share/vdsm/storage/hsm.py&quo= t;, line 2683, in createStorageDomain<o:p></o:p>     domVersion)<o:p></o:p>   File "/usr/share/vdsm/storage/localFsSD.= py", line 84, in create<o:p></o:p>     cls._preCreateValidation(sdUUID, = mntPoint, remotePath, version)<o:p></o:p>   File "/usr/share/vdsm/storage/localFsSD.= py", line 51, in _preCreateValidation<o:p></o:p>     fileSD.validateFileSystemFeatures= (sdUUID, domPath)<o:p></o:p>   File "/usr/share/vdsm/storage/fileSD.py&= quot;, line 94, in validateFileSystemFeatures<o:p></o:p>     raise se.StorageDomainTargetUnsup= ported()<o:p></o:p> StorageDomainTargetUnsupported: Storage Domain targe= t is unsupported: ()<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::8= 85::Storage.TaskManager.Task::(_run) Task=3D`54f143dd-2229-4bc1-b639-9017ea= 3ecdd0`::Task._run: 54f143dd-2229-4bc1-b639-9017ea3ecdd0 (4, u'c46aa2c4-c40= 5-45eb-b7fd-71f627d1c546', u'test', u'/vmstore/isos', 1, u'3') {} failed - stopping task<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,110::task::1= 217::Storage.TaskManager.Task::(stop) Task=3D`54f143dd-2229-4bc1-b639-9017e= a3ecdd0`::stopping in state preparing (force False)<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::9= 93::Storage.TaskManager.Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-901= 7ea3ecdd0`::ref 1 aborting True<o:p></o:p> Thread-1736::INFO::2014-11-17 15:50:32,111::task::11= 71::Storage.TaskManager.Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-901= 7ea3ecdd0`::aborting: Task is aborted: 'Storage Domain target is unsupporte= d' - code 399<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::1= 176::Storage.TaskManager.Task::(prepare) Task=3D`54f143dd-2229-4bc1-b639-90= 17ea3ecdd0`::Prepare: aborted: Storage Domain target is unsupported<o:p></o= :p> Thread-1736::DEBUG::2014-11-17 15:50:32,111::task::9= 93::Storage.TaskManager.Task::(_decref) Task=3D`54f143dd-2229-4bc1-b639-901= 7ea3ecdd0`::ref 0 aborting True<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::9= 28::Storage.TaskManager.Task::(_doAbort) Task=3D`54f143dd-2229-4bc1-b639-90= 17ea3ecdd0`::Task._doAbort: force False<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,112::resourc= eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r= equests {}<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,112::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b63= 9-9017ea3ecdd0`::moving from state preparing -> state aborting<o:p></o:p= > Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::5= 50::Storage.TaskManager.Task::(__state_aborting) Task=3D`54f143dd-2229-4bc1= -b639-9017ea3ecdd0`::_aborting: recover policy none<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,113::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`54f143dd-2229-4bc1-b63= 9-9017ea3ecdd0`::moving from state aborting -> state failed<o:p></o:p></= p> Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourc= eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll= requests {} resources {}<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,113::resourc= eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r= equests {}<o:p></o:p> Thread-1736::ERROR::2014-11-17 15:50:32,114::dispatc= her::76::Storage.Dispatcher::(wrapper) {'status': {'message': 'Storage Doma= in target is unsupported: ()', 'code': 399}}<o:p></o:p> Thread-1736::DEBUG::2014-11-17 15:50:32,114::stompRe= actor::163::yajsonrpc.StompServer::(send) Sending response<o:p></o:p> JsonRpc (StompReactor)::DEBUG::2014-11-17 15:50:32,2= 12::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message = <StompFrame command=3D'SEND'><o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,215::__init_= _::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'StoragePool.disconn= ectStorageServer' in bridge with {u'connectionParams': [{u'id': u'deb5a580-= 6994-4db9-9899-cd05c39c2efa', u'connection': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': u= '', u'port': u''}], u'storagepoolID': u'00000000-0000-0000-0000-00000000000= 0', u'domainType': 4}<o:p></o:p> JsonRpcServer::DEBUG::2014-11-17 15:50:32,215::__ini= t__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request<o:p><= /o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,220::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb4= 0-00816fe2c131`::moving from state init -> state preparing<o:p></o:p></p= > Thread-1744::INFO::2014-11-17 15:50:32,221::logUtils= ::44::dispatcher::(wrapper) Run and protect: disconnectStorageServer(domTyp= e=3D4, spUUID=3Du'00000000-0000-0000-0000-000000000000', conList=3D[{u'conn= ection': u'/vmstore/isos', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'password': '******', u'id': u'deb5a580-6994= -4db9-9899-cd05c39c2efa', u'port': u''}], options=3DNone)<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,221::misc::7= 41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto= rage.sdc.refreshStorage)<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::7= 43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>= Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::7= 41::Storage.SamplingMethod::(__call__) Trying to enter sampling method (sto= rage.iscsi.rescan)<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,222::misc::7= 43::Storage.SamplingMethod::(__call__) Got in to sampling method<o:p></o:p>= Thread-1744::DEBUG::2014-11-17 15:50:32,222::iscsi::= 403::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 = seconds<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,223::iscsiad= m::92::Storage.Misc.excCmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m se= ssion -R (cwd None)<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,276::misc::7= 51::Storage.SamplingMethod::(__call__) Returning last result<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,280::multipa= th::110::Storage.Misc.excCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cw= d None)<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,409::multipa= th::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> =3D ''; <rc&= gt; =3D 0<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::48= 9::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op= eration' got the operation mutex<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,410::lvm::49= 1::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate op= eration' released the operation mutex<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::50= 0::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op= eration' got the operation mutex<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,411::lvm::50= 2::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate op= eration' released the operation mutex<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::52= 0::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op= eration' got the operation mutex<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,412::lvm::52= 2::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate op= eration' released the operation mutex<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,412::misc::7= 51::Storage.SamplingMethod::(__call__) Returning last result<o:p></o:p> Thread-1744::INFO::2014-11-17 15:50:32,413::logUtils= ::47::dispatcher::(wrapper) Run and protect: disconnectStorageServer, Retur= n response: {'statuslist': [{'status': 0, 'id': u'deb5a580-6994-4db9-9899-c= d05c39c2efa'}]}<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,413::task::1= 191::Storage.TaskManager.Task::(prepare) Task=3D`11fde6d7-ad39-4b40-bb40-00= 816fe2c131`::finished: {'statuslist': [{'status': 0, 'id': u'deb5a580-6994-= 4db9-9899-cd05c39c2efa'}]}<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::5= 95::Storage.TaskManager.Task::(_updateState) Task=3D`11fde6d7-ad39-4b40-bb4= 0-00816fe2c131`::moving from state preparing -> state finished<o:p></o:p= > Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourc= eManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll= requests {} resources {}<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,414::resourc= eManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll r= equests {}<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,414::task::9= 93::Storage.TaskManager.Task::(_decref) Task=3D`11fde6d7-ad39-4b40-bb40-008= 16fe2c131`::ref 0 aborting False<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,415::__init_= _::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'StoragePool.disconne= ctStorageServer' in bridge with [{'status': 0, 'id': u'deb5a580-6994-4db9-9= 899-cd05c39c2efa'}]<o:p></o:p> Thread-1744::DEBUG::2014-11-17 15:50:32,416::stompRe= actor::163::yajsonrpc.StompServer::(send) Sending response<o:p></o:p> <o:p> </o:p> </div> </body> </html> --_000_EE5DB373DE433D4A87A60EE0284C92F926421EFDRISXMBX03adsuhn_--

1 0

Re: [ovirt-users] ovirt-websocket-proxy uses the wrong IP
by mots 17 Nov '14

17 Nov '14

------=_Part_36_779334652.1416253714037 Content-Type: multipart/alternative; boundary="=_B2Bqz28RlRkiQt0tI2lBQMQUJD-DsqIJ4LiGs35u4K91tsvv" This is a multi-part message in MIME format. Your mail reader does not understand MIME message format. --=_B2Bqz28RlRkiQt0tI2lBQMQUJD-DsqIJ4LiGs35u4K91tsvv Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable Thank you for your reply.=0D=0A=0D=0AThe engine resolves the node's IP co= rrectly. A few details about the setup: It's a two node cluster with shar= ed, internal storage using DRBD. The storage is managed by pacemaker, so = that the node which currently serves as iscsi target gets assigned the ad= ditional IP.=20=0D=0AThe Engine/websocket-proxy then always connects to t= he storage IP when it attempts to connect to the node which currently fun= ctions as iscsi target. This only happens in oVirt 3.5, and I was able to= "fix" it by going back to 3.4.=0D=0A=0D=0ARegards,=0D=0A=0D=0Amots=0D=0A= =20=0D=0A-----Urspr=C3=BCngliche Nachricht-----=0D=0A> Von:Simone Tirabos= chi <stirabos(a)redhat.com <mailto:stirabos@redhat.com> >=0D=0A> Gesendet: = Mon 17 November 2014 18:11=0D=0A> An: Patrick Lottenbach <pl(a)a-bot.ch <ma= ilto:pl@a-bot.ch> >=0D=0A> CC: users(a)ovirt.org <mailto:users@ovirt.org>=20= =0D=0A> Betreff: Re: [ovirt-users] ovirt-websocket-proxy uses the wrong I= P=0D=0A>=20=0D=0A>=20=0D=0A>=20=0D=0A>=20=0D=0A>=20=0D=0A> ----- Original= Message -----=0D=0A> > From: "mots" <mots(a)nepu.moe <mailto:mots@nepu.moe= > >=0D=0A> > To: users(a)ovirt.org <mailto:users@ovirt.org>=20=0D=0A> > Sen= t: Saturday, November 15, 2014 10:24:29 PM=0D=0A> > Subject: [ovirt-users= ] ovirt-websocket-proxy uses the wrong IP=0D=0A> >=20=0D=0A> > ovirt-webs= ocket-proxy uses the wrong IP Hello,=0D=0A> >=20=0D=0A> > One of my nodes= has two IP addresses, 10.42.0.101 and 10.42.0.103. Ovirt is=0D=0A> > con= figured to use 10.42.0.101, yet the ovirt-websocket-proxy service tries=0D= =0A> > to connect to 10.42.0.103, where no VNC server is listening.=0D=0A= > >=20=0D=0A> > Is there any way I can configure it to use the correct ad= dress=3F=0D=0A> >=20=0D=0A> > [root@engine =CB=9C]#=0D=0A> > /usr/share/o= virt-engine/services/ovirt-websocket-proxy/ovirt-websocket-proxy.py=0D=0A= > > --debug start=0D=0A> > ovirt-websocket-proxy[1838] DEBUG _daemon:403 = daemon entry pid=3D1838=0D=0A> > ovirt-websocket-proxy[1838] DEBUG _daemo= n:404 background=3DFalse=0D=0A> > ovirt-websocket-proxy[1838] DEBUG loadF= ile:70 loading config=0D=0A> > '/usr/share/ovirt-engine/services/ovirt-we= bsocket-proxy/ovirt-websocket-proxy.conf'=0D=0A> > ovirt-websocket-proxy[= 1838] DEBUG loadFile:70 loading config=0D=0A> > '/etc/ovirt-engine/ovirt-= websocket-proxy.conf.d/10-setup.conf'=0D=0A> > ovirt-websocket-proxy[1838= ] DEBUG _daemon:440 I am a daemon 1838=0D=0A> > ovirt-websocket-proxy[183= 8] DEBUG _setLimits:377 Setting rlimits=0D=0A> > WebSocket server setting= s:=0D=0A> > - Listen on *:6100=0D=0A>=20=0D=0A> The WebSocketProxy is lis= tening on all the available IPs, so no problems on that side.=0D=0A>=20=0D= =0A> > - Flash security policy server=0D=0A> > - SSL/TLS support=0D=0A> >= - Deny non-SSL/TLS connections=0D=0A> > - proxying from *:6100 to target= s in /dummy=0D=0A> >=20=0D=0A> > 1: 10.42.0.1: new handler Process=0D=0A>= > 1: 10.42.0.1: SSL/TLS (wss://) WebSocket connection=0D=0A> > 1: 10.42.= 0.1: Version hybi-13, base64: 'True'=0D=0A> > 1: 10.42.0.1: Path:=0D=0A> = > '/eyJ2YWxpZFRvIjoiMjAxNDExMTUyMTI1MDgiLCJkYXRhIjoiJTdCJTIyaG9zdCUyMjolM= jIxMC40Mi4wLjEwMyUyMiwlMjJwb3J0JTIyOiUyMjU5MDElMjIsJTIyc3NsX3RhcmdldCUyMj= pmYWxzZSU3RCIsInZhbGlkRnJvbSI6IjIwMTQxMTE1MjEyMzA4Iiwic2lnbmVkRmllbGRzIjo= idmFsaWRUbyxkYXRhLHZhbGlkRnJvbSxzYWx0Iiwic2lnbmF0dXJlIjoiajRQUmxwYjBvT0dO= ZUNPaHZKK01wUTVrVGRMYVA0Sm8zRDIzTGlXRlZYRm4xNU9KN0NZVmw5OTBpNTBUNzlVZkpqU= zRlRmZ1SHJhT1c4TlFNbXIwanZXSUpTWCtnL3RYSnc4MWRFS2wrcFVPVHo3MWlmY2dTbXdITm= ptOUkwTTl6Q0NNR2dvbE1BRzZwMndFbDFySDdSZkhMWnIvOGo4bnpnVGZ0NlhaOTdBcHgyejh= kMlo0UjRmdklXemtXMjErMDdsNWw4dXpNVytEM1FmaWdDS1Q3V3VKdlFHNi9SSC9zZWRBWHJX= cnFUNXYzTHNuNVl0MWtYb2lGV3ZYOHNUdE5PdGdvQWk3eGN5WUhGaEM1ei9SMjZXNEkrSlJNc= DZlVDNxbWVlZnM0eWRSN0NpZWwzZWZvZDB5TU9meGJwMG9EMGlscXVWUWVjK1JxeGxqd21ZVG= 5BPT0iLCJzYWx0IjoiWGhVQ1dYL2hQU1U9In0=3D'=0D=0A> > 1: connecting to: 10.4= 2.0.103:5901=0D=0A>=20=0D=0A> The engine is instructing the WebSocket pro= xy to connect to the host on the wrong IP address.=0D=0A> Are you using a= n all-in-one setup where the engine, KVM and the websocketproxy are on a = single machine=3F=0D=0A> Can you please check how the engine machine reso= lve the host name=3F=0D=0A>=20=0D=0A>=20=0D=0A> > 1: handler exception: [= Errno 111] Connection refused=0D=0A> > 1: Traceback (most recent call las= t):=0D=0A> > File "/usr/lib/python2.6/site-packages/websockify/websocket.= py", line 711, in=0D=0A> > top_new_client=0D=0A> > self.new_client()=0D=0A= > > File "/usr/lib/python2.6/site-packages/websockify/websocketproxy.py",= line=0D=0A> > 183, in new_client=0D=0A> > connect=3DTrue, use_ssl=3Dself= =2Essl_target, unix_socket=3Dself.unix_target)=0D=0A> > File "/usr/lib/py= thon2.6/site-packages/websockify/websocket.py", line 188, in=0D=0A> > soc= ket=0D=0A> > sock.connect(addrs[0][4])=0D=0A> > File "<string>", line 1, = in connect=0D=0A> > error: [Errno 111] Connection refused=0D=0A> >=20=0D=0A= > >=20=0D=0A> > Regards,=0D=0A> >=20=0D=0A> > mots=0D=0A> >=20=0D=0A> > _= ______________________________________________=0D=0A> > Users mailing lis= t=0D=0A> > Users(a)ovirt.org <mailto:Users@ovirt.org>=20=0D=0A> > http://li= sts.ovirt.org/mailman/listinfo/users <http://lists.ovirt.org/mailman/list= info/users>=20=0D=0A> >=20=0D=0A>=20=0D=0A=0D=0A --=_B2Bqz28RlRkiQt0tI2lBQMQUJD-DsqIJ4LiGs35u4K91tsvv Content-Type: text/html; charset=utf-8 Content-Transfer-Encoding: quoted-printable =0D=0A<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN" "htt= p://www.w3.org/TR/html4/loose.dtd"><html>=0D=0A<head>=0D=0A <meta name=3D= "Generator" content=3D"Zarafa WebApp v7.1.10-44973">=0D=0A <meta http-eq= uiv=3D"Content-Type" content=3D"text/html; charset=3Dutf-8">=0D=0A <titl= e>AW: [ovirt-users] ovirt-websocket-proxy uses the wrong IP</title>=0D=0A= </head>=0D=0A<body>=0D=0AThank you for y= our reply. The engine resolves the node's IP correctly. A few deta= ils about the setup: It's a two node cluster with shared, internal storag= e using DRBD. The storage is managed by pacemaker, so that the node which= currently serves as iscsi target gets assigned the additional IP. Th= e Engine/websocket-proxy then always connects to the storage IP when it a= ttempts to connect to the node which currently functions as iscsi target.= This only happens in oVirt 3.5, and I was able to "fix" it by going back= to 3.4. <pre style=3D"white-space: -moz-pre-wrap; white-space:= -pre-wrap; white-space: -o-pre-wrap; white-space: pre-wrap; word-wrap: b= reak-word;" wrap=3D"">Regards, mots -----Urspr=C3=BCnglich= e Nachricht----- > Von:Simone Tiraboschi <<a href=3D"mailto:stir= abos(a)redhat.com">stirabos(a)redhat.com</a>> > Gesendet: Mon 17 Nov= ember 2014 18:11 > An: Patrick Lottenbach <<a href=3D"mailto:pl@= a-bot.ch">pl(a)a-bot.ch</a>> > CC: <a href=3D"mailto:users@ovirt.o= rg">users(a)ovirt.org</a> > Betreff: Re: [ovirt-users] ovirt-websocke= t-proxy uses the wrong IP > > > > > <br= >> ----- Original Message ----- > > From: "mots" <<a href=3D= "mailto:mots@nepu.moe">mots(a)nepu.moe</a>> > > To: <a href=3D"= mailto:users@ovirt.org">users(a)ovirt.org</a> > > Sent: Saturday, = November 15, 2014 10:24:29 PM > > Subject: [ovirt-users] ovirt-w= ebsocket-proxy uses the wrong IP > > > > ovirt-websock= et-proxy uses the wrong IP Hello, > > > > One of my no= des has two IP addresses, 10.42.0.101 and 10.42.0.103. Ovirt is > &= gt; configured to use 10.42.0.101, yet the ovirt-websocket-proxy service = tries > > to connect to 10.42.0.103, where no VNC server is list= ening. > > > > Is there any way I can configure it to = use the correct address=3F > > > > [root@engine =CB=9C= ]# > > /usr/share/ovirt-engine/services/ovirt-websocket-proxy/ov= irt-websocket-proxy.py > > --debug start > > ovirt-webs= ocket-proxy[1838] DEBUG _daemon:403 daemon entry pid=3D1838 > > = ovirt-websocket-proxy[1838] DEBUG _daemon:404 background=3DFalse > = > ovirt-websocket-proxy[1838] DEBUG loadFile:70 loading config >= > '/usr/share/ovirt-engine/services/ovirt-websocket-proxy/ovirt-webso= cket-proxy.conf' > > ovirt-websocket-proxy[1838] DEBUG loadFile:= 70 loading config > > '/etc/ovirt-engine/ovirt-websocket-proxy.c= onf.d/10-setup.conf' > > ovirt-websocket-proxy[1838] DEBUG _daem= on:440 I am a daemon 1838 > > ovirt-websocket-proxy[1838] DEBUG = _setLimits:377 Setting rlimits > > WebSocket server settings:<br= >> > - Listen on *:6100 > > The WebSocketProxy is list= ening on all the available IPs, so no problems on that side. > = > > - Flash security policy server > > - SSL/TLS support<b= r>> > - Deny non-SSL/TLS connections > > - proxying from *= :6100 to targets in /dummy > > > > 1: 10.42.0.1: new h= andler Process > > 1: 10.42.0.1: SSL/TLS (wss://) WebSocket conn= ection > > 1: 10.42.0.1: Version hybi-13, base64: 'True' >= > 1: 10.42.0.1: Path: > > '/eyJ2YWxpZFRvIjoiMjAxNDExMTUyMTI1= MDgiLCJkYXRhIjoiJTdCJTIyaG9zdCUyMjolMjIxMC40Mi4wLjEwMyUyMiwlMjJwb3J0JTIyO= iUyMjU5MDElMjIsJTIyc3NsX3RhcmdldCUyMjpmYWxzZSU3RCIsInZhbGlkRnJvbSI6IjIwMT= QxMTE1MjEyMzA4Iiwic2lnbmVkRmllbGRzIjoidmFsaWRUbyxkYXRhLHZhbGlkRnJvbSxzYWx= 0Iiwic2lnbmF0dXJlIjoiajRQUmxwYjBvT0dOZUNPaHZKK01wUTVrVGRMYVA0Sm8zRDIzTGlX= RlZYRm4xNU9KN0NZVmw5OTBpNTBUNzlVZkpqUzRlRmZ1SHJhT1c4TlFNbXIwanZXSUpTWCtnL= 3RYSnc4MWRFS2wrcFVPVHo3MWlmY2dTbXdITmptOUkwTTl6Q0NNR2dvbE1BRzZwMndFbDFySD= dSZkhMWnIvOGo4bnpnVGZ0NlhaOTdBcHgyejhkMlo0UjRmdklXemtXMjErMDdsNWw4dXpNVyt= EM1FmaWdDS1Q3V3VKdlFHNi9SSC9zZWRBWHJXcnFUNXYzTHNuNVl0MWtYb2lGV3ZYOHNUdE5P= dGdvQWk3eGN5WUhGaEM1ei9SMjZXNEkrSlJNcDZlVDNxbWVlZnM0eWRSN0NpZWwzZWZvZDB5T= U9meGJwMG9EMGlscXVWUWVjK1JxeGxqd21ZVG5BPT0iLCJzYWx0IjoiWGhVQ1dYL2hQU1U9In= 0=3D' > > 1: connecting to: 10.42.0.103:5901 > > Th= e engine is instructing the WebSocket proxy to connect to the host on the= wrong IP address. > Are you using an all-in-one setup where the en= gine, KVM and the websocketproxy are on a single machine=3F > Can y= ou please check how the engine machine resolve the host name=3F > <= br>> > > 1: handler exception: [Errno 111] Connection refuse= d > > 1: Traceback (most recent call last): > > File "/= usr/lib/python2.6/site-packages/websockify/websocket.py", line 711, in<br= >> > top_new_client > > self.new_client() > > Fil= e "/usr/lib/python2.6/site-packages/websockify/websocketproxy.py", line<b= r>> > 183, in new_client > > connect=3DTrue, use_ssl=3Dsel= f.ssl_target, unix_socket=3Dself.unix_target) > > File "/usr/lib= /python2.6/site-packages/websockify/websocket.py", line 188, in > &= gt; socket > > sock.connect(addrs[0][4]) > > File "<= string>", line 1, in connect > > error: [Errno 111] Connectio= n refused > > > > > > Regards, > > = > > mots > > > > ___________________________= ____________________ > > Users mailing list > > <a href= =3D"mailto:Users@ovirt.org">Users(a)ovirt.org</a> > > <a href=3D"h= ttp://lists.ovirt.org/mailman/listinfo/users" target=3D"_blank">http://li= sts.ovirt.org/mailman/listinfo/users</a> > > > </pre>=0D=0A= </body>=0D=0A</html> --=_B2Bqz28RlRkiQt0tI2lBQMQUJD-DsqIJ4LiGs35u4K91tsvv-- ------=_Part_36_779334652.1416253714037 Content-Type: application/pgp-signature; name=signature.asc Content-Transfer-Encoding: 7bit Content-Description: OpenPGP digital signature Content-Disposition: attachment; filename="signature.asc" -----BEGIN PGP SIGNATURE----- Version: CIPHERMAIL (2.8.6-4) iQEcBAEBCAAGBQJUalESAAoJELfzdzVzTtoKeF0H/1YQt1E5Xa6ddb/QDmr+a0xm l0YZi8SP2bDvKsktd5u1skXpXp5BMhYYQL77P9/dvg/nUudz5Pv+MNLtMc/VeWoS X46jIJBsL8l+eg5hTa/LocJZ2mBRkZnbwSYUuNKFNrHc7hDhWXwNBZsObrXsBQIK 4wllZgmAhm+yUxX2S2oWDRF76XkuSnQdGLjVHySfrD4/ko39+hQyOZWjKNFUlNB2 WXwfI6xurJDccVWW9wLndt6JO8s2uKf2NcDLSDaY1abi2gFEqx1iIFxY3v4YV+LT tIc4HwK/BqE+HusOVAxLL20tvV4ki+2/2g4jsYmXjpPYyWByLspV1IcHKnbtbpw= =u/// -----END PGP SIGNATURE----- ------=_Part_36_779334652.1416253714037--

1 0

ovirt-websocket-proxy uses the wrong IP
by mots 17 Nov '14

17 Nov '14

------=_Part_27_1351307630.1416086778054 Content-Type: multipart/alternative; boundary="=_diYpkdcCc+XflEeiQy00J5SerIjuuCS5jgM5BJ73AfiuYSQ8" This is a multi-part message in MIME format. Your mail reader does not understand MIME message format. --=_diYpkdcCc+XflEeiQy00J5SerIjuuCS5jgM5BJ73AfiuYSQ8 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable Hello,=0D=0A=0D=0AOne of my nodes has two IP addresses, 10.42.0.101 and 1= 0.42.0.103. Ovirt is configured to use 10.42.0.101, yet the ovirt-websock= et-proxy service tries to connect to 10.42.0.103, where no VNC server is = listening.=0D=0A=0D=0AIs there any way I can configure it to use the corr= ect address=3F=0D=0A=0D=0A[root@engine ~]# /usr/share/ovirt-engine/servic= es/ovirt-websocket-proxy/ovirt-websocket-proxy.py --debug start=0D=0Aovir= t-websocket-proxy[1838] DEBUG _daemon:403 daemon entry pid=3D1838=0D=0Aov= irt-websocket-proxy[1838] DEBUG _daemon:404 background=3DFalse=0D=0Aovirt= -websocket-proxy[1838] DEBUG loadFile:70 loading config '/usr/share/ovirt= -engine/services/ovirt-websocket-proxy/ovirt-websocket-proxy.conf'=0D=0Ao= virt-websocket-proxy[1838] DEBUG loadFile:70 loading config '/etc/ovirt-e= ngine/ovirt-websocket-proxy.conf.d/10-setup.conf'=0D=0Aovirt-websocket-pr= oxy[1838] DEBUG _daemon:440 I am a daemon 1838=0D=0Aovirt-websocket-proxy= [1838] DEBUG _setLimits:377 Setting rlimits=0D=0AWebSocket server setting= s:=0D=0A=C2=A0 - Listen on *:6100=0D=0A=C2=A0 - Flash security policy ser= ver=0D=0A=C2=A0 - SSL/TLS support=0D=0A=C2=A0 - Deny non-SSL/TLS connecti= ons=0D=0A=C2=A0 - proxying from *:6100 to targets in /dummy=0D=0A=0D=0A=C2= =A0 1: 10.42.0.1: new handler Process=0D=0A=C2=A0 1: 10.42.0.1: SSL/TLS (= wss://) WebSocket connection=0D=0A=C2=A0 1: 10.42.0.1: Version hybi-13, b= ase64: 'True'=0D=0A=C2=A0 1: 10.42.0.1: Path: '/eyJ2YWxpZFRvIjoiMjAxNDExM= TUyMTI1MDgiLCJkYXRhIjoiJTdCJTIyaG9zdCUyMjolMjIxMC40Mi4wLjEwMyUyMiwlMjJwb3= J0JTIyOiUyMjU5MDElMjIsJTIyc3NsX3RhcmdldCUyMjpmYWxzZSU3RCIsInZhbGlkRnJvbSI= 6IjIwMTQxMTE1MjEyMzA4Iiwic2lnbmVkRmllbGRzIjoidmFsaWRUbyxkYXRhLHZhbGlkRnJv= bSxzYWx0Iiwic2lnbmF0dXJlIjoiajRQUmxwYjBvT0dOZUNPaHZKK01wUTVrVGRMYVA0Sm8zR= DIzTGlXRlZYRm4xNU9KN0NZVmw5OTBpNTBUNzlVZkpqUzRlRmZ1SHJhT1c4TlFNbXIwanZXSU= pTWCtnL3RYSnc4MWRFS2wrcFVPVHo3MWlmY2dTbXdITmptOUkwTTl6Q0NNR2dvbE1BRzZwMnd= FbDFySDdSZkhMWnIvOGo4bnpnVGZ0NlhaOTdBcHgyejhkMlo0UjRmdklXemtXMjErMDdsNWw4= dXpNVytEM1FmaWdDS1Q3V3VKdlFHNi9SSC9zZWRBWHJXcnFUNXYzTHNuNVl0MWtYb2lGV3ZYO= HNUdE5PdGdvQWk3eGN5WUhGaEM1ei9SMjZXNEkrSlJNcDZlVDNxbWVlZnM0eWRSN0NpZWwzZW= ZvZDB5TU9meGJwMG9EMGlscXVWUWVjK1JxeGxqd21ZVG5BPT0iLCJzYWx0IjoiWGhVQ1dYL2h= QU1U9In0=3D'=0D=0A=C2=A0 1: connecting to: 10.42.0.103:5901=0D=0A=C2=A0 1= : handler exception: [Errno 111] Connection refused=0D=0A=C2=A0 1: Traceb= ack (most recent call last):=0D=0A=C2=A0 File "/usr/lib/python2.6/site-pa= ckages/websockify/websocket.py", line 711, in top_new_client=0D=0A=C2=A0=C2= =A0=C2=A0 self.new_client()=0D=0A=C2=A0 File "/usr/lib/python2.6/site-pac= kages/websockify/websocketproxy.py", line 183, in new_client=0D=0A=C2=A0=C2= =A0=C2=A0 connect=3DTrue, use_ssl=3Dself.ssl_target, unix_socket=3Dself.u= nix_target)=0D=0A=C2=A0 File "/usr/lib/python2.6/site-packages/websockify= /websocket.py", line 188, in socket=0D=0A=C2=A0=C2=A0=C2=A0 sock.connect(= addrs[0][4])=0D=0A=C2=A0 File "<string>", line 1, in connect=0D=0Aerror: = [Errno 111] Connection refused=0D=0A=0D=0A=0D=0ARegards,=0D=0A=0D=0Amots=0D= =0A --=_diYpkdcCc+XflEeiQy00J5SerIjuuCS5jgM5BJ73AfiuYSQ8 Content-Type: text/html; charset=utf-8 Content-Transfer-Encoding: quoted-printable =0D=0A<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN" "htt= p://www.w3.org/TR/html4/loose.dtd"><html>=0D=0A<head>=0D=0A <meta name=3D= "Generator" content=3D"Zarafa WebApp v7.1.10-44973">=0D=0A <meta http-eq= uiv=3D"Content-Type" content=3D"text/html; charset=3Dutf-8">=0D=0A <titl= e>ovirt-websocket-proxy uses the wrong IP</title>=0D=0A</head>=0D=0A<body= >=0D=0AHello, One of my nodes </f= ont>has two IP addresses, 10.42.0.101 an= d 10.42.0.103. Ovirt is configured to use 10.42.0.101, yet the ovirt-webs= ocket-proxy service tries to connect to 10.42.0.103, where no VNC server = is listening. Is there any way I can configure it to use the corre= ct address=3F [root@engine ~]# /usr/share/ovirt-engine/services/ov= irt-websocket-proxy/ovirt-websocket-proxy.py --debug start ovirt-webso= cket-proxy[1838] DEBUG _daemon:403 daemon entry pid=3D1838 ovirt-webso= cket-proxy[1838] DEBUG _daemon:404 background=3DFalse ovirt-websocket-= proxy[1838] DEBUG loadFile:70 loading config '/usr/share/ovirt-engine/ser= vices/ovirt-websocket-proxy/ovirt-websocket-proxy.conf' ovirt-websocke= t-proxy[1838] DEBUG loadFile:70 loading config '/etc/ovirt-engine/ovirt-w= ebsocket-proxy.conf.d/10-setup.conf' ovirt-websocket-proxy[1838] DEBUG= _daemon:440 I am a daemon 1838 ovirt-websocket-proxy[1838] DEBUG _set= Limits:377 Setting rlimits WebSocket server settings:   - List= en on *:6100   - Flash security policy server   - SSL/TLS= support   - Deny non-SSL/TLS connections   - proxying fr= om *:6100 to targets in /dummy   1: 10.42.0.1: new handler Pr= ocess   1: 10.42.0.1: SSL/TLS (wss://) WebSocket connection &n= bsp; 1: 10.42.0.1: Version hybi-13, base64: 'True'   1: 10.42.0.1= : Path: '/eyJ2YWxpZFRvIjoiMjAxNDExMTUyMTI1MDgiLCJkYXRhIjoiJTdCJTIyaG9zdCU= yMjolMjIxMC40Mi4wLjEwMyUyMiwlMjJwb3J0JTIyOiUyMjU5MDElMjIsJTIyc3NsX3Rhcmdl= dCUyMjpmYWxzZSU3RCIsInZhbGlkRnJvbSI6IjIwMTQxMTE1MjEyMzA4Iiwic2lnbmVkRmllb= GRzIjoidmFsaWRUbyxkYXRhLHZhbGlkRnJvbSxzYWx0Iiwic2lnbmF0dXJlIjoiajRQUmxwYj= BvT0dOZUNPaHZKK01wUTVrVGRMYVA0Sm8zRDIzTGlXRlZYRm4xNU9KN0NZVmw5OTBpNTBUNzl= VZkpqUzRlRmZ1SHJhT1c4TlFNbXIwanZXSUpTWCtnL3RYSnc4MWRFS2wrcFVPVHo3MWlmY2dT= bXdITmptOUkwTTl6Q0NNR2dvbE1BRzZwMndFbDFySDdSZkhMWnIvOGo4bnpnVGZ0NlhaOTdBc= HgyejhkMlo0UjRmdklXemtXMjErMDdsNWw4dXpNVytEM1FmaWdDS1Q3V3VKdlFHNi9SSC9zZW= RBWHJXcnFUNXYzTHNuNVl0MWtYb2lGV3ZYOHNUdE5PdGdvQWk3eGN5WUhGaEM1ei9SMjZXNEk= rSlJNcDZlVDNxbWVlZnM0eWRSN0NpZWwzZWZvZDB5TU9meGJwMG9EMGlscXVWUWVjK1JxeGxq= d21ZVG5BPT0iLCJzYWx0IjoiWGhVQ1dYL2hQU1U9In0=3D'   1: connecting t= o: 10.42.0.103:5901   1: handler exception: [Errno 111] Connectio= n refused   1: Traceback (most recent call last):   File = "/usr/lib/python2.6/site-packages/websockify/websocket.py", line 711, in = top_new_client     self.new_client()   File "/u= sr/lib/python2.6/site-packages/websockify/websocketproxy.py", line 183, i= n new_client     connect=3DTrue, use_ssl=3Dself.ssl_tar= get, unix_socket=3Dself.unix_target)   File "/usr/lib/python2.6/s= ite-packages/websockify/websocket.py", line 188, in socket  &nbsp= ;  sock.connect(addrs[0][4])   File "<string>", line 1= , in connect error: [Errno 111] Connection refused Regards,= mots =0D=0A</body>=0D=0A</html> --=_diYpkdcCc+XflEeiQy00J5SerIjuuCS5jgM5BJ73AfiuYSQ8-- ------=_Part_27_1351307630.1416086778054 Content-Type: application/pgp-signature; name=signature.asc Content-Transfer-Encoding: 7bit Content-Description: OpenPGP digital signature Content-Disposition: attachment; filename="signature.asc" -----BEGIN PGP SIGNATURE----- Version: CIPHERMAIL (2.8.6-4) iQEcBAEBCAAGBQJUZ8T6AAoJELfzdzVzTtoK3K0H/2O2w3FZAvdP+AxXEzwrZIMR W9dBZ8yuX3gyhzu5LdANuu4BVjMStfJ1bK4VdWyyp2DquR5hVtbBv1C3obBquDok rxByShXWm5YdVvWaDzxHiUtTuIqy0vqfQgp8KDHRUOubhqSEfqHFuAs/GOLJqlSv x/mo4lSrQ5IK+OiNh83fEw+S9Y5O5lems0IT3XQH6xRR7YBP0Dt0b4Rdg+qSt9GH 3VYFnxbYAY7mp7tCQPJUGSBwuclOMNgHLSGa9FapFKnFm6+ionXCuEoQFc19F7z4 u5QrogtXjTAqp26/VKW1RiSkgtbQZhLHz8xYQAuav6VKALpwTaGOwLKN4O+jYo4= =Tx0F -----END PGP SIGNATURE----- ------=_Part_27_1351307630.1416086778054--

2 1

can't create new disk for vm
by Nathanaël Blanchet 17 Nov '14

17 Nov '14

Hi all, Since I upgraded engine to 3.5, I can't add new disk anymore to existant vms, none than create a new vm with a new disk. Clicking on OK button after the disk definition does nothing. I tried many situations (preallocated or thin provision) or changing of storage domain, but it is the same. Has anybody encountered the same issue?

2 2