- Users - oVirt List Archives

[Users] Stuck disk
by Jason Lawer 05 Feb '13

05 Feb '13

Hi, I appear to have 2 VMs that were in the process of being removed from my ovirt 3.1 system when the SPM role shifted to another node and it appears that the disks were removed from the iSCSI LVM Volume Group but oVirt seems to believe that it still exists. I can't remove these from the UI anymore as they fail and I am getting a log entry on the SPM node indicating that the disk cannot be found. Can someone give me an idea of how to remove these disks (I am assuming I will need to remove the records from the DB). Thanks in Advance, Jason Lawer

2 1

[Users] Data Center is being initialized, please wait for initialization to complete
by Alex Leonhardt 05 Feb '13

05 Feb '13

Hi, Occasionally, mainly after doing larger disk operations, I see this : Data Center is being initialized, please wait for initialization to complete and then the SPM host changes ... I didnt see any particular load on that server at the time it happened - I simply told it to delete some VMs from a export domain. Any clues ? Alex -- | RHCE | Senior Systems Engineer | www.vcore.co | www.vsearchcloud.com |

2 1

[Users] Connecting with vnc to a VM defined as spice
by Gianluca Cecchi 05 Feb '13

05 Feb '13

Hello, I have a Windows XP VM in 3.2 beta defined as spice. If I want to connect from a client that only has vnc (a Mac for example) how can I do from User Portal? Even if the VM is powered off then I power it on, on the right I see only spice as an option... Does the user need any special right? And if I select run once form user portal and select vnc then it tries to fire up spice protocol and if I click on "..." icon, only spice is present and selected... Any pre-requisite on engine/node/vm/client? Thanks, Gianluca

3 5

[Users] Configuration file of VM
by Alexei Yakovlev 04 Feb '13

04 Feb '13

Hi everybody, can you help me to find where does ovirt stores configuration files in xml format of Virtual Machines?

3 2

Re: [Users] Problem with libvirt
by Juan Jose 04 Feb '13

04 Feb '13

Hello Antonio, Could you indicate to me how I can connect to this #vdsm irc channel, please?, I have seen in the ovirt page, community, IRC, but it doesn't explain anything about how to connect. I have the ircII client in my Debian box but I guess that it is necessary the host and port number. Is it possible that host was irc.freenode.net?, and what is the port number and the user to connect? Thanks, Juanjo. On Mon, Feb 4, 2013 at 4:42 PM, Antoni Segura Puimedon <asegurap(a)redhat.com>wrote: > Juanjo, > > I'll look at it in the evening. If I don't find anything, maybe you > can connect to the #vdsm irc channel at freenode tomorrow. > > Best > > Toni > > ----- Original Message ----- > > From: "Juan Jose" <jj197005(a)gmail.com> > > To: "Antoni Segura Puimedon" <asegurap(a)redhat.com> > > Cc: users(a)ovirt.org > > Sent: Monday, February 4, 2013 4:27:04 PM > > Subject: Re: [Users] Problem with libvirt > > > > More information, > > > > > > I attach the messages in the moment that error with VM installation > > occur. > > > > > > Juanjo. > > > > > > On Fri, Feb 1, 2013 at 11:26 AM, Antoni Segura Puimedon < > > asegurap(a)redhat.com > wrote: > > > > > > Hola, > > > > Could you make some pastebins with the contents of the files > > /etc/sysconfig/network-scripts/ifcfg* ? > > > > Also, virsh -r net-list and the log generated on the process > > of losing the connection when creating a guest. > > > > Best, > > > > Toni > > > > > > > > ----- Original Message ----- > > > From: "Juan Jose" < jj197005(a)gmail.com > > > > To: "Moti Asayag" < masayag(a)redhat.com > > > > Cc: users(a)ovirt.org > > > Sent: Friday, February 1, 2013 10:57:08 AM > > > Subject: Re: [Users] Problem with libvirt > > > > > > > > > Hello Monti and Dafna, > > > > > > > > > The host have connectivity with the engine until I try to install a > > > VM and in the middle of the process host loses the connectivity. I > > > can see that it is a connection problem. How can I check if the > > > host > > > address is the same as I used to add it to my data-center?, I made > > > an IP address change in the host but I delete it from engine and > > > after change its address I re-added. > > > > > > > > > On the other hand, I would like to know if my network configuration > > > is correct because when I execute the "ipconfig" command in host > > > console I can see the interfaces bond0 to bond4, em1 interface, > > > localhost and ovirtmgmt with the host IP. Is it that correct? > > > > > > > > > Many thanks in avanced, > > > > > > > > > Juanjo. > > > > > > > > > On Thu, Jan 31, 2013 at 3:04 PM, Moti Asayag < masayag(a)redhat.com > > > > wrote: > > > > > > > > > > > > On 01/31/2013 03:37 PM, Juan Jose wrote: > > > > Hello Moti, > > > > > > > > The execution of this command in the host is: > > > > > > This indicates VDSM is up and running correctly, but the > > > ovirt-engine > > > can't reach it. > > > > > > Can you check the connectivity from the ovirt-engine to the host > > > (use > > > the same address as used to add it to data-center) ? > > > > > > Maybe there are iptables issues preventing establishing connection > > > from > > > the engine to the host. > > > > > > > > > > > > > > > > > > > > [root@ovirt-host ~]# vdsClient -s 0 getVdsCaps > > > > HBAInventory = {'iSCSI': [{'InitiatorName': > > > > 'iqn.1994-05.com.redhat:69e9aaf7e4c'}], 'FC': []} > > > > ISCSIInitiatorName = iqn.1994-05.com.redhat:69e9aaf7e4c > > > > bondings = {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', > > > > 'netmask': > > > > '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': > > > > {'addr': > > > > '', > > > > 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': > > > > '00:00:00:00:00:00'}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': > > > > '1500', > > > > 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, > > > > 'bond2': > > > > {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': > > > > [], > > > > 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, > > > > 'mtu': > > > > '1500', 'netmask': '', 'slaves': [], 'hwaddr': > > > > '00:00:00:00:00:00'}} > > > > clusterLevels = ['3.0', '3.1'] > > > > cpuCores = 4 > > > > cpuFlags = > > > > > fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,smx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,lahf_lm,dtherm,tpr_shadow,vnmi,flexpriority,model_coreduo,model_Conroe > > > > cpuModel = Intel(R) Core(TM)2 Quad CPU Q9300 @ 2.50GHz > > > > cpuSockets = 1 > > > > cpuSpeed = 1999.000 > > > > emulatedMachines = ['pc-0.15', 'pc-1.0', 'pc', 'pc-0.14', > > > > 'pc-0.13', > > > > 'pc-0.12', 'pc-0.11', 'pc-0.10', 'isapc', 'pc-0.15', 'pc-1.0', > > > > 'pc', > > > > 'pc-0.14', 'pc-0.13', 'pc-0.12', 'pc-0.11', 'pc-0.10', 'isapc'] > > > > guestOverhead = 65 > > > > hooks = {} > > > > kvmEnabled = true > > > > lastClient = xxx.xxx.xxx.91 > > > > lastClientIface = ovirtmgmt > > > > management_ip = > > > > memSize = 7701 > > > > netConfigDirty = False > > > > networks = {'ovirtmgmt': {'addr': '158.109.202.67', 'cfg': > > > > {'DELAY': > > > > '0', 'IPV6INIT': 'no', 'UUID': > > > > '3cbac056-822a-43e9-a4ec-df5324becd79', > > > > 'DEFROUTE': 'yes', 'DNS1': 'xxx.xxx.xxx.1', 'IPADDR': > > > > 'xxx.xxx.xxx.67', > > > > 'ONBOOT': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'BROADCAST': > > > > 'xxx.xxx.xxx.255', 'NM_CONTROLLED': 'no', 'NETMASK': > > > > 'xxx.xxx.xxx.0', > > > > 'BOOTPROTO': 'none', 'DNS2': 'xxx.xxx.xxx.9', 'DEVICE': > > > > 'ovirtmgmt', > > > > 'TYPE': 'Bridge', 'GATEWAY': 'xxx.xxx.xxx.1', 'NETWORK': > > > > 'xxx.xxx.xxx.0'}, 'mtu': '1500', 'netmask': '255.255.254.0', > > > > 'stp': > > > > 'off', 'bridged': True, 'gateway': 'xxx.xxx.xxx.1', 'ports': > > > > ['em1']}} > > > > nics = {'em1': {'hwaddr': '00:19:99:35:cc:54', 'netmask': '', > > > > 'speed': > > > > 1000, 'addr': '', 'mtu': '1500'}} > > > > operatingSystem = {'release': '1', 'version': '17', 'name': > > > > 'Fedora'} > > > > packages2 = {'kernel': {'release': '1.fc17.x86_64', 'buildtime': > > > > 1350912755.0, 'version': '3.6.3'}, 'spice-server': {'release': > > > > '5.fc17', > > > > 'buildtime': '1336983054', 'version': '0.10.1'}, 'vdsm': > > > > {'release': > > > > '10.fc17', 'buildtime': '1349383616', 'version': '4.10.0'}, > > > > 'qemu-kvm': > > > > {'release': '2.fc17', 'buildtime': '1349642820', 'version': > > > > '1.0.1'}, > > > > 'libvirt': {'release': '2.fc17', 'buildtime': '1355687905', > > > > 'version': > > > > '0.9.11.8'}, 'qemu-img': {'release': '2.fc17', 'buildtime': > > > > '1349642820', 'version': '1.0.1'}} > > > > reservedMem = 321 > > > > software_revision = 10 > > > > software_version = 4.10 > > > > supportedProtocols = ['2.2', '2.3'] > > > > supportedRHEVMs = ['3.0', '3.1'] > > > > uuid = 36303030-3139-3236-3800-00199935CC54_00:19:99:35:cc:54 > > > > version_name = Snow Man > > > > vlans = {} > > > > vmTypes = ['kvm'] > > > > [root@ovirt-host ~]# > > > > > > > > I can't execute this command in my engine because command > > > > "vdsClient" is > > > > not installed. > > > > > > > > Many thanks in avanced, > > > > > > > > Juanjo. > > > > > > > > On Thu, Jan 31, 2013 at 2:06 PM, Moti Asayag < masayag(a)redhat.com > > > > > > > > > > <mailto: masayag(a)redhat.com >> wrote: > > > > > > > > On 01/31/2013 02:26 PM, Dafna Ron wrote: > > > > > non responsive state for host means that you either have a > > > > > network > > > > issue > > > > > (iptables perhaps) or vdsmd is down. > > > > > please attach vdsm and libvirtd logs. > > > > > > > > > > > > > > > > > > By looking the engine.log below there are network issues: > > > > > > > > 2013-01-31 11:49:54,121 ERROR > > > > [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > (QuartzScheduler_Worker-73) [73213e4f] vds::refreshVdsStats > > > > Failed > > > > getVdsStats, vds = 7d3491e8-49ce-11e2-8b2e-000a5e429f63 : host1, > > > > error > > > > = VDSNetworkException: VDSNetworkException: > > > > > > > > Can you reach the host from the ovirt-engine server ? or can you > > > > check > > > > that vdsm service runs on the host by invoking: > > > > > > > > vdsClient -s 0 getVdsCaps > > > > > > > > > > > > > > On 01/31/2013 01:21 PM, Juan Jose wrote: > > > > >> Hello everybody, > > > > >> > > > > >> I have installed and configured oVirt 3.1 engine in a Fedora > > > > >> 17 > > > > with a > > > > >> Fedora 17 node connected. Ihave defined a NFS domain for my VM > > > > >> and > > > > >> another for ISOs. I try to start a Fedora 17 Server with Run > > > > >> once and > > > > >> the machi start without problems, after that I preceed with > > > > >> the > > > > >> installation in its wirtual disk but when I arrive to define > > > > >> partitions in the virtual disk the machine is freeze and I > > > > >> start > > > > >> to > > > > >> receive engine errors and the default data center go in non > > > > responsive > > > > >> status. > > > > >> > > > > >> I can see this messages in /var/log/ovirt-engine/engine.log, > > > > >> which I > > > > >> attach to this message: > > > > >> > > > > >> .... > > > > >> 2013-01-31 11:43:23,957 INFO > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-47) [75664f2b] Recieved a Device > > > > >> without > > > > >> an > > > > >> address when processing VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 > > > > >> devices, skipping device: {shared=false, > > > > >> volumeID=1d0e9fdf-c4bc-4894-8ff1-7a5e185d57a4, index=0, > > > > >> propagateErrors=off, format=raw, type=disk, > > > > >> truesize=8589938688, > > > > >> reqsize=0, bootOrder=2, iface=virtio, > > > > >> volumeChain=[Ljava.lang.Object;@1ea2bdf9, > > > > >> imageID=49e21bfc-384b-4bea-8013-f02b1be137c7, > > > > >> domainID=57d184a0-908b-49b5-926f-cd413b9e6526, specParams={}, > > > > >> optional=false, needExtend=false, > > > > >> > > > > > path=/rhev/data-center/d6e7e8b8-49c7-11e2-a261-000a5e429f63/57d184a0-908b-49b5-926f-cd413b9e6526/images/49e21bfc-384b-4bea-8013-f02b1be137c7/1d0e9fdf-c4bc-4894-8ff1-7a5e185d57a4, > > > > >> device=disk, poolID=d6e7e8b8-49c7-11e2-a261-000a5e429f63, > > > > >> readonly=false, deviceId=49e21bfc-384b-4bea-8013-f02b1be137c7, > > > > >> apparentsize=8589934592}. > > > > >> 2013-01-31 11:43:23,960 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-47) [75664f2b] VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable > > > > >> device > > > > >> was > > > > >> removed unexpetedly from libvirt: > > > > >> > > > > > VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=4dca1c64-dbf8-4e31-b359-82cf0e259f65,Device=qxl,Type=video,BootOrder=0,SpecParams={vram=65536},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=false,alias= > > > > >> 2013-01-31 11:43:23,961 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-47) [75664f2b] VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable > > > > >> device > > > > >> was > > > > >> removed unexpetedly from libvirt: > > > > >> > > > > > VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=aba73f2f-e951-4eba-9da4-8fb58315df2c,Device=memballoon,Type=balloon,BootOrder=0,SpecParams={model=virtio},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= > > > > >> 2013-01-31 11:43:23,962 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-47) [75664f2b] VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable > > > > >> device > > > > >> was > > > > >> removed unexpetedly from libvirt: > > > > >> > > > > > VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=9bfb770c-13fa-4bf6-9f1f-414927bc31b0,Device=cdrom,Type=disk,BootOrder=0,SpecParams={path=},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= > > > > >> 2013-01-31 11:43:23,963 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-47) [75664f2b] VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable > > > > >> device > > > > >> was > > > > >> removed unexpetedly from libvirt: > > > > >> > > > > > VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=614bc0b4-64d8-4058-8bf8-83db62617e00,Device=bridge,Type=interface,BootOrder=0,SpecParams={},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=false,alias= > > > > >> 2013-01-31 11:43:23,964 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-47) [75664f2b] VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable > > > > >> device > > > > >> was > > > > >> removed unexpetedly from libvirt: > > > > >> > > > > > VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=49e21bfc-384b-4bea-8013-f02b1be137c7,Device=disk,Type=disk,BootOrder=0,SpecParams={},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=false,alias= > > > > >> 2013-01-31 11:43:26,063 INFO > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-24) [7d021319] VM Fedora17 > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 moved from WaitForLaunch > > > > >> --> > > > > >> PoweringUp > > > > >> 2013-01-31 11:43:26,064 INFO > > > > >> [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] > > > > >> (QuartzScheduler_Worker-24) [7d021319] START, > > > > FullListVdsCommand(vdsId > > > > >> = 7d3491e8-49ce-11e2-8b2e-000a5e429f63, vds=null, > > > > >> vmIds=[da09284e-3189-428b-a879-6201f7a5ca87]), log id: f68f564 > > > > >> 2013-01-31 11:43:26,086 INFO > > > > >> [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] > > > > >> (QuartzScheduler_Worker-24) [7d021319] FINISH, > > > > >> FullListVdsCommand, > > > > >> return: > > > > >> [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@33c68023, > > > > >> log > > > > >> id: f68f564 > > > > >> 2013-01-31 11:43:26,091 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-24) [7d021319] VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable > > > > >> device > > > > >> was > > > > >> removed unexpetedly from libvirt: > > > > >> > > > > > VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=aba73f2f-e951-4eba-9da4-8fb58315df2c,Device=memballoon,Type=balloon,BootOrder=0,SpecParams={model=virtio},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= > > > > >> 2013-01-31 11:43:26,092 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-24) [7d021319] VM > > > > >> da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable > > > > >> device > > > > >> was > > > > >> removed unexpetedly from libvirt: > > > > >> > > > > > VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=9bfb770c-13fa-4bf6-9f1f-414927bc31b0,Device=cdrom,Type=disk,BootOrder=0,SpecParams={path=},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= > > > > >> 2013-01-31 11:43:31,721 INFO > > > > >> [org.ovirt.engine.core.bll.SetVmTicketCommand] > > > > (ajp--0.0.0.0-8009-11) > > > > >> [28d7a789] Running command: SetVmTicketCommand internal: > > > > >> false. > > > > >> Entities affected : ID: da09284e-3189-428b-a879-6201f7a5ca87 > > > > Type: VM > > > > >> 2013-01-31 11:43:31,724 INFO > > > > >> [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] > > > > >> (ajp--0.0.0.0-8009-11) [28d7a789] START, > > > > SetVmTicketVDSCommand(vdsId = > > > > >> 7d3491e8-49ce-11e2-8b2e-000a5e429f63, > > > > >> vmId=da09284e-3189-428b-a879-6201f7a5ca87, > > > > >> ticket=qmcnuOICblb3, > > > > >> validTime=120,m userName=admin@internal, > > > > >> userId=fdfc627c-d875-11e0-90f0-83df133b58cc), log id: 6eaacb95 > > > > >> 2013-01-31 11:43:31,758 INFO > > > > >> [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] > > > > >> (ajp--0.0.0.0-8009-11) [28d7a789] FINISH, > > > > >> SetVmTicketVDSCommand, > > > > >> log > > > > >> id: 6eaacb95 > > > > >> > > > > >> ... > > > > >> > > > > >> 2013-01-31 11:49:13,392 WARN > > > > >> [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] > > > > >> (QuartzScheduler_Worker-81) [164eaa47] domain > > > > >> 57d184a0-908b-49b5-926f-cd413b9e6526 in problem. vds: host1 > > > > >> 2013-01-31 11:49:54,121 ERROR > > > > >> [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] > > > > >> (QuartzScheduler_Worker-73) [73213e4f] vds::refreshVdsStats > > > > >> Failed > > > > >> getVdsStats, vds = 7d3491e8-49ce-11e2-8b2e-000a5e429f63 : > > > > >> host1, > > > > >> error = VDSNetworkException: VDSNetworkException: > > > > >> 2013-01-31 11:49:54,172 WARN > > > > >> [org.ovirt.engine.core.vdsbroker.VdsManager] > > > > >> (QuartzScheduler_Worker-73) [73213e4f] > > > > >> ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS > > > > >> , > > > > >> vds = > > > > >> 7d3491e8-49ce-11e2-8b2e-000a5e429f63 : host1, VDS Network > > > > >> Error, > > > > >> continuing. > > > > >> VDSNetworkException: > > > > >> > > > > >> .... > > > > >> > > > > >> > > > > >> In the events windows after VM freezing, I have below events: > > > > >> > > > > >> 2013-Jan-31, 11:50:52 Failed to elect Host as Storage Pool > > > > >> Manager > > > > >> for Data Center Default. Setting status to Non-Operational. > > > > >> 2013-Jan-31, 11:50:52 VM Fedora17 was set to the Unknown > > > > >> status. > > > > >> 2013-Jan-31, 11:50:52 Host host1 is non-responsive. > > > > >> 2013-Jan-31, 11:49:55 Invalid status on Data Center Default. > > > > >> Setting > > > > >> Data Center status to Non-Responsive (On host host1, Error: > > > > >> Network > > > > >> error during communication with the Host.). > > > > >> 2013-Jan-31, 11:44:25 VM Fedora17 started on Host host1 > > > > >> > > > > >> Any suggest about the problem?. It seem a libvirt problem, I > > > > >> will > > > > >> continue investigating. > > > > >> > > > > >> Many thanks in avanced, > > > > >> > > > > >> Juanjo. > > > > >> > > > > >> > > > > >> _______________________________________________ > > > > >> Users mailing list > > > > >> Users(a)ovirt.org <mailto: Users(a)ovirt.org > > > > > >> http://lists.ovirt.org/mailman/listinfo/users > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > _______________________________________________ > > > Users mailing list > > > Users(a)ovirt.org > > > http://lists.ovirt.org/mailman/listinfo/users > > > > > > > >

1 0

[Users] Problem with libvirt
by Juan Jose 04 Feb '13

04 Feb '13

Hello everybody, I have installed and configured oVirt 3.1 engine in a Fedora 17 with a Fedora 17 node connected. Ihave defined a NFS domain for my VM and another for ISOs. I try to start a Fedora 17 Server with Run once and the machi start without problems, after that I preceed with the installation in its wirtual disk but when I arrive to define partitions in the virtual disk the machine is freeze and I start to receive engine errors and the default data center go in non responsive status. I can see this messages in /var/log/ovirt-engine/engine.log, which I attach to this message: .... 2013-01-31 11:43:23,957 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-47) [75664f2b] Recieved a Device without an address when processing VM da09284e-3189-428b-a879-6201f7a5ca87 devices, skipping device: {shared=false, volumeID=1d0e9fdf-c4bc-4894-8ff1-7a5e185d57a4, index=0, propagateErrors=off, format=raw, type=disk, truesize=8589938688, reqsize=0, bootOrder=2, iface=virtio, volumeChain=[Ljava.lang.Object;@1ea2bdf9, imageID=49e21bfc-384b-4bea-8013-f02b1be137c7, domainID=57d184a0-908b-49b5-926f-cd413b9e6526, specParams={}, optional=false, needExtend=false, path=/rhev/data-center/d6e7e8b8-49c7-11e2-a261-000a5e429f63/57d184a0-908b-49b5-926f-cd413b9e6526/images/49e21bfc-384b-4bea-8013-f02b1be137c7/1d0e9fdf-c4bc-4894-8ff1-7a5e185d57a4, device=disk, poolID=d6e7e8b8-49c7-11e2-a261-000a5e429f63, readonly=false, deviceId=49e21bfc-384b-4bea-8013-f02b1be137c7, apparentsize=8589934592}. 2013-01-31 11:43:23,960 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-47) [75664f2b] VM da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable device was removed unexpetedly from libvirt: VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=4dca1c64-dbf8-4e31-b359-82cf0e259f65,Device=qxl,Type=video,BootOrder=0,SpecParams={vram=65536},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=false,alias= 2013-01-31 11:43:23,961 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-47) [75664f2b] VM da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable device was removed unexpetedly from libvirt: VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=aba73f2f-e951-4eba-9da4-8fb58315df2c,Device=memballoon,Type=balloon,BootOrder=0,SpecParams={model=virtio},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= 2013-01-31 11:43:23,962 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-47) [75664f2b] VM da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable device was removed unexpetedly from libvirt: VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=9bfb770c-13fa-4bf6-9f1f-414927bc31b0,Device=cdrom,Type=disk,BootOrder=0,SpecParams={path=},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= 2013-01-31 11:43:23,963 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-47) [75664f2b] VM da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable device was removed unexpetedly from libvirt: VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=614bc0b4-64d8-4058-8bf8-83db62617e00,Device=bridge,Type=interface,BootOrder=0,SpecParams={},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=false,alias= 2013-01-31 11:43:23,964 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-47) [75664f2b] VM da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable device was removed unexpetedly from libvirt: VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=49e21bfc-384b-4bea-8013-f02b1be137c7,Device=disk,Type=disk,BootOrder=0,SpecParams={},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=false,alias= 2013-01-31 11:43:26,063 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-24) [7d021319] VM Fedora17 da09284e-3189-428b-a879-6201f7a5ca87 moved from WaitForLaunch --> PoweringUp 2013-01-31 11:43:26,064 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-24) [7d021319] START, FullListVdsCommand(vdsId = 7d3491e8-49ce-11e2-8b2e-000a5e429f63, vds=null, vmIds=[da09284e-3189-428b-a879-6201f7a5ca87]), log id: f68f564 2013-01-31 11:43:26,086 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-24) [7d021319] FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@33c68023, log id: f68f564 2013-01-31 11:43:26,091 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-24) [7d021319] VM da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable device was removed unexpetedly from libvirt: VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=aba73f2f-e951-4eba-9da4-8fb58315df2c,Device=memballoon,Type=balloon,BootOrder=0,SpecParams={model=virtio},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= 2013-01-31 11:43:26,092 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-24) [7d021319] VM da09284e-3189-428b-a879-6201f7a5ca87 managed non plugable device was removed unexpetedly from libvirt: VmId=da09284e-3189-428b-a879-6201f7a5ca87,DeviceId=9bfb770c-13fa-4bf6-9f1f-414927bc31b0,Device=cdrom,Type=disk,BootOrder=0,SpecParams={path=},Address=,IsManaged=true,IsPlugged=true,IsReadOnly=true,alias= 2013-01-31 11:43:31,721 INFO [org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp--0.0.0.0-8009-11) [28d7a789] Running command: SetVmTicketCommand internal: false. Entities affected : ID: da09284e-3189-428b-a879-6201f7a5ca87 Type: VM 2013-01-31 11:43:31,724 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp--0.0.0.0-8009-11) [28d7a789] START, SetVmTicketVDSCommand(vdsId = 7d3491e8-49ce-11e2-8b2e-000a5e429f63, vmId=da09284e-3189-428b-a879-6201f7a5ca87, ticket=qmcnuOICblb3, validTime=120,m userName=admin@internal, userId=fdfc627c-d875-11e0-90f0-83df133b58cc), log id: 6eaacb95 2013-01-31 11:43:31,758 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (ajp--0.0.0.0-8009-11) [28d7a789] FINISH, SetVmTicketVDSCommand, log id: 6eaacb95 ... 2013-01-31 11:49:13,392 WARN [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-81) [164eaa47] domain 57d184a0-908b-49b5-926f-cd413b9e6526 in problem. vds: host1 2013-01-31 11:49:54,121 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-73) [73213e4f] vds::refreshVdsStats Failed getVdsStats, vds = 7d3491e8-49ce-11e2-8b2e-000a5e429f63 : host1, error = VDSNetworkException: VDSNetworkException: 2013-01-31 11:49:54,172 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-73) [73213e4f] ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = 7d3491e8-49ce-11e2-8b2e-000a5e429f63 : host1, VDS Network Error, continuing. VDSNetworkException: .... In the events windows after VM freezing, I have below events: 2013-Jan-31, 11:50:52 Failed to elect Host as Storage Pool Manager for Data Center Default. Setting status to Non-Operational. 2013-Jan-31, 11:50:52 VM Fedora17 was set to the Unknown status. 2013-Jan-31, 11:50:52 Host host1 is non-responsive. 2013-Jan-31, 11:49:55 Invalid status on Data Center Default. Setting Data Center status to Non-Responsive (On host host1, Error: Network error during communication with the Host.). 2013-Jan-31, 11:44:25 VM Fedora17 started on Host host1 Any suggest about the problem?. It seem a libvirt problem, I will continue investigating. Many thanks in avanced, Juanjo.

4 11

[Users] USB mapping.
by Alexei Yakovlev 04 Feb '13

04 Feb '13

Hi, is it any way to attach USB devices that is connected to host with VM? I need a USB key to be visible at virtual machine, the key is attached physically to oVirt Host under Fedora 17.

2 1

[Users] Testday aftermath
by Joop 04 Feb '13

04 Feb '13

Yesterday was testday but not much fun :-( I had a reasonable working setup but for testday I decided to start from scratch and that ended rather soon. Installing and configuring engine was not a problem but I want a setup where I have two gluster hosts and two hosts as vmhosts. I added a second cluster using the webinterface set it to gluster storage and added two minimal installed Fedora 18 hosts where I setup static networking and verified that it worked. Adding the two hosts went OK but adding a Volume gives the following error on engine: 2013-02-01 09:32:39,084 INFO [org.ovirt.engine.core.bll.gluster.CreateGlusterVolumeCommand] (ajp--127.0.0.1-8702-4) [5ea886d] Running command: CreateGlusterVolumeCommand internal: false. Entities affected : ID: 8720debc-a184-4b61-9fa8-0fdf4d339b9a Type: VdsGroups 2013-02-01 09:32:39,117 INFO [org.ovirt.engine.core.vdsbroker.gluster.CreateGlusterVolumeVDSCommand] (ajp--127.0.0.1-8702-4) [5ea886d] START, CreateGlusterVolumeVDSCommand(HostName = st02, HostId = e7b74172-2f95-43cb-83ff-11705ae24265), log id: 4270f4ef 2013-02-01 09:32:39,246 WARN [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-4) [5ea886d] Weird return value: StatusForXmlRpc [mCode=4106, mMessage=XML error error: <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <cliOutput><volCreate><count>2</count><bricks> st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data </bricks><transport>tcp</transport><type>2</type><volname>GlusterData</volname><replica-count>2</replica-count></volCreate></cliOutput> ] 2013-02-01 09:32:39,248 WARN [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-4) [5ea886d] Weird return value: StatusForXmlRpc [mCode=4106, mMessage=XML error error: <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <cliOutput><volCreate><count>2</count><bricks> st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data </bricks><transport>tcp</transport><type>2</type><volname>GlusterData</volname><replica-count>2</replica-count></volCreate></cliOutput> ] 2013-02-01 09:32:39,249 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-4) [5ea886d] Failed in CreateGlusterVolumeVDS method 2013-02-01 09:32:39,250 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp--127.0.0.1-8702-4) [5ea886d] Error code unexpected and error message VDSGenericException: VDSErrorException: Failed to CreateGlusterVolumeVDS, error = XML error error: <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <cliOutput><volCreate><count>2</count><bricks> st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data </bricks><transport>tcp</transport><type>2</type><volname>GlusterData</volname><replica-count>2</replica-count></volCreate></cliOutput> 2013-02-01 09:32:39,254 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (ajp--127.0.0.1-8702-4) [5ea886d] Command CreateGlusterVolumeVDS execution failed. Exception: VDSErrorException: VDSGenericException: VDSErrorException: Failed to CreateGlusterVolumeVDS, error = XML error error: <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <cliOutput><volCreate><count>2</count><bricks> st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data </bricks><transport>tcp</transport><type>2</type><volname>GlusterData</volname><replica-count>2</replica-count></volCreate></cliOutput> 2013-02-01 09:32:39,255 INFO [org.ovirt.engine.core.vdsbroker.gluster.CreateGlusterVolumeVDSCommand] (ajp--127.0.0.1-8702-4) [5ea886d] FINISH, CreateGlusterVolumeVDSCommand, log id: 4270f4ef 2013-02-01 09:32:39,256 ERROR [org.ovirt.engine.core.bll.gluster.CreateGlusterVolumeCommand] (ajp--127.0.0.1-8702-4) [5ea886d] Command org.ovirt.engine.core.bll.gluster.CreateGlusterVolumeCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to CreateGlusterVolumeVDS, error = XML error error: <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <cliOutput><volCreate><count>2</count><bricks> st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data </bricks><transport>tcp</transport><type>2</type><volname>GlusterData</volname><replica-count>2</replica-count></volCreate></cliOutput> 2013-02-01 09:32:39,268 INFO [org.ovirt.engine.core.bll.gluster.CreateGlusterVolumeCommand] (ajp--127.0.0.1-8702-4) [5ea886d] Lock freed to object EngineLock [exclusiveLocks= key: 8720debc-a184-4b61-9fa8-0fdf4d339b9a value: GLUSTER , sharedLocks= ] 2013-02-01 09:32:40,902 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (QuartzScheduler_Worker-85) START, GlusterVolumesListVDSCommand(HostName = st02, HostId = e7b74172-2f95-43cb-83ff-11705ae24265), log id: 61cafb32 And on ST01 the following in vdsm.log Thread-644::DEBUG::2013-02-01 10:24:06,378::BindingXMLRPC::913::vds::(wrapper) client [192.168.216.150]::call volumeCreate with ('GlusterData', ['st01.nieuwland.nl:/home/gluster-data', 'st02.nieuwland.nl:/home/gluster-data'], 2, 0, ['TCP']) {} MainProcess|Thread-644::DEBUG::2013-02-01 10:24:06,381::misc::84::Storage.Misc.excCmd::(<lambda>) '/usr/sbin/gluster --mode=script volume create GlusterData replica 2 transport TCP st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data --xml' (cwd None) MainProcess|Thread-644::DEBUG::2013-02-01 10:24:06,639::misc::84::Storage.Misc.excCmd::(<lambda>) SUCCESS: <err> = ''; <rc> = 0 MainProcess|Thread-644::ERROR::2013-02-01 10:24:06,640::supervdsmServer::80::SuperVdsm.ServerCallback::(wrapper) Error in wrapper Traceback (most recent call last): File "/usr/share/vdsm/supervdsmServer.py", line 78, in wrapper return func(*args, **kwargs) File "/usr/share/vdsm/supervdsmServer.py", line 352, in wrapper return func(*args, **kwargs) File "/usr/share/vdsm/gluster/cli.py", line 45, in wrapper return func(*args, **kwargs) File "/usr/share/vdsm/gluster/cli.py", line 446, in volumeCreate xmltree = _execGlusterXml(command) File "/usr/share/vdsm/gluster/cli.py", line 89, in _execGlusterXml raise ge.GlusterXmlErrorException(err=out) GlusterXmlErrorException: XML error error: <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <cliOutput><volCreate><count>2</count><bricks> st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data </bricks><transport>tcp</transport><type>2</type><volname>GlusterData</volname><replica-count>2</replica-count></volCreate></cliOutput> Thread-644::ERROR::2013-02-01 10:24:06,655::BindingXMLRPC::929::vds::(wrapper) vdsm exception occured Traceback (most recent call last): File "/usr/share/vdsm/BindingXMLRPC.py", line 918, in wrapper res = f(*args, **kwargs) File "/usr/share/vdsm/gluster/api.py", line 32, in wrapper rv = func(*args, **kwargs) File "/usr/share/vdsm/gluster/api.py", line 63, in volumeCreate transportList) File "/usr/share/vdsm/supervdsm.py", line 81, in __call__ return callMethod() File "/usr/share/vdsm/supervdsm.py", line 72, in <lambda> **kwargs) File "<string>", line 2, in glusterVolumeCreate File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod raise convert_to_error(kind, result) GlusterXmlErrorException: XML error error: <?xml version="1.0" encoding="UTF-8" standalone="yes"?> <cliOutput><volCreate><count>2</count><bricks> st01.nieuwland.nl:/home/gluster-data st02.nieuwland.nl:/home/gluster-data </bricks><transport>tcp</transport><type>2</type><volname>GlusterData</volname><replica-count>2</replica-count></volCreate></cliOutput> And on ST02 I get this error: MainProcess|Thread-93::DEBUG::2013-02-01 10:24:09,540::misc::84::Storage.Misc.excCmd::(<lambda>) '/usr/sbin/gluster --mode=script peer status --xml' (cwd None) MainProcess|Thread-93::DEBUG::2013-02-01 10:24:09,622::misc::84::Storage.Misc.excCmd::(<lambda>) SUCCESS: <err> = ''; <rc> = 0 Thread-93::DEBUG::2013-02-01 10:24:09,624::BindingXMLRPC::920::vds::(wrapper) return hostsList with {'status': {'message': 'Done', 'code': 0}, 'hosts': [{'status': 'CONNECTED', 'hostname': '192.168.216.152', 'uuid': '15c7a739-6735-43f5-a1c0-3c7ff3469588'}, {'status': 'CONNECTED', 'hostname': '192.168.216.151', 'uuid': 'd53f4dcb-1116-4fbc-8d5b-e882175264aa'}]} Thread-94::DEBUG::2013-02-01 10:24:09,639::BindingXMLRPC::913::vds::(wrapper) client [192.168.216.150]::call volumesList with () {} MainProcess|Thread-94::DEBUG::2013-02-01 10:24:09,641::misc::84::Storage.Misc.excCmd::(<lambda>) '/usr/sbin/gluster --mode=script volume info --xml' (cwd None) MainProcess|Thread-94::DEBUG::2013-02-01 10:24:09,724::misc::84::Storage.Misc.excCmd::(<lambda>) SUCCESS: <err> = ''; <rc> = 0 MainProcess|Thread-94::ERROR::2013-02-01 10:24:09,725::supervdsmServer::80::SuperVdsm.ServerCallback::(wrapper) Error in wrapper Traceback (most recent call last): File "/usr/share/vdsm/supervdsmServer.py", line 78, in wrapper return func(*args, **kwargs) File "/usr/share/vdsm/supervdsmServer.py", line 352, in wrapper return func(*args, **kwargs) File "/usr/share/vdsm/gluster/cli.py", line 45, in wrapper return func(*args, **kwargs) File "/usr/share/vdsm/gluster/cli.py", line 431, in volumeInfo raise ge.GlusterXmlErrorException(err=[etree.tostring(xmltree)]) GlusterXmlErrorException: XML error error: <cliOutput><opRet>0</opRet><opErrno>0</opErrno><opErrstr /><volInfo><volumes><volume><name>GlusterData</name><id>e5cf9724-ecb0-41a3-abdf-0ea891e49f92</id><type>2</type><status>0</status><brickCount>2</brickCount><distCount>2</distCount><stripeCount>1</stripeCount><replicaCount>2</replicaCount><transport>0</transport><bricks><brick>st01.nieuwland.nl:/home/gluster-data</brick><brick>st02.nieuwland.nl:/home/gluster-data</brick></bricks><optCount>0</optCount><options /></volume><count>1</count></volumes></volInfo></cliOutput> Thread-94::ERROR::2013-02-01 10:24:09,741::BindingXMLRPC::929::vds::(wrapper) vdsm exception occured Traceback (most recent call last): File "/usr/share/vdsm/BindingXMLRPC.py", line 918, in wrapper res = f(*args, **kwargs) File "/usr/share/vdsm/gluster/api.py", line 32, in wrapper rv = func(*args, **kwargs) File "/usr/share/vdsm/gluster/api.py", line 56, in volumesList return {'volumes': self.svdsmProxy.glusterVolumeInfo(volumeName)} File "/usr/share/vdsm/supervdsm.py", line 81, in __call__ return callMethod() File "/usr/share/vdsm/supervdsm.py", line 72, in <lambda> **kwargs) File "<string>", line 2, in glusterVolumeInfo File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod raise convert_to_error(kind, result) GlusterXmlErrorException: XML error I have included a lot more logs in the attached zip. Running gluster volume info shows me on both server the correct data. The directory is created under /home, SELinux was enforcing but permissive gives the same error. [root@st01 ~]# rpm -aq | grep vdsm vdsm-gluster-4.10.3-6.fc18.noarch vdsm-xmlrpc-4.10.3-6.fc18.noarch vdsm-cli-4.10.3-6.fc18.noarch vdsm-python-4.10.3-6.fc18.x86_64 vdsm-4.10.3-6.fc18.x86_64 [root@st01 ~]# rpm -aq | grep gluster glusterfs-fuse-3.3.1-8.fc18.x86_64 vdsm-gluster-4.10.3-6.fc18.noarch glusterfs-3.3.1-8.fc18.x86_64 glusterfs-server-3.3.1-8.fc18.x86_64 libvirt-0.10.2.2-3.fc18.x86_64 + deps same version qemu-kvm-1.2.2-2.fc18.x86_64 + deps same version Anything else I can provide do to fix this problem? Joop -- irc: jvandewege @fosdem ;-) Anything else?

5 9

[Users] Glusterfs HA doubts
by Adrian Gibanel 04 Feb '13

04 Feb '13

In oVirt 3.1 GlusterFS support was added. It was an easy way to replicate your virtual machine storage without too much hassle. There are two main howtos: * http://www.middleswarth.net/content/installing-ovirt-31-and-glusterfs-using… (Robert Middleswarth) * http://blog.jebpages.com/archives/ovirt-3-1-glusterized/ (Jason Brooks). 1) What about performance? I've done some tests with rsync backups (even using the suggested --inplace rsync switch) that implies small files. These backups were done into local mounted glusterfs volumes. Backups instead of lasting about 2 hours they lasted like 15 hours long. Is there maybe something that only happens with small files and with big files performance is ok? 2) How to know the current status? In DRBD you know it checking a proc file if I remember it well. I remember too that GlusterFS doesn't have an equivalent thing and there's no evident way to know if all the files are synced. If you have tried it how do you know if both sets of virtual disks images are synced? 3) Mount dns resolution If you check Jason Brooks howto you will see that it uses a hostname for refering to nfs mount. If you want to perform HA you need your storage to be mounted and if the server1 host is down it doesn't help that the nfs mount point associated to the storage is server1:/vms/ and not server2:/vms/. Checking Middleswarth howto I think that he does the same thing. Let's explain a bit more so that understand. My example setup is the one where you have two host machines where you run a set of virtual machines on one and the other one doesn't have any virtual machine running. Where is the virtual machines storage located? It's located at the glusterfs volume. So the first one of the machines mounts the glusterfs volume as nfs (It's an example). If it uses its own hostname for the nfs mount then if itself goes down the second host isn't going to mount it when it's restarted in the HA mode. So the first one of the machines mounts the glusterfs volume as nfs (It's an example). If it uses the second host hostname for the nfs mount then if the second host goes down the virtual machine cannot access its virtual disks. A workaround for this situation which I have thought is to use /etc/hosts on both machines so that: whatever.domain.com resolves in both hosts to the host self's ip. I think that glusterfs has a way of mounting their share through "-t glusterfs" that somehow can ignore these hostnames problems but I haven't read it too much about it so I'm not too sure. 4) So my doubts basically are: * Has anyone setup a two host glusterfs HA oVirt cluster where storage is shared by a replicated Glusterfs volume that is shared and stored by both of them? * Does HA work when one of the host goes down? * Or does it complain about hostname as I suspect? * Any tips to ensure the best performance? Thank you. -- -- Adrián Gibanel I.T. Manager +34 675 683 301 www.btactic.com Ens podeu seguir a/Nos podeis seguir en: i Abans d´imprimir aquest missatge, pensa en el medi ambient. El medi ambient és cosa de tothom. / Antes de imprimir el mensaje piensa en el medio ambiente. El medio ambiente es cosa de todos. AVIS: El contingut d'aquest missatge i els seus annexos és confidencial. Si no en sou el destinatari, us fem saber que està prohibit utilitzar-lo, divulgar-lo i/o copiar-lo sense tenir l'autorització corresponent. Si heu rebut aquest missatge per error, us agrairem que ho feu saber immediatament al remitent i que procediu a destruir el missatge . AVISO: El contenido de este mensaje y de sus anexos es confidencial. Si no es el destinatario, les hacemos saber que está prohibido utilizarlo, divulgarlo y/o copiarlo sin tener la autorización correspondiente. Si han recibido este mensaje por error, les agradeceríamos que lo hagan saber inmediatamente al remitente y que procedan a destruir el mensaje .

2 1

[Users] How to connect to console after another user logged out
by Gianluca Cecchi 04 Feb '13

04 Feb '13

Hello, I have 3.2 beta and a Fedra 18 VM configured with spice access. One user connects to portal and access it, then terminates his session and closes the spice console window and log out from portal. Another user connects to portal and tries to connect to the same VM that is still powered on. It receives this message: Error: F18: Console connection denied. Another user has already accessed the console of this VM. The VM should be rebooted to allow another user to access it, or changed by an admin to not enforce reboot between users accessing its console. I didn't find a way to accomplish the "or changed by an admin to not enforce reboot..." Where to set this? Thanks, Gianluca

2 1

[Users] Cannot add local storage on 3.2
by Matt . 03 Feb '13

03 Feb '13

Hi All, I have made a successfull install of 3.2 but there seems to be changed a lot about local storage comparing to 3.1 When I want to add storage on a local host there is a Datacenter and Cluster created for this Host, named by the name of the host. After this, the local storage is not available and I'm not able to add extra also. I have looked at "adding a new storage domains" and when I select my host I still need to set my path, but I did this already by configuring local storage before. The message I receive while configuring the local storage is: error cannot add storage. internal error storage connection doesn't exist What goes wrong here ? This was much easier on 3.1!

3 7

[Users] 3.2 beta and f18 host on dell R815 problem
by Gianluca Cecchi 03 Feb '13

03 Feb '13

during install of server I get this Host installation failed. Fix installation issues and try to Re-Install In deploy log 2013-01-31 12:17:30 DEBUG otopi.plugins.ovirt_host_deploy.vdsm.hardware hardware._isVirtualizationEnabled:144 virtualization support GenuineIntel (cpu: False, bios: True) 2013-01-31 12:17:30 DEBUG otopi.context context._executeMethod:127 method exception Traceback (most recent call last): File "/tmp/ovirt-SfEARpd3h4/pythonlib/otopi/context.py", line 117, in _executeMethod method['method']() File "/tmp/ovirt-SfEARpd3h4/otopi-plugins/ovirt-host-deploy/vdsm/hardware.py", line 170, in _validate_virtualization _('Hardware does not support virtualization') RuntimeError: Hardware does not support virtualization 2013-01-31 12:17:30 ERROR otopi.context context._executeMethod:136 Failed to execute stage 'Setup validation': Hardware does not support virtualization note the GenuineIntel above... ?? But actually it is AMD [root@f18ovn03 ~]# lsmod|grep kvm kvm_amd 59623 0 kvm 431794 1 kvm_amd cat /proc/cpuinfo ... processor : 47 vendor_id : AuthenticAMD cpu family : 16 model : 9 model name : AMD Opteron(tm) Processor 6174 stepping : 1 microcode : 0x10000d9 cpu MHz : 800.000 cache size : 512 KB physical id : 3 siblings : 12 core id : 5 cpu cores : 12 apicid : 59 initial apicid : 59 fpu : yes fpu_exception : yes cpuid level : 5 wp : yes flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ht syscall nx mmxext fxsr_opt pdpe1gb rdtscp lm 3dnowext 3dnow constant_tsc rep_good nopl nonstop_tsc extd_apicid amd_dcm pni monitor cx16 popcnt lahf_lm cmp_legacy svm extapic cr8_legacy abm sse4a misalignsse 3dnowprefetch osvw ibs skinit wdt nodeid_msr hw_pstate npt lbrv svm_lock nrip_save pausefilter bogomips : 4400.44 TLB size : 1024 4K pages clflush size : 64 cache_alignment : 64 address sizes : 48 bits physical, 48 bits virtual power management: ts ttp tm stc 100mhzsteps hwpstate Any hint? Gianluca

4 10

[Users] oVirt 3.2 testday
by Moran Goldboim 03 Feb '13

03 Feb '13

Thanks for all the ones participating this day. we would like to get some feedback from you after testing this beta version. how do you feel with respect to: -stability - basic flows -new features -blocker bugs (should be added to this version tracker [1]) [1] https://bugzilla.redhat.com/show_bug.cgi?id=881006 Thanks, Moran.

1 1

[Users] 3.2 beta: Amd Opteron 6174 wrongly detected as 8 socket
by Gianluca Cecchi 03 Feb '13

03 Feb '13

Hello, after deploy of a node that has 4 sockets with 12cores each, it is wrongly detected in web admin gui. See: https://docs.google.com/file/d/0BwoPbcrMv8mvdjdYNjVfT2NWY0U/edit It says 8 sockets each with 6 cores.... Output of # virsh capabilities here: https://docs.google.com/file/d/0BwoPbcrMv8mveG5OaVBZN1VENlU/edit output of cpuid here: https://docs.google.com/file/d/0BwoPbcrMv8mvUFFRYkZEX0lmRG8/edit also run this [root@f18ovn03 ~]# vdsClient -s 0 getVdsCaps HBAInventory = {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:f9baf5a8f6c3'}], 'FC': []} ISCSIInitiatorName = iqn.1994-05.com.redhat:f9baf5a8f6c3 bondings = {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}} bridges = {'ovirtmgmt': {'addr': '192.168.1.102', 'cfg': {'DOMAIN': 'localdomain.local', 'UUID': '60d40d4a-d8ab-4f5b-bd48-2e807df36be4', 'DNS3': '82.113.193.3', 'IPADDR0': '192.168.1.102', 'DNS1': '192.168.1.103', 'PREFIX0': '24', 'DEFROUTE': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'DELAY': '0', 'NM_CONTROLLED': 'no', 'BOOTPROTO': 'none', 'GATEWAY0': '192.168.1.1', 'DNS2': '8.8.8.8', 'DEVICE': 'ovirtmgmt', 'TYPE': 'Bridge', 'ONBOOT': 'yes', 'IPV6INIT': 'no'}, 'mtu': '1500', 'netmask': '255.255.255.0', 'stp': 'off', 'ports': ['em1']}} clusterLevels = ['3.0', '3.1', '3.2'] cpuCores = 48 cpuFlags = fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,mmx,fxsr,sse,sse2,ht,syscall,nx,mmxext,fxsr_opt,pdpe1gb,rdtscp,lm,3dnowext,3dnow,constant_tsc,rep_good,nopl,nonstop_tsc,extd_apicid,amd_dcm,pni,monitor,cx16,popcnt,lahf_lm,cmp_legacy,svm,extapic,cr8_legacy,abm,sse4a,misalignsse,3dnowprefetch,osvw,ibs,skinit,wdt,nodeid_msr,hw_pstate,npt,lbrv,svm_lock,nrip_save,pausefilter,model_athlon,model_Opteron_G3,model_Opteron_G1,model_phenom,model_Opteron_G2 cpuModel = AMD Opteron(tm) Processor 6174 cpuSockets = 8 cpuSpeed = 800.000 cpuThreads = 48 emulatedMachines = ['pc-1.2', 'none', 'pc', 'pc-1.1', 'pc-1.0', 'pc-0.15', 'pc-0.14', 'pc-0.13', 'pc-0.12', 'pc-0.11', 'pc-0.10', 'isapc', 'pc-1.2', 'none', 'pc', 'pc-1.1', 'pc-1.0', 'pc-0.15', 'pc-0.14', 'pc-0.13', 'pc-0.12', 'pc-0.11', 'pc-0.10', 'isapc'] guestOverhead = 65 hooks = {} kvmEnabled = true lastClient = 192.168.1.111 lastClientIface = ovirtmgmt management_ip = memSize = 64418 netConfigDirty = False networks = {'ovirtmgmt': {'iface': 'ovirtmgmt', 'addr': '192.168.1.102', 'cfg': {'DOMAIN': 'localdomain.local', 'UUID': '60d40d4a-d8ab-4f5b-bd48-2e807df36be4', 'DNS3': '82.113.193.3', 'IPADDR0': '192.168.1.102', 'DNS1': '192.168.1.103', 'PREFIX0': '24', 'DEFROUTE': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'DELAY': '0', 'NM_CONTROLLED': 'no', 'BOOTPROTO': 'none', 'GATEWAY0': '192.168.1.1', 'DNS2': '8.8.8.8', 'DEVICE': 'ovirtmgmt', 'TYPE': 'Bridge', 'ONBOOT': 'yes', 'IPV6INIT': 'no'}, 'mtu': '1500', 'netmask': '255.255.255.0', 'stp': 'off', 'bridged': True, 'gateway': '0.0.0.0', 'ports': ['em1']}} nics = {'em4': {'addr': '', 'cfg': {'PEERROUTES': 'yes', 'UUID': 'bed68125-4345-4995-ba49-a6e5580c58dd', 'NAME': 'em4', 'TYPE': 'Ethernet', 'IPV6_PEERDNS': 'yes', 'DEFROUTE': 'yes', 'PEERDNS': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'HWADDR': '00:25:64:F9:76:82', 'BOOTPROTO': 'dhcp', 'IPV6_AUTOCONF': 'yes', 'IPV6_FAILURE_FATAL': 'no', 'IPV6_PEERROUTES': 'yes', 'IPV6_DEFROUTE': 'yes', 'ONBOOT': 'yes', 'IPV6INIT': 'yes'}, 'mtu': '1500', 'netmask': '', 'hwaddr': '00:25:64:f9:76:82', 'speed': 0}, 'em1': {'addr': '', 'cfg': {'BRIDGE': 'ovirtmgmt', 'DOMAIN': 'localdomain.local', 'DEVICE': 'em1', 'UUID': '60d40d4a-d8ab-4f5b-bd48-2e807df36be4', 'DNS3': '82.113.193.3', 'IPADDR0': '192.168.1.102', 'DNS1': '192.168.1.103', 'PREFIX0': '24', 'DEFROUTE': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'NM_CONTROLLED': 'no', 'GATEWAY0': '192.168.1.1', 'DNS2': '8.8.8.8', 'HWADDR': '00:25:64:f9:76:7c', 'ONBOOT': 'yes', 'IPV6INIT': 'no'}, 'mtu': '1500', 'netmask': '', 'hwaddr': '00:25:64:f9:76:7c', 'speed': 1000}, 'em3': {'addr': '', 'cfg': {'PEERROUTES': 'yes', 'UUID': '2984885c-fbd8-4ad1-a393-00f0a205ae79', 'NAME': 'em3', 'TYPE': 'Ethernet', 'IPV6_PEERDNS': 'yes', 'DEFROUTE': 'yes', 'PEERDNS': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'HWADDR': '00:25:64:F9:76:80', 'BOOTPROTO': 'dhcp', 'IPV6_AUTOCONF': 'yes', 'IPV6_FAILURE_FATAL': 'no', 'IPV6_PEERROUTES': 'yes', 'IPV6_DEFROUTE': 'yes', 'ONBOOT': 'yes', 'IPV6INIT': 'yes'}, 'mtu': '1500', 'netmask': '', 'hwaddr': '00:25:64:f9:76:80', 'speed': 0}, 'em2': {'addr': '', 'cfg': {'PEERROUTES': 'yes', 'UUID': 'ebd889bc-57ae-4ee9-8db2-4595309ee81c', 'NAME': 'em2', 'TYPE': 'Ethernet', 'IPV6_PEERDNS': 'yes', 'DEFROUTE': 'yes', 'PEERDNS': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'HWADDR': '00:25:64:F9:76:7E', 'BOOTPROTO': 'dhcp', 'IPV6_AUTOCONF': 'yes', 'IPV6_FAILURE_FATAL': 'no', 'IPV6_PEERROUTES': 'yes', 'IPV6_DEFROUTE': 'yes', 'ONBOOT': 'yes', 'IPV6INIT': 'yes'}, 'mtu': '1500', 'netmask': '', 'hwaddr': '00:25:64:f9:76:7e', 'speed': 0}} operatingSystem = {'release': '1', 'version': '18', 'name': 'Fedora'} packages2 = {'kernel': {'release': '204.fc18.x86_64', 'buildtime': 1358955869.0, 'version': '3.7.4'}, 'spice-server': {'release': '1.fc18', 'buildtime': 1356035501, 'version': '0.12.2'}, 'vdsm': {'release': '6.fc18', 'buildtime': 1359564723, 'version': '4.10.3'}, 'qemu-kvm': {'release': '2.fc18', 'buildtime': 1358351894, 'version': '1.2.2'}, 'libvirt': {'release': '3.fc18', 'buildtime': 1355788803, 'version': '0.10.2.2'}, 'qemu-img': {'release': '2.fc18', 'buildtime': 1358351894, 'version': '1.2.2'}, 'mom': {'release': '1.fc18', 'buildtime': 1349470214, 'version': '0.3.0'}} reservedMem = 321 software_revision = 6 software_version = 4.10 supportedENGINEs = ['3.0', '3.1'] supportedProtocols = ['2.2', '2.3'] uuid = 4C4C4544-0056-5910-8047-CAC04F4E344A version_name = Snow Man vlans = {} vmTypes = ['kvm'] (this time the host is the inended one... ;-) Gianluca

2 3

[Users] Problems with upgrade to 3.3 nightly
by Gianluca Cecchi 03 Feb '13

03 Feb '13

Hello, passing from an all-in-one setup based on F18 and nightly: ovirt-engine-setup-plugin-allinone-3.2.0-1.20130125.git032a91f.fc18.noarch to the proposed new nightly I get these errors: [root@tekkaman ~]# engine-upgrade Checking for updates... (This may take several minutes)...[ DONE ] 9 Updates available: * ovirt-engine-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-backend-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-config-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-dbscripts-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-notification-service-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-restapi-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-tools-common-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-userportal-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch * ovirt-engine-webadmin-portal-3.3.0-0.1.20130201070537.20130201.git357cdaa.fc18.noarch During the upgrade process, oVirt Engine will not be accessible. All existing running virtual machines will continue but you will not be able to start or stop any new virtual machines during the process. Would you like to proceed? (yes|no): yes Stopping ovirt-engine service... [ DONE ] Stopping DB related services... [ DONE ] Starting DB related services... [ DONE ] Starting ovirt-engine service... [ DONE ] [Errno 8] Exec format error Error: Upgrade failed. please check log at /var/log/ovirt-engine/ovirt-engine-upgrade_2013_02_02_13_05_15.log Is this expected and I have to start from clean environment or should the upgrade go ok? engine-upgrade log here: https://docs.google.com/file/d/0BwoPbcrMv8mvU2ZYX3lOV2V3Tkk/edit?usp=sharing Gianluca

2 1

[Users] 3.2 beta: how to add iscsi domain to a f18 host
by Gianluca Cecchi 02 Feb '13

02 Feb '13

Hello, having a fedora 18 system configured as a node and working with a local_storage DC/CLuster, I then created an iSCSI dc and cluster and moved there the host Then I configured a CentOS server as an iSCSI sw target. But in admin portal the host section remains greyed out and I can't proceed. I suspect I have to manually install/configure some prerequisites to have the host work as iSCSI initiator.... Is it true? What I have to do on host part (or engine part) if I have not a node iso but a standard fedora 18 system as hypervisor? Here what I see in new domain box: https://docs.google.com/file/d/0BwoPbcrMv8mvX281aWlMYUlzWXc/edit?usp=sharing Thanks, Gianluca

1 1

[Users] Storage FC on ovirt 3.2 : /dev/dm* bad chown
by Kevin Maziere Aubry 01 Feb '13

01 Feb '13

Hi My environnement is Fedora18 / Ovirt 3.2 and VDSM vdsm-4.10.3-6.fc18.x86_64. I use a storage on fiber channel. When creating a VM the disk is created by ovirt. When starting the VM the disk has not the permission to be accessed by vdsm. In fact the device for the disk has root:disks as owner/group. Changing that by 36.36 permit the VM to start and work fine. But snapshots do not work, and a reboot cause udev to remap the disk to another /dev/dm-* device. My fedora18 has selinux disabled. On irc : <apuimedo> vdsm/storage/blockSD.py: cmd = [constants.EXT_CHOWN, "%s:%s" % <apuimedo> vdsm/storage/blockSD.py: self.log.error("failed to chown %s", masterDir) <apuimedo> vdsm/storage/fileUtils.py:def chown(path, user=-1, group=-1): <apuimedo> vdsm/storage/fileUtils.py: os.chown(path, uid, gid) <apuimedo> vdsm/storage/lvm.py: cmd = [constants.EXT_CHOWN, USER_GROUP, lv_path] Kevin Mazière Responsable Infrastructure Alter Way – Hosting 1 rue Royal - 227 Bureaux de la Colline 92213 Saint-Cloud Cedex Tél : +33 (0)1 41 16 38 41 Mob : +33 (0)7 62 55 57 05 http://www.alterway.fr

1 1

[Users] Fwd: Re: 3.2 beta and f18 host on dell R815 problem
by Gianluca Cecchi 01 Feb '13

01 Feb '13

Find attach Putty.log=output of cpuid command Engine.log after patching and retry. No file under host-deploy, it gives syntax error >> > ---------- Forwarded message ---------- >> > From: Alon Bar-Lev <alonbl(a)redhat.com> >> > Date: Thu, Jan 31, 2013 at 1:48 PM >> > Subject: Re: [Users] 3.2 beta and f18 host on dell R815 problem >> > To: Gianluca Cecchi <gianluca.cecchi(a)gmail.com> >> > >> > >> > Sorry, had error. >> > >> > ----- Original Message ----- >> >> From: "Alon Bar-Lev" <alonbl(a)redhat.com> >> >> To: "Gianluca Cecchi" <gianluca.cecchi(a)gmail.com> >> >> Sent: Thursday, January 31, 2013 2:40:55 PM >> >> Subject: Re: [Users] 3.2 beta and f18 host on dell R815 problem >> >> >> >> Hi! >> >> >> >> Can you please try to replace the attach file at: >> >> /usr/share/ovirt-host-deploy/plugins/ovirt-host-deploy/vdsm/hardware.py >> >> >> >> Retry and send me the log? >> >> >> >> I added some more debug to see what went wrong. >> >> >> >> Thanks! >> >> Alon >> >> >> >> >> >> ----- Original Message ----- >> >> > From: "Gianluca Cecchi" <gianluca.cecchi(a)gmail.com> >> >> > To: "users" <users(a)ovirt.org> >> >> > Sent: Thursday, January 31, 2013 1:57:38 PM >> >> > Subject: Re: [Users] 3.2 beta and f18 host on dell R815 problem >> >> > >> >> > Output of command >> >> > # virsh capabilities >> >> > on this host >> >> > >> >> > https://docs.google.com/file/d/0BwoPbcrMv8mveG5OaVBZN1VENlU/edit >> >> > _______________________________________________ >> >> > Users mailing list >> >> > Users(a)ovirt.org >> >> > http://lists.ovirt.org/mailman/listinfo/users >> >> > >> >> > >

2 4

[Users] latest vdsm cannot read ib device speeds causing storage attach fail
by Dead Horse 01 Feb '13

01 Feb '13

Any ideas on this one? (from VDSM log): Thread-25::DEBUG::2013-01-22 15:35:29,065::BindingXMLRPC::914::vds::(wrapper) client [3.57.111.30]::call getCapabilities with () {} Thread-25::ERROR::2013-01-22 15:35:29,113::netinfo::159::root::(speed) cannot read ib0 speed Traceback (most recent call last): File "/usr/lib64/python2.6/site-packages/vdsm/netinfo.py", line 155, in speed s = int(file('/sys/class/net/%s/speed' % dev).read()) IOError: [Errno 22] Invalid argument Causes VDSM to fail to attach storage Engine side sees: ERROR [org.ovirt.engine.core.bll.storage.NFSStorageHelper] (QuartzScheduler_Worker-96) [553ef26e] The connection with details 192.168.0.1:/ovirt/ds failed because of error code 100 and error message is: general exception 2013-01-22 15:35:30,160 INFO [org.ovirt.engine.core.bll.SetNonOperationalVdsCommand] (QuartzScheduler_Worker-96) [1ab78378] Running command: SetNonOperationalVdsCommand internal: true. Entities affected : ID: 8970b3fe-1faf-11e2-bc1f-00151712f280 Type: VDS 2013-01-22 15:35:30,200 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (QuartzScheduler_Worker-96) [1ab78378] START, SetVdsStatusVDSCommand(HostName = kezan, HostId = 8970b3fe-1faf-11e2-bc1f-00151712f280, status=NonOperational, nonOperationalReason=STORAGE_DOMAIN_UNREACHABLE), log id: 4af5c4cd 2013-01-22 15:35:30,211 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (QuartzScheduler_Worker-96) [1ab78378] FINISH, SetVdsStatusVDSCommand, log id: 4af5c4cd 2013-01-22 15:35:30,242 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-96) [1ab78378] Try to add duplicate audit log values with the same name. Type: VDS_SET_NONOPERATIONAL_DOMAIN. Value: storagepoolname Engine = latest master VDSM = latest master node = el6

6 17

[Users] Syntax error hardware.py
by Gianluca Cecchi 31 Jan '13

31 Jan '13

See attached images... >

1 0

[Users] oVirt 3.2 Beta Availability
by Mike Burns 31 Jan '13

31 Jan '13

The beta versions of oVirt 3.2 are now available. Packages can be installed using the ovirt-beta yum repository [1] after installing the ovirt-release package[1]. oVirt Node is not currently available. It should be made available in the next couple days. Thanks The oVirt Team [1] http://resources.ovirt.org/releases/beta/ [2] http://resources.ovirt.org/releases/ovirt-release-fedora.noarch.rpm

3 4

[Users] Choosing which network to run VM migration on ovirt 3.1
by Yuval M 31 Jan '13

31 Jan '13

Hi, I'm running the following setup: 2 hosts, each has 2 physical NICs, the first NIC (which is bridged to ovirtmgmt) is a 100Mbps ethernet card connected to a switch (and to the internet) the 2nd NIC is a fast Infiniband card which is connected back-to-back to the other host. both links are running fine, and I managed to have the 2nd host mount the storage via the fast link. The problem is that VM migration takes place over the slow link. How do I configure the cluster so that the migration uses the fast link? I've already created a network using the web interface. the migration still uses the slow link. Thanks, Yuval

4 9

[Users] [Reminder] oVirt 3.2 test-day is today!!
by Moran Goldboim 31 Jan '13

31 Jan '13

Today are having oVirt 3.2 testday - http://www.ovirt.org/Testing/OvirtTestDay you are welcome to take an active part on testing oVirt 3.2 new features[1], look around and get a deeper experience with this oVirt release. [1] http://www.ovirt.org/OVirt_3.2_release-management#Features Moran.

1 0

[Users] Cannot create vm's from user portal
by Dead Horse 30 Jan '13

30 Jan '13

Seeing this error in engine log when attempting to create a new VM from the userportal: 2013-01-28 15:35:47,158 ERROR [org.ovirt.engine.core.bll.GetClustersWithPermittedActionQuery] (ajp--127.0.0.1-8702-5) Query GetClustersWithPermittedActionQuery failed. Exception message is PreparedStatementCallback; bad SQL grammar [select * from fn_perms_get_vds_groups_with_permitted_action(?, ?)]; nested exception is org.postgresql.util.PSQLException: ERROR: missing FROM-clause entry for table "vds_groups" Where: PL/pgSQL function "fn_perms_get_vds_groups_with_permitted_action" line 3 at RETURN QUERY 2013-01-28 15:36:16,726 ERROR [org.ovirt.engine.core.bll.GetClustersWithPermittedActionQuery] (ajp--127.0.0.1-8702-7) Query GetClustersWithPermittedActionQuery failed. Exception message is PreparedStatementCallback; bad SQL grammar [select * from fn_perms_get_vds_groups_with_permitted_action(?, ?)]; nested exception is org.postgresql.util.PSQLException: ERROR: missing FROM-clause entry for table "vds_groups" Where: PL/pgSQL function "fn_perms_get_vds_groups_with_permitted_action" line 3 at RETURN QUERY Current running engine build --> commit: 61c11aecc40e755d08b6c34c6fe1c0a07fa94de8 - DHC

2 3

[Users] oVirt 3.1 VM is not responding
by José Ferradeira 30 Jan '13

30 Jan '13

------=_Part_476_18436217.1359564233863 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable Hi,=20 I'm testing oVirt 3.1, with one node 2.5.5-0.1fc17 and a NAS built with ope= nfiler 2.99, working on a Gigabit net.=20 I configured a NFS share on openfiler, and everything looks nice.=20 I'm installing a Windows 2008 R2 as a VM, time to time i get a warning mess= age: ! VM is not responding, but the windows installation keeps going, a bi= t slow.=20 Any idea what is happening?=20 Thanks=20 Jose=20 the vdsm.log :=20 Thread-2405::DEBUG::2013-01-30 16:42:21,532::resourceManager::212::Resource= Manager.Request::(grant) ResName=3D`Storage.7b44684c-5f34-11e2-beba-00138fb= e3093`ReqID=3D`e9aefcf3-b4ed-4d7c-9d95-723f824c30ee`::Granted request=20 Thread-2405::DEBUG::2013-01-30 16:42:21,532::task::817::TaskManager.Task::(= resourceAcquired) Task=3D`c581aa06-4e50-41dd-a836-756827865cc6`::_resources= Acquired: Storage.7b44684c-5f34-11e2-beba-00138fbe3093 (shared)=20 Thread-2405::DEBUG::2013-01-30 16:42:21,532::task::978::TaskManager.Task::(= _decref) Task=3D`c581aa06-4e50-41dd-a836-756827865cc6`::ref 1 aborting Fals= e=20 Thread-2405::INFO::2013-01-30 16:42:21,664::logUtils::39::dispatcher::(wrap= per) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_i= d': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'aclo= udDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Act= ive,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f1= 35b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-cen= ter/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f08= aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver= ': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {= 'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': '= 23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active',= 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, 'c= c060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '313= 802031104', 'alerts': [], 'disktotal': '982927736832'}}}=20 Thread-2405::DEBUG::2013-01-30 16:42:21,664::task::1172::TaskManager.Task::= (prepare) Task=3D`c581aa06-4e50-41dd-a836-756827865cc6`::finished: {'info':= {'spm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name= ': 'acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb025= 31ad:Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-= a66c-2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/= data-center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20= aab92f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'ma= ster_ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f= 08aa': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'diskt= otal': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': '= Active', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '4766763581= 44'}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfre= e': '313802031104', 'alerts': [], 'disktotal': '982927736832'}}}=20 Thread-2405::DEBUG::2013-01-30 16:42:21,664::task::588::TaskManager.Task::(= _updateState) Task=3D`c581aa06-4e50-41dd-a836-756827865cc6`::moving from st= ate preparing -> state finished=20 Thread-2405::DEBUG::2013-01-30 16:42:21,664::resourceManager::809::Resource= Manager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storag= e.7b44684c-5f34-11e2-beba-00138fbe3093': < ResourceRef 'Storage.7b44684c-5f= 34-11e2-beba-00138fbe3093', isValid: 'True' obj: 'None'>}=20 Thread-2405::DEBUG::2013-01-30 16:42:21,664::resourceManager::844::Resource= Manager.Owner::(cancelAll) Owner.cancelAll requests {}=20 Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::538::Resource= Manager::(releaseResource) Trying to release resource 'Storage.7b44684c-5f3= 4-11e2-beba-00138fbe3093'=20 Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::553::Resource= Manager::(releaseResource) Released resource 'Storage.7b44684c-5f34-11e2-be= ba-00138fbe3093' (0 active users)=20 Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::558::Resource= Manager::(releaseResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138f= be3093' is free, finding out if anyone is waiting for it.=20 Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::565::Resource= Manager::(releaseResource) No one is waiting for resource 'Storage.7b44684c= -5f34-11e2-beba-00138fbe3093', Clearing records.=20 Thread-2405::DEBUG::2013-01-30 16:42:21,665::task::978::TaskManager.Task::(= _decref) Task=3D`c581aa06-4e50-41dd-a836-756827865cc6`::ref 0 aborting Fals= e=20 Thread-2406::DEBUG::2013-01-30 16:42:23,304::task::588::TaskManager.Task::(= _updateState) Task=3D`8926a671-3076-42e5-8521-f8ec6ba67748`::moving from st= ate init -> state preparing=20 Thread-2406::INFO::2013-01-30 16:42:23,304::logUtils::37::dispatcher::(wrap= per) Run and protect: repoStats(options=3DNone)=20 Thread-2406::INFO::2013-01-30 16:42:23,304::logUtils::39::dispatcher::(wrap= per) Run and protect: repoStats, Return response: {'39484795-666f-44b3-9cf5= -21bbb02531ad': {'delay': '0.00179004669189', 'lastCheck': 1359564135.40165= , 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'dela= y': '0.00332403182983', 'lastCheck': 1359564141.220177, 'code': 0, 'valid':= True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': '0.00157809257507= ', 'lastCheck': 1359564143.248153, 'code': 0, 'valid': True}}=20 Thread-2406::DEBUG::2013-01-30 16:42:23,305::task::1172::TaskManager.Task::= (prepare) Task=3D`8926a671-3076-42e5-8521-f8ec6ba67748`::finished: {'394847= 95-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.00179004669189', 'lastCheck':= 1359564135.40165, 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aa= b92f08aa': {'delay': '0.00332403182983', 'lastCheck': 1359564141.220177, 'c= ode': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': = '0.00157809257507', 'lastCheck': 1359564143.248153, 'code': 0, 'valid': Tru= e}}=20 Thread-2406::DEBUG::2013-01-30 16:42:23,305::task::588::TaskManager.Task::(= _updateState) Task=3D`8926a671-3076-42e5-8521-f8ec6ba67748`::moving from st= ate preparing -> state finished=20 Thread-2406::DEBUG::2013-01-30 16:42:23,305::resourceManager::809::Resource= Manager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}=20 Thread-2406::DEBUG::2013-01-30 16:42:23,305::resourceManager::844::Resource= Manager.Owner::(cancelAll) Owner.cancelAll requests {}=20 Thread-2406::DEBUG::2013-01-30 16:42:23,305::task::978::TaskManager.Task::(= _decref) Task=3D`8926a671-3076-42e5-8521-f8ec6ba67748`::ref 0 aborting Fals= e=20 Thread-2407::DEBUG::2013-01-30 16:42:23,368::libvirtvm::240::vm.Vm::(_getDi= skStats) vmId=3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk hdc stats not = available=20 Thread-2407::DEBUG::2013-01-30 16:42:23,368::libvirtvm::240::vm.Vm::(_getDi= skStats) vmId=3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk fda stats not = available=20 VM Channels Listener::DEBUG::2013-01-30 16:42:27,492::vmChannels::60::vds::= (_handle_timeouts) Timeout on fileno 18.=20 Thread-788::DEBUG::2013-01-30 16:42:28,907::task::588::TaskManager.Task::(_= updateState) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::moving from sta= te init -> state preparing=20 Thread-788::INFO::2013-01-30 16:42:28,907::logUtils::37::dispatcher::(wrapp= er) Run and protect: getVolumeSize(sdUUID=3D'39484795-666f-44b3-9cf5-21bbb0= 2531ad', spUUID=3D'7b44684c-5f34-11e2-beba-00138fbe3093', imgUUID=3D'2ff4c7= 43-6e5a-42af-8b99-131c8849bd3f', volUUID=3D'7304b24f-5c4d-41b3-b12f-b916bc9= f4299', options=3DNone)=20 Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::175::ResourceM= anager.Request::(__init__) ResName=3D`Storage.39484795-666f-44b3-9cf5-21bbb= 02531ad`ReqID=3D`9ba33a25-cdd2-4fc0-9f50-55a82178ad48`::Request was made in= '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResour= ce'=20 Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::486::ResourceM= anager::(registerResource) Trying to register resource 'Storage.39484795-66= 6f-44b3-9cf5-21bbb02531ad' for lock type 'shared'=20 Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::528::ResourceM= anager::(registerResource) Resource 'Storage.39484795-666f-44b3-9cf5-21bbb0= 2531ad' is free. Now locking as 'shared' (1 active user)=20 Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::212::ResourceM= anager.Request::(grant) ResName=3D`Storage.39484795-666f-44b3-9cf5-21bbb025= 31ad`ReqID=3D`9ba33a25-cdd2-4fc0-9f50-55a82178ad48`::Granted request=20 Thread-788::DEBUG::2013-01-30 16:42:28,908::task::817::TaskManager.Task::(r= esourceAcquired) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::_resourcesA= cquired: Storage.39484795-666f-44b3-9cf5-21bbb02531ad (shared)=20 Thread-788::DEBUG::2013-01-30 16:42:28,909::task::978::TaskManager.Task::(_= decref) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::ref 1 aborting False= =20 Thread-788::DEBUG::2013-01-30 16:42:28,911::fileVolume::535::Storage.Volume= ::(validateVolumePath) validate path for 7304b24f-5c4d-41b3-b12f-b916bc9f42= 99=20 Thread-788::DEBUG::2013-01-30 16:42:28,913::fileVolume::535::Storage.Volume= ::(validateVolumePath) validate path for 7304b24f-5c4d-41b3-b12f-b916bc9f42= 99=20 Thread-788::INFO::2013-01-30 16:42:28,914::logUtils::39::dispatcher::(wrapp= er) Run and protect: getVolumeSize, Return response: {'truesize': '32562257= 92', 'apparentsize': '107374182400'}=20 Thread-788::DEBUG::2013-01-30 16:42:28,914::task::1172::TaskManager.Task::(= prepare) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::finished: {'truesiz= e': '3256225792', 'apparentsize': '107374182400'}=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::task::588::TaskManager.Task::(_= updateState) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::moving from sta= te preparing -> state finished=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::809::ResourceM= anager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage= .39484795-666f-44b3-9cf5-21bbb02531ad': < ResourceRef 'Storage.39484795-666= f-44b3-9cf5-21bbb02531ad', isValid: 'True' obj: 'None'>}=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::844::ResourceM= anager.Owner::(cancelAll) Owner.cancelAll requests {}=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::538::ResourceM= anager::(releaseResource) Trying to release resource 'Storage.39484795-666f= -44b3-9cf5-21bbb02531ad'=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::553::ResourceM= anager::(releaseResource) Released resource 'Storage.39484795-666f-44b3-9cf= 5-21bbb02531ad' (0 active users)=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::558::ResourceM= anager::(releaseResource) Resource 'Storage.39484795-666f-44b3-9cf5-21bbb02= 531ad' is free, finding out if anyone is waiting for it.=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::565::ResourceM= anager::(releaseResource) No one is waiting for resource 'Storage.39484795-= 666f-44b3-9cf5-21bbb02531ad', Clearing records.=20 Thread-788::DEBUG::2013-01-30 16:42:28,915::task::978::TaskManager.Task::(_= decref) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::ref 0 aborting False= =20 VM Channels Listener::DEBUG::2013-01-30 16:42:29,494::vmChannels::60::vds::= (_handle_timeouts) Timeout on fileno 322.=20 Thread-2412::DEBUG::2013-01-30 16:42:31,843::BindingXMLRPC::156::vds::(wrap= per) [192.168.5.180]=20 Thread-2412::DEBUG::2013-01-30 16:42:31,843::task::588::TaskManager.Task::(= _updateState) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`::moving from st= ate init -> state preparing=20 Thread-2412::INFO::2013-01-30 16:42:31,844::logUtils::37::dispatcher::(wrap= per) Run and protect: getSpmStatus(spUUID=3D'7b44684c-5f34-11e2-beba-00138f= be3093', options=3DNone)=20 Thread-2412::INFO::2013-01-30 16:42:31,844::logUtils::39::dispatcher::(wrap= per) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 1= , 'spmStatus': 'SPM', 'spmLver': 4}}=20 Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::1172::TaskManager.Task::= (prepare) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`::finished: {'spm_st= ': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4}}=20 Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::588::TaskManager.Task::(= _updateState) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`::moving from st= ate preparing -> state finished=20 Thread-2412::DEBUG::2013-01-30 16:42:31,844::resourceManager::809::Resource= Manager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}=20 Thread-2412::DEBUG::2013-01-30 16:42:31,844::resourceManager::844::Resource= Manager.Owner::(cancelAll) Owner.cancelAll requests {}=20 Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::978::TaskManager.Task::(= _decref) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`::ref 0 aborting Fals= e=20 Thread-2413::DEBUG::2013-01-30 16:42:31,936::BindingXMLRPC::156::vds::(wrap= per) [192.168.5.180]=20 Thread-2413::DEBUG::2013-01-30 16:42:31,937::task::588::TaskManager.Task::(= _updateState) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::moving from st= ate init -> state preparing=20 Thread-2413::INFO::2013-01-30 16:42:31,937::logUtils::37::dispatcher::(wrap= per) Run and protect: getStoragePoolInfo(spUUID=3D'7b44684c-5f34-11e2-beba-= 00138fbe3093', options=3DNone)=20 Thread-2413::DEBUG::2013-01-30 16:42:31,937::resourceManager::175::Resource= Manager.Request::(__init__) ResName=3D`Storage.7b44684c-5f34-11e2-beba-0013= 8fbe3093`ReqID=3D`e72c6606-db58-41d0-8072-c4fff9e0e417`::Request was made i= n '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResou= rce'=20 Thread-2413::DEBUG::2013-01-30 16:42:31,937::resourceManager::486::Resource= Manager::(registerResource) Trying to register resource 'Storage.7b44684c-5= f34-11e2-beba-00138fbe3093' for lock type 'shared'=20 Thread-2413::DEBUG::2013-01-30 16:42:31,937::resourceManager::528::Resource= Manager::(registerResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138= fbe3093' is free. Now locking as 'shared' (1 active user)=20 Thread-2413::DEBUG::2013-01-30 16:42:31,938::resourceManager::212::Resource= Manager.Request::(grant) ResName=3D`Storage.7b44684c-5f34-11e2-beba-00138fb= e3093`ReqID=3D`e72c6606-db58-41d0-8072-c4fff9e0e417`::Granted request=20 Thread-2413::DEBUG::2013-01-30 16:42:31,939::task::817::TaskManager.Task::(= resourceAcquired) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::_resources= Acquired: Storage.7b44684c-5f34-11e2-beba-00138fbe3093 (shared)=20 Thread-2413::DEBUG::2013-01-30 16:42:31,939::task::978::TaskManager.Task::(= _decref) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::ref 1 aborting Fals= e=20 Thread-2413::INFO::2013-01-30 16:42:32,073::logUtils::39::dispatcher::(wrap= per) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_i= d': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'aclo= udDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Act= ive,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f1= 35b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-cen= ter/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f08= aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver= ': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {= 'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': '= 23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active',= 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, 'c= c060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '313= 801244672', 'alerts': [], 'disktotal': '982927736832'}}}=20 Thread-2413::DEBUG::2013-01-30 16:42:32,073::task::1172::TaskManager.Task::= (prepare) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::finished: {'info':= {'spm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name= ': 'acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb025= 31ad:Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-= a66c-2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/= data-center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20= aab92f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'ma= ster_ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f= 08aa': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'diskt= otal': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': '= Active', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '4766763581= 44'}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfre= e': '313801244672', 'alerts': [], 'disktotal': '982927736832'}}}=20 Thread-2413::DEBUG::2013-01-30 16:42:32,073::task::588::TaskManager.Task::(= _updateState) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::moving from st= ate preparing -> state finished=20 Thread-2413::DEBUG::2013-01-30 16:42:32,073::resourceManager::809::Resource= Manager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storag= e.7b44684c-5f34-11e2-beba-00138fbe3093': < ResourceRef 'Storage.7b44684c-5f= 34-11e2-beba-00138fbe3093', isValid: 'True' obj: 'None'>}=20 Thread-2413::DEBUG::2013-01-30 16:42:32,073::resourceManager::844::Resource= Manager.Owner::(cancelAll) Owner.cancelAll requests {}=20 Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::538::Resource= Manager::(releaseResource) Trying to release resource 'Storage.7b44684c-5f3= 4-11e2-beba-00138fbe3093'=20 Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::553::Resource= Manager::(releaseResource) Released resource 'Storage.7b44684c-5f34-11e2-be= ba-00138fbe3093' (0 active users)=20 Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::558::Resource= Manager::(releaseResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138f= be3093' is free, finding out if anyone is waiting for it.=20 Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::565::Resource= Manager::(releaseResource) No one is waiting for resource 'Storage.7b44684c= -5f34-11e2-beba-00138fbe3093', Clearing records.=20 Thread-2413::DEBUG::2013-01-30 16:42:32,075::task::978::TaskManager.Task::(= _decref) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::ref 0 aborting Fals= e=20 Thread-2414::DEBUG::2013-01-30 16:42:33,894::task::588::TaskManager.Task::(= _updateState) Task=3D`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::moving from st= ate init -> state preparing=20 Thread-2414::INFO::2013-01-30 16:42:33,894::logUtils::37::dispatcher::(wrap= per) Run and protect: repoStats(options=3DNone)=20 Thread-2414::INFO::2013-01-30 16:42:33,894::logUtils::39::dispatcher::(wrap= per) Run and protect: repoStats, Return response: {'39484795-666f-44b3-9cf5= -21bbb02531ad': {'delay': '0.00196099281311', 'lastCheck': 1359564145.53994= , 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'dela= y': '0.00284719467163', 'lastCheck': 1359564151.359623, 'code': 0, 'valid':= True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': '0.0015709400177'= , 'lastCheck': 1359564153.38349, 'code': 0, 'valid': True}}=20 Thread-2414::DEBUG::2013-01-30 16:42:33,895::task::1172::TaskManager.Task::= (prepare) Task=3D`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::finished: {'394847= 95-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.00196099281311', 'lastCheck':= 1359564145.53994, 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aa= b92f08aa': {'delay': '0.00284719467163', 'lastCheck': 1359564151.359623, 'c= ode': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': = '0.0015709400177', 'lastCheck': 1359564153.38349, 'code': 0, 'valid': True}= }=20 Thread-2414::DEBUG::2013-01-30 16:42:33,895::task::588::TaskManager.Task::(= _updateState) Task=3D`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::moving from st= ate preparing -> state finished=20 Thread-2414::DEBUG::2013-01-30 16:42:33,895::resourceManager::809::Resource= Manager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}=20 Thread-2414::DEBUG::2013-01-30 16:42:33,895::resourceManager::844::Resource= Manager.Owner::(cancelAll) Owner.cancelAll requests {}=20 Thread-2414::DEBUG::2013-01-30 16:42:33,895::task::978::TaskManager.Task::(= _decref) Task=3D`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::ref 0 aborting Fals= e=20 Thread-2415::DEBUG::2013-01-30 16:42:33,996::libvirtvm::240::vm.Vm::(_getDi= skStats) vmId=3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk hdc stats not = available=20 Thread-2415::DEBUG::2013-01-30 16:42:33,997::libvirtvm::240::vm.Vm::(_getDi= skStats) vmId=3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk fda stats not = available=20 Thread-424::DEBUG::2013-01-30 16:42:34,508::task::588::TaskManager.Task::(_= updateState) Task=3D`413cbd4e-2ddc-4a21-9526-39e970dc48d3`::moving from sta= te init -> state preparing=20 Thread-424::INFO::2013-01-30 16:42:34,509::logUtils::37::dispatcher::(wrapp= er) Run and protect: getVolumeSize(sdUUID=3D'39484795-666f-44b3-9cf5-21bbb0= 2531ad', spUUID=3D'7b44684c-5f34-11e2-beba-00138fbe3093', imgUUID=3D'c7524c= bd-5f92-41ba-a9e9-8724dd2c4c11', volUUID=3D'5904f9d7-09c3-404d-8887-71a32ff= 96735', options=3DNone)=20 Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::175::ResourceM= anager.Request::(__init__) ResName=3D`Storage.39484795-666f-44b3-9cf5-21bbb= 02531ad`ReqID=3D`e4e2d280-6941-469f-be31-ac06bfb73f99`::Request was made in= '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResour= ce'=20 Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::486::ResourceM= anager::(registerResource) Trying to register resource 'Storage.39484795-66= 6f-44b3-9cf5-21bbb02531ad' for lock type 'shared'=20 Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::528::ResourceM= anager::(registerResource) Resource 'Storage.39484795-666f-44b3-9cf5-21bbb0= 2531ad' is free. Now locking as 'shared' (1 active user)=20 Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::212::ResourceM= anager.Request::(grant) ResName=3D`Storage.39484795-666f-44b3-9cf5-21bbb025= 31ad`ReqID=3D`e4e2d280-6941-469f-be31-ac06bfb73f99`::Granted request=20 Thread-424::DEBUG::2013-01-30 16:42:34,509::task::817::TaskManager.Task::(r= esourceAcquired) Task=3D`413cbd4e-2ddc-4a21-9526-39e970dc48d3`::_resourcesA= cquired: Storage.39484795-666f-44b3-9cf5-21bbb02531ad (shared)=20 Thread-424::DEBUG::2013-01-30 16:42:34,510::task::978::TaskManager.Task::(_= decref) Task=3D`413cbd4e-2ddc-4a21-9526-39e970dc48d3`::ref 1 aborting False= =20 Thread-424::DEBUG::2013-01-30 16:42:34,511::fileVolume::535::Storage.Volume= ::(validateVolumePath) validate path for 5904f9d7-09c3-404d-8887-71a32ff967= 35=20 ---------------------------------------------=20 Logicworks Tecnologias de Inform=C3=A1tica=20 http://www.logicworks.pt=20 ------=_Part_476_18436217.1359564233863 Content-Type: text/html; charset=utf-8 Content-Transfer-Encoding: quoted-printable <html><head><style type=3D'text/css'>p { margin: 0; }</style></head><body><= div style=3D'font-family: verdana,helvetica,sans-serif; font-size: 10pt; co= lor: #330066'>Hi, I'm testing oVirt 3.1, with one node 2.5.5-0.1fc17= and a NAS built with openfiler 2.99, working on a Gigabit net. I config= ured a NFS share on openfiler, and everything looks nice. I'm installing= a Windows 2008 R2 as a VM, time to time i get a warning message: ! VM is n= ot responding, but the windows installation keeps going, a bit slow. <br= >Any idea what is happening? Thanks Jose the vdsm.log = : Thread-2405::DEBUG::2013-01-30 16:42:21,532::resourceManager::212:= :ResourceManager.Request::(grant) ResName=3D`Storage.7b44684c-5f34-11e2-beb= a-00138fbe3093`ReqID=3D`e9aefcf3-b4ed-4d7c-9d95-723f824c30ee`::Granted requ= est Thread-2405::DEBUG::2013-01-30 16:42:21,532::task::817::TaskManager.= Task::(resourceAcquired) Task=3D`c581aa06-4e50-41dd-a836-756827865cc6`::_re= sourcesAcquired: Storage.7b44684c-5f34-11e2-beba-00138fbe3093 (shared) T= hread-2405::DEBUG::2013-01-30 16:42:21,532::task::978::TaskManager.Task::(_= decref) Task=3D`c581aa06-4e50-41dd-a836-756827865cc6`::ref 1 aborting False= Thread-2405::INFO::2013-01-30 16:42:21,664::logUtils::39::dispatcher::(= wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': {'s= pm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': '= acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad= :Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c= -2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data= -center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab9= 2f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master= _ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa= ': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal= ': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Acti= ve', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}= , 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': = '313802031104', 'alerts': [], 'disktotal': '982927736832'}}} Thread-2405= ::DEBUG::2013-01-30 16:42:21,664::task::1172::TaskManager.Task::(prepare) T= ask=3D`c581aa06-4e50-41dd-a836-756827865cc6`::finished: {'info': {'spm_id':= 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'acloudD= C', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Active= ,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f135b= 8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center= /7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f08aa/= images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver': = 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'st= atus': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': '236= 48534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active', 'd= iskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, 'cc06= 0953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '313802= 031104', 'alerts': [], 'disktotal': '982927736832'}}} Thread-2405::DEBUG= ::2013-01-30 16:42:21,664::task::588::TaskManager.Task::(_updateState) Task= =3D`c581aa06-4e50-41dd-a836-756827865cc6`::moving from state preparing -&gt= ; state finished Thread-2405::DEBUG::2013-01-30 16:42:21,664::resourceMa= nager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {= } resources {'Storage.7b44684c-5f34-11e2-beba-00138fbe3093': < ResourceR= ef 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093', isValid: 'True' obj: 'No= ne'>} Thread-2405::DEBUG::2013-01-30 16:42:21,664::resourceManager::8= 44::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Threa= d-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::538::ResourceManag= er::(releaseResource) Trying to release resource 'Storage.7b44684c-5f34-11e= 2-beba-00138fbe3093' Thread-2405::DEBUG::2013-01-30 16:42:21,665::resour= ceManager::553::ResourceManager::(releaseResource) Released resource 'Stora= ge.7b44684c-5f34-11e2-beba-00138fbe3093' (0 active users) Thread-2405::D= EBUG::2013-01-30 16:42:21,665::resourceManager::558::ResourceManager::(rele= aseResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' is fre= e, finding out if anyone is waiting for it. Thread-2405::DEBUG::2013-01-= 30 16:42:21,665::resourceManager::565::ResourceManager::(releaseResource) N= o one is waiting for resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093= ', Clearing records. Thread-2405::DEBUG::2013-01-30 16:42:21,665::task::= 978::TaskManager.Task::(_decref) Task=3D`c581aa06-4e50-41dd-a836-756827865c= c6`::ref 0 aborting False Thread-2406::DEBUG::2013-01-30 16:42:23,304::t= ask::588::TaskManager.Task::(_updateState) Task=3D`8926a671-3076-42e5-8521-= f8ec6ba67748`::moving from state init -> state preparing Thread-2406:= :INFO::2013-01-30 16:42:23,304::logUtils::37::dispatcher::(wrapper) Run and= protect: repoStats(options=3DNone) Thread-2406::INFO::2013-01-30 16:42:= 23,304::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Ret= urn response: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.0017900= 4669189', 'lastCheck': 1359564135.40165, 'code': 0, 'valid': True}, '0b6a87= e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00332403182983', 'lastCheck':= 1359564141.220177, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c-2f1= 35b8fbb30': {'delay': '0.00157809257507', 'lastCheck': 1359564143.248153, '= code': 0, 'valid': True}} Thread-2406::DEBUG::2013-01-30 16:42:23,305::t= ask::1172::TaskManager.Task::(prepare) Task=3D`8926a671-3076-42e5-8521-f8ec= 6ba67748`::finished: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.= 00179004669189', 'lastCheck': 1359564135.40165, 'code': 0, 'valid': True}, = '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00332403182983', 'last= Check': 1359564141.220177, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86-a= 66c-2f135b8fbb30': {'delay': '0.00157809257507', 'lastCheck': 1359564143.24= 8153, 'code': 0, 'valid': True}} Thread-2406::DEBUG::2013-01-30 16:42:23= ,305::task::588::TaskManager.Task::(_updateState) Task=3D`8926a671-3076-42e= 5-8521-f8ec6ba67748`::moving from state preparing -> state finished T= hread-2406::DEBUG::2013-01-30 16:42:23,305::resourceManager::809::ResourceM= anager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} Thr= ead-2406::DEBUG::2013-01-30 16:42:23,305::resourceManager::844::ResourceMan= ager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-2406::DEBUG::= 2013-01-30 16:42:23,305::task::978::TaskManager.Task::(_decref) Task=3D`892= 6a671-3076-42e5-8521-f8ec6ba67748`::ref 0 aborting False Thread-2407::DE= BUG::2013-01-30 16:42:23,368::libvirtvm::240::vm.Vm::(_getDiskStats) vmId= =3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk hdc stats not available = Thread-2407::DEBUG::2013-01-30 16:42:23,368::libvirtvm::240::vm.Vm::(_getDi= skStats) vmId=3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk fda stats not = available VM Channels Listener::DEBUG::2013-01-30 16:42:27,492::vmChanne= ls::60::vds::(_handle_timeouts) Timeout on fileno 18. Thread-788::DEBUG:= :2013-01-30 16:42:28,907::task::588::TaskManager.Task::(_updateState) Task= =3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::moving from state init -> sta= te preparing Thread-788::INFO::2013-01-30 16:42:28,907::logUtils::37::di= spatcher::(wrapper) Run and protect: getVolumeSize(sdUUID=3D'39484795-666f-= 44b3-9cf5-21bbb02531ad', spUUID=3D'7b44684c-5f34-11e2-beba-00138fbe3093', i= mgUUID=3D'2ff4c743-6e5a-42af-8b99-131c8849bd3f', volUUID=3D'7304b24f-5c4d-4= 1b3-b12f-b916bc9f4299', options=3DNone) Thread-788::DEBUG::2013-01-30 16= :42:28,908::resourceManager::175::ResourceManager.Request::(__init__) ResNa= me=3D`Storage.39484795-666f-44b3-9cf5-21bbb02531ad`ReqID=3D`9ba33a25-cdd2-4= fc0-9f50-55a82178ad48`::Request was made in '/usr/share/vdsm/storage/resour= ceManager.py' line '485' at 'registerResource' Thread-788::DEBUG::2013-0= 1-30 16:42:28,908::resourceManager::486::ResourceManager::(registerResource= ) Trying to register resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad= ' for lock type 'shared' Thread-788::DEBUG::2013-01-30 16:42:28,908::res= ourceManager::528::ResourceManager::(registerResource) Resource 'Storage.39= 484795-666f-44b3-9cf5-21bbb02531ad' is free. Now locking as 'shared' (1 act= ive user) Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::2= 12::ResourceManager.Request::(grant) ResName=3D`Storage.39484795-666f-44b3-= 9cf5-21bbb02531ad`ReqID=3D`9ba33a25-cdd2-4fc0-9f50-55a82178ad48`::Granted r= equest Thread-788::DEBUG::2013-01-30 16:42:28,908::task::817::TaskManage= r.Task::(resourceAcquired) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::_= resourcesAcquired: Storage.39484795-666f-44b3-9cf5-21bbb02531ad (shared)<br= >Thread-788::DEBUG::2013-01-30 16:42:28,909::task::978::TaskManager.Task::(= _decref) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::ref 1 aborting Fals= e Thread-788::DEBUG::2013-01-30 16:42:28,911::fileVolume::535::Storage.V= olume::(validateVolumePath) validate path for 7304b24f-5c4d-41b3-b12f-b916b= c9f4299 Thread-788::DEBUG::2013-01-30 16:42:28,913::fileVolume::535::Sto= rage.Volume::(validateVolumePath) validate path for 7304b24f-5c4d-41b3-b12f= -b916bc9f4299 Thread-788::INFO::2013-01-30 16:42:28,914::logUtils::39::d= ispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'tru= esize': '3256225792', 'apparentsize': '107374182400'} Thread-788::DEBUG:= :2013-01-30 16:42:28,914::task::1172::TaskManager.Task::(prepare) Task=3D`a= ec127b3-b02b-4bc3-b0e9-542c90b4c91e`::finished: {'truesize': '3256225792', = 'apparentsize': '107374182400'} Thread-788::DEBUG::2013-01-30 16:42:28,9= 15::task::588::TaskManager.Task::(_updateState) Task=3D`aec127b3-b02b-4bc3-= b0e9-542c90b4c91e`::moving from state preparing -> state finished Thr= ead-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::809::ResourceMana= ger.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.39= 484795-666f-44b3-9cf5-21bbb02531ad': < ResourceRef 'Storage.39484795-666= f-44b3-9cf5-21bbb02531ad', isValid: 'True' obj: 'None'>} Thread-788::= DEBUG::2013-01-30 16:42:28,915::resourceManager::844::ResourceManager.Owner= ::(cancelAll) Owner.cancelAll requests {} Thread-788::DEBUG::2013-01-30 = 16:42:28,915::resourceManager::538::ResourceManager::(releaseResource) Tryi= ng to release resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' Th= read-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::553::ResourceMan= ager::(releaseResource) Released resource 'Storage.39484795-666f-44b3-9cf5-= 21bbb02531ad' (0 active users) Thread-788::DEBUG::2013-01-30 16:42:28,91= 5::resourceManager::558::ResourceManager::(releaseResource) Resource 'Stora= ge.39484795-666f-44b3-9cf5-21bbb02531ad' is free, finding out if anyone is = waiting for it. Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceMana= ger::565::ResourceManager::(releaseResource) No one is waiting for resource= 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad', Clearing records. Threa= d-788::DEBUG::2013-01-30 16:42:28,915::task::978::TaskManager.Task::(_decre= f) Task=3D`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::ref 0 aborting False V= M Channels Listener::DEBUG::2013-01-30 16:42:29,494::vmChannels::60::vds::(= _handle_timeouts) Timeout on fileno 322. Thread-2412::DEBUG::2013-01-30 = 16:42:31,843::BindingXMLRPC::156::vds::(wrapper) [192.168.5.180] Thread-= 2412::DEBUG::2013-01-30 16:42:31,843::task::588::TaskManager.Task::(_update= State) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`::moving from state ini= t -> state preparing Thread-2412::INFO::2013-01-30 16:42:31,844::logU= tils::37::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID=3D'7b4= 4684c-5f34-11e2-beba-00138fbe3093', options=3DNone) Thread-2412::INFO::2= 013-01-30 16:42:31,844::logUtils::39::dispatcher::(wrapper) Run and protect= : getSpmStatus, Return response: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM'= , 'spmLver': 4}} Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::1172= ::TaskManager.Task::(prepare) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`= ::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4}} T= hread-2412::DEBUG::2013-01-30 16:42:31,844::task::588::TaskManager.Task::(_= updateState) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`::moving from sta= te preparing -> state finished Thread-2412::DEBUG::2013-01-30 16:42:3= 1,844::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.rele= aseAll requests {} resources {} Thread-2412::DEBUG::2013-01-30 16:42:31,= 844::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelA= ll requests {} Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::978::T= askManager.Task::(_decref) Task=3D`4095451f-5992-4276-8bbc-3cbd5c192905`::r= ef 0 aborting False Thread-2413::DEBUG::2013-01-30 16:42:31,936::Binding= XMLRPC::156::vds::(wrapper) [192.168.5.180] Thread-2413::DEBUG::2013-01-= 30 16:42:31,937::task::588::TaskManager.Task::(_updateState) Task=3D`42cacf= 2e-311a-4ca4-8158-6b3dd8f00a94`::moving from state init -> state prepari= ng Thread-2413::INFO::2013-01-30 16:42:31,937::logUtils::37::dispatcher:= :(wrapper) Run and protect: getStoragePoolInfo(spUUID=3D'7b44684c-5f34-11e2= -beba-00138fbe3093', options=3DNone) Thread-2413::DEBUG::2013-01-30 16:4= 2:31,937::resourceManager::175::ResourceManager.Request::(__init__) ResName= =3D`Storage.7b44684c-5f34-11e2-beba-00138fbe3093`ReqID=3D`e72c6606-db58-41d= 0-8072-c4fff9e0e417`::Request was made in '/usr/share/vdsm/storage/resource= Manager.py' line '485' at 'registerResource' Thread-2413::DEBUG::2013-01= -30 16:42:31,937::resourceManager::486::ResourceManager::(registerResource)= Trying to register resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093'= for lock type 'shared' Thread-2413::DEBUG::2013-01-30 16:42:31,937::res= ourceManager::528::ResourceManager::(registerResource) Resource 'Storage.7b= 44684c-5f34-11e2-beba-00138fbe3093' is free. Now locking as 'shared' (1 act= ive user) Thread-2413::DEBUG::2013-01-30 16:42:31,938::resourceManager::= 212::ResourceManager.Request::(grant) ResName=3D`Storage.7b44684c-5f34-11e2= -beba-00138fbe3093`ReqID=3D`e72c6606-db58-41d0-8072-c4fff9e0e417`::Granted = request Thread-2413::DEBUG::2013-01-30 16:42:31,939::task::817::TaskMana= ger.Task::(resourceAcquired) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`:= :_resourcesAcquired: Storage.7b44684c-5f34-11e2-beba-00138fbe3093 (shared)<= br>Thread-2413::DEBUG::2013-01-30 16:42:31,939::task::978::TaskManager.Task= ::(_decref) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::ref 1 aborting F= alse Thread-2413::INFO::2013-01-30 16:42:32,073::logUtils::39::dispatche= r::(wrapper) Run and protect: getStoragePoolInfo, Return response: {'info':= {'spm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name= ': 'acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb025= 31ad:Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-= a66c-2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/= data-center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20= aab92f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'ma= ster_ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f= 08aa': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'diskt= otal': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': '= Active', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '4766763581= 44'}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfre= e': '313801244672', 'alerts': [], 'disktotal': '982927736832'}}} Thread-= 2413::DEBUG::2013-01-30 16:42:32,073::task::1172::TaskManager.Task::(prepar= e) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::finished: {'info': {'spm_= id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'acl= oudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Ac= tive,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f= 135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-ce= nter/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f0= 8aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ve= r': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': = {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': = '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active'= , 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, '= cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '31= 3801244672', 'alerts': [], 'disktotal': '982927736832'}}} Thread-2413::D= EBUG::2013-01-30 16:42:32,073::task::588::TaskManager.Task::(_updateState) = Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::moving from state preparing = -> state finished Thread-2413::DEBUG::2013-01-30 16:42:32,073::resour= ceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll reques= ts {} resources {'Storage.7b44684c-5f34-11e2-beba-00138fbe3093': < Resou= rceRef 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093', isValid: 'True' obj:= 'None'>} Thread-2413::DEBUG::2013-01-30 16:42:32,073::resourceManage= r::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} T= hread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::538::ResourceM= anager::(releaseResource) Trying to release resource 'Storage.7b44684c-5f34= -11e2-beba-00138fbe3093' Thread-2413::DEBUG::2013-01-30 16:42:32,074::re= sourceManager::553::ResourceManager::(releaseResource) Released resource 'S= torage.7b44684c-5f34-11e2-beba-00138fbe3093' (0 active users) Thread-241= 3::DEBUG::2013-01-30 16:42:32,074::resourceManager::558::ResourceManager::(= releaseResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' is= free, finding out if anyone is waiting for it. Thread-2413::DEBUG::2013= -01-30 16:42:32,074::resourceManager::565::ResourceManager::(releaseResourc= e) No one is waiting for resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe= 3093', Clearing records. Thread-2413::DEBUG::2013-01-30 16:42:32,075::ta= sk::978::TaskManager.Task::(_decref) Task=3D`42cacf2e-311a-4ca4-8158-6b3dd8= f00a94`::ref 0 aborting False Thread-2414::DEBUG::2013-01-30 16:42:33,89= 4::task::588::TaskManager.Task::(_updateState) Task=3D`e5af4772-7be5-43c0-a= 907-8a3f3fe0cebd`::moving from state init -> state preparing Thread-2= 414::INFO::2013-01-30 16:42:33,894::logUtils::37::dispatcher::(wrapper) Run= and protect: repoStats(options=3DNone) Thread-2414::INFO::2013-01-30 16= :42:33,894::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats,= Return response: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.001= 96099281311', 'lastCheck': 1359564145.53994, 'code': 0, 'valid': True}, '0b= 6a87e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00284719467163', 'lastChe= ck': 1359564151.359623, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c= -2f135b8fbb30': {'delay': '0.0015709400177', 'lastCheck': 1359564153.38349,= 'code': 0, 'valid': True}} Thread-2414::DEBUG::2013-01-30 16:42:33,895:= :task::1172::TaskManager.Task::(prepare) Task=3D`e5af4772-7be5-43c0-a907-8a= 3f3fe0cebd`::finished: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '= 0.00196099281311', 'lastCheck': 1359564145.53994, 'code': 0, 'valid': True}= , '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00284719467163', 'la= stCheck': 1359564151.359623, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86= -a66c-2f135b8fbb30': {'delay': '0.0015709400177', 'lastCheck': 1359564153.3= 8349, 'code': 0, 'valid': True}} Thread-2414::DEBUG::2013-01-30 16:42:33= ,895::task::588::TaskManager.Task::(_updateState) Task=3D`e5af4772-7be5-43c= 0-a907-8a3f3fe0cebd`::moving from state preparing -> state finished T= hread-2414::DEBUG::2013-01-30 16:42:33,895::resourceManager::809::ResourceM= anager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} Thr= ead-2414::DEBUG::2013-01-30 16:42:33,895::resourceManager::844::ResourceMan= ager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-2414::DEBUG::= 2013-01-30 16:42:33,895::task::978::TaskManager.Task::(_decref) Task=3D`e5a= f4772-7be5-43c0-a907-8a3f3fe0cebd`::ref 0 aborting False Thread-2415::DE= BUG::2013-01-30 16:42:33,996::libvirtvm::240::vm.Vm::(_getDiskStats) vmId= =3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk hdc stats not available = Thread-2415::DEBUG::2013-01-30 16:42:33,997::libvirtvm::240::vm.Vm::(_getDi= skStats) vmId=3D`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk fda stats not = available Thread-424::DEBUG::2013-01-30 16:42:34,508::task::588::TaskMan= ager.Task::(_updateState) Task=3D`413cbd4e-2ddc-4a21-9526-39e970dc48d3`::mo= ving from state init -> state preparing Thread-424::INFO::2013-01-30 = 16:42:34,509::logUtils::37::dispatcher::(wrapper) Run and protect: getVolum= eSize(sdUUID=3D'39484795-666f-44b3-9cf5-21bbb02531ad', spUUID=3D'7b44684c-5= f34-11e2-beba-00138fbe3093', imgUUID=3D'c7524cbd-5f92-41ba-a9e9-8724dd2c4c1= 1', volUUID=3D'5904f9d7-09c3-404d-8887-71a32ff96735', options=3DNone) Th= read-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::175::ResourceMan= ager.Request::(__init__) ResName=3D`Storage.39484795-666f-44b3-9cf5-21bbb02= 531ad`ReqID=3D`e4e2d280-6941-469f-be31-ac06bfb73f99`::Request was made in '= /usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource= ' Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::486::Reso= urceManager::(registerResource) Trying to register resource 'Storage.394847= 95-666f-44b3-9cf5-21bbb02531ad' for lock type 'shared' Thread-424::DEBUG= ::2013-01-30 16:42:34,509::resourceManager::528::ResourceManager::(register= Resource) Resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' is free. = Now locking as 'shared' (1 active user) Thread-424::DEBUG::2013-01-30 16= :42:34,509::resourceManager::212::ResourceManager.Request::(grant) ResName= =3D`Storage.39484795-666f-44b3-9cf5-21bbb02531ad`ReqID=3D`e4e2d280-6941-469= f-be31-ac06bfb73f99`::Granted request Thread-424::DEBUG::2013-01-30 16:4= 2:34,509::task::817::TaskManager.Task::(resourceAcquired) Task=3D`413cbd4e-= 2ddc-4a21-9526-39e970dc48d3`::_resourcesAcquired: Storage.39484795-666f-44b= 3-9cf5-21bbb02531ad (shared) Thread-424::DEBUG::2013-01-30 16:42:34,510:= :task::978::TaskManager.Task::(_decref) Task=3D`413cbd4e-2ddc-4a21-9526-39e= 970dc48d3`::ref 1 aborting False Thread-424::DEBUG::2013-01-30 16:42:34,= 511::fileVolume::535::Storage.Volume::(validateVolumePath) validate path fo= r 5904f9d7-09c3-404d-8887-71a32ff96735 <div></s= pan>-----------------------------= ---------------- Logicworks Tecnologias de Inform=C3=A1tica</= span> <a href=3D"http://www.logicwor= ks.pt">http://www.logicworks.pt</= span></a> </div></div></body></html> ------=_Part_476_18436217.1359564233863--

1 0