Users

Thread Start a new thread

Download

users@ovirt.org

April 2014

145 participants
263 discussions

[Users] does anyone know if there is an ovirt weekly status meeting today?
by Einav Cohen 02 Apr '14

02 Apr '14

it has been removed from the calendar (at least mine...) Thanks, Einav

1 0

[Users] Call for Papers Deadline in One Month: LinuxCon/CloudOpen North America
by Brian Proffitt 02 Apr '14

02 Apr '14

Conference: LinuxCon North America Information: LinuxCon is the only event covering all matters Linux - offering collaboration and education for everyone in the ecosystem from developers and maintainers to sys admins and architects to business executives and community members. Date: August 20-22, 2014 Location: Chicago, Illinois Website: http://events.linuxfoundation.org/events/linuxcon-north-america Call for Papers Deadline: May 2, 2014 Call for Papers URL: http://events.linuxfoundation.org/events/linuxcon-north-america/program/cfp Conference: CloudOpen North America Information: CloudOpen is the only conference offering collaboration and education for all matters of open source cloud, from CloudStack to KVM to Xen, Hadoop, Gluster, OpenStack and more. Date: August 20-22, 2014 Location: Chicago, Illinois Website: http://events.linuxfoundation.org/events/cloudopen-north-america Call for Papers Deadline: May 2, 2014 Call for Papers URL: http://events.linuxfoundation.org/events/cloudopen-north-america/program/cfp -- Brian Proffitt - oVirt Community Manager Open Source and Standards, Red Hat - http://community.redhat.com Phone: +1 574 383 9BKP IRC: bkp @ OFTC

1 0

[Users] Host's Cluster Compatibility Version doesn't match the Cluster's Compatibility Version.
by Jim＠Ovirt 02 Apr '14

02 Apr '14

Hi, I've just installed OVirt 3.4 AllInOne on a clean install of Fedora19 on a multihomed box. I had some issues getting networking working, but that seems to be up now. I've got two Alerts that are in the way now: Host's Cluster Compatibility Version doesn't match the Cluster's Compatibility Version. According to "yum list installed" every package that begins "ovirt" is version 3.4.something and every package that begins "vdsm" is version 4.14.6, but "vdsClient -s 0 getVdsCaps" reports clusterLevels = ['3.0', '3.1', '3.2', '3.3' ]. So it looks like ovirt 3.4 isn't compatible with ovirt 3.4, which seems a bit odd. What have I done wrong here? The other Alert is "Power Management is not configured for this Host.". I don't have any kind of power management for this box, is it possible to stop this message? Thanks Jim

3 3

[Users] el7 based node? el7 as a supported platform for ovirt?
by Paul Jansen 02 Apr '14

02 Apr '14

--1060583355-1358189179-1396420276=:80659 Content-Type: text/plain; charset=iso-8859-1 Content-Transfer-Encoding: quoted-printable Hi.=0AThere's been a bit of chatter about what is required for Fedora 20 su= pport for ovirt.=0AI can't seem to find newer fedora19 based node isos anym= ore.=A0 Has there been a conscious decision to standardize on the el6 based= node iso?=0AIs an F20 based node iso something that is being worked on?=0A= =0AI hear that el7 might be announced this month.=A0 Presumably Centos 7.0 = won't be far away after that.=0AI've done some test installs of RHEL 7 beta= to get a look at what packages are in use.=0AOf particular interest to me = is the qemu-kvm package - the version of which seem to be important for liv= e snapshots and live migrations (something we don't seem to do well right n= ow).=0AEL beta7 has this version - qemu-kvm-1.5.3-19.el7.x86_64.rpm=0A=0AGi= ven the issues with needing newer versions of certain packages than what is= in EL 6.5, is this situation likely to be improved greatly when Centos 7 i= s available?=0AI don't recall seeing much discussion about ovirt running un= der el7.=A0 Is this being looked at at this stage, or given the fact there = is only a beta of rhel7=A0 to work with right now are we just waiting for t= he release before deciding too much?=0A=0AThanks.=0A --1060583355-1358189179-1396420276=:80659 Content-Type: text/html; charset=iso-8859-1 Content-Transfer-Encoding: quoted-printable <html><body><div style=3D"color:#000; background-color:#fff; font-family:He= lveticaNeue, Helvetica Neue, Helvetica, Arial, Lucida Grande, Sans-Serif;fo= nt-size:12pt"><div>Hi.</div><div><span>There's been a bit of chatter about = what is required for Fedora 20 support for ovirt.</span></div><div style=3D= "color: rgb(0, 0, 0); font-size: 16px; font-family: HelveticaNeue,Helvetica= Neue,Helvetica,Arial,Lucida Grande,Sans-Serif; background-color: transpare= nt; font-style: normal;"><span>I can't seem to find newer fedora19 based no= de isos anymore.  Has there been a conscious decision to standardize o= n the el6 based node iso?</span></div><div style=3D"color: rgb(0, 0, 0); fo= nt-size: 16px; font-family: HelveticaNeue,Helvetica Neue,Helvetica,Arial,Lu= cida Grande,Sans-Serif; background-color: transparent; font-style: normal;"= ><span>Is an F20 based node iso something that is being worked on?</span></= div><div style=3D"color: rgb(0, 0, 0); font-size: 16px; font-family: HelveticaNeue,Helvetica Neue,Helvetica,Arial,Lucida Grande,Sans-Serif; bac= kground-color: transparent; font-style: normal;"><br><span></span></div><di= v style=3D"color: rgb(0, 0, 0); font-size: 16px; font-family: HelveticaNeue= ,Helvetica Neue,Helvetica,Arial,Lucida Grande,Sans-Serif; background-color:= transparent; font-style: normal;"><span>I hear that el7 might be announced= this month.  Presumably Centos 7.0 won't be far away after that.</spa= n></div><div style=3D"color: rgb(0, 0, 0); font-size: 16px; font-family: He= lveticaNeue,Helvetica Neue,Helvetica,Arial,Lucida Grande,Sans-Serif; backgr= ound-color: transparent; font-style: normal;"><span>I've done some test ins= talls of RHEL 7 beta to get a look at what packages are in use.</span></div= ><div style=3D"color: rgb(0, 0, 0); font-size: 16px; font-family: Helvetica= Neue,Helvetica Neue,Helvetica,Arial,Lucida Grande,Sans-Serif; background-co= lor: transparent; font-style: normal;"><span>Of particular interest to me is the qemu-kvm package - the version of which seem to be important for li= ve snapshots and live migrations (something we don't seem to do well right = now).</span></div><div style=3D"color: rgb(0, 0, 0); font-size: 16px; font-= family: HelveticaNeue,Helvetica Neue,Helvetica,Arial,Lucida Grande,Sans-Ser= if; background-color: transparent; font-style: normal;"><span>EL beta7 has = this version - </span><a href=3D"http://easi.adl.quantum.com/install/rhel/7= .0/x86_64/dvd/Packages/qemu-kvm-1.5.3-19.el7.x86_64.rpm">qemu-kvm-1.5.3-19.= el7.x86_64.rpm</a></div><div style=3D"color: rgb(0, 0, 0); font-size: 16px;= font-family: HelveticaNeue,Helvetica Neue,Helvetica,Arial,Lucida Grande,Sa= ns-Serif; background-color: transparent; font-style: normal;"><br></div><di= v style=3D"color: rgb(0, 0, 0); font-size: 16px; font-family: HelveticaNeue= ,Helvetica Neue,Helvetica,Arial,Lucida Grande,Sans-Serif; background-color:= transparent; font-style: normal;">Given the issues with needing newer versions of certain packages than what is in EL 6.5, is this situation lik= ely to be improved greatly when Centos 7 is available?</div><div style=3D"c= olor: rgb(0, 0, 0); font-size: 16px; font-family: HelveticaNeue,Helvetica N= eue,Helvetica,Arial,Lucida Grande,Sans-Serif; background-color: transparent= ; font-style: normal;">I don't recall seeing much discussion about ovirt ru= nning under el7.  Is this being looked at at this stage, or given the = fact there is only a beta of rhel7  to work with right now are we just= waiting for the release before deciding too much?</div><div style=3D"color= : rgb(0, 0, 0); font-size: 16px; font-family: HelveticaNeue,Helvetica Neue,= Helvetica,Arial,Lucida Grande,Sans-Serif; background-color: transparent; fo= nt-style: normal;"><br></div><div style=3D"color: rgb(0, 0, 0); font-size: = 16px; font-family: HelveticaNeue,Helvetica Neue,Helvetica,Arial,Lucida Gran= de,Sans-Serif; background-color: transparent; font-style: normal;">Thanks.<br></div></div></body></html> --1060583355-1358189179-1396420276=:80659--

3 2

[Users] Logical Network VLAN Tagging and DataCenters
by Simon Barrett 02 Apr '14

02 Apr '14

--_000_D86C48DF8800164BBE50B87623F7AC95481F3217ln2wio001devtra_ Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: quoted-printable Is it possible/supported to have the same VLAN ID defined across multiple d= atacenters logical networks? I am seeing a situation where if I have two Datacenters, both with a logica= l network that have the same vlan tag. E.G. DC =3D London, Logical network =3D prod, VLAN =3D 123 DC =3D Paris, Logical network =3D prod, VLAN =3D 123 If I edit one of the VM's running in the "London" datacenter, the VM NIC sh= ows up as empty even though if I click on the VM and go to the "Network Int= erfaces" tab at the bottom of the screen it lists up the "prod" network und= er "Network Name". All works OK for VM's that are assigned a logical network that is only defi= ned once (i.e. has not been added to both datacenters). This configuration is on oVirt Engine 3.3.2-1.el6. I am planning on moving = to 3.4 very soon so will test out again on that version. Thanks, Simon --_000_D86C48DF8800164BBE50B87623F7AC95481F3217ln2wio001devtra_ Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" xmlns:o=3D"urn:schemas-micr= osoft-com:office:office" xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:m=3D"http://schemas.microsoft.com/office/2004/12/omml" xmlns=3D"http:= //www.w3.org/TR/REC-html40"> <head> <meta http-equiv=3D"Content-Type" content=3D"text/html; charset=3Dus-ascii"= > <meta name=3D"Generator" content=3D"Microsoft Word 15 (filtered medium)"> <style></style> </head> <body lang=3D"EN-GB" link=3D"#0563C1" vlink=3D"#954F72"> <div class=3D"WordSection1"> <p class=3D"MsoNormal">Is it possible/supported to have the same VLAN ID de= fined across multiple datacenters logical networks?<o:p></o:p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> <p class=3D"MsoNormal">I am seeing a situation where if I have two Datacent= ers, both with a logical network that have the same vlan tag. E.G.<o:p></o:= p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> <p class=3D"MsoNormal">DC =3D London, Logical network =3D prod, VLAN =3D 12= 3<o:p></o:p></p> <p class=3D"MsoNormal">DC =3D Paris, Logical network =3D prod, VLAN =3D 123= <o:p></o:p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> <p class=3D"MsoNormal">If I edit one of the VM’s running in the &#822= 0;London” datacenter, the VM NIC shows up as empty even though if I c= lick on the VM and go to the “Network Interfaces” tab at the bo= ttom of the screen it lists up the “prod” network under “= Network Name”.<o:p></o:p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> <p class=3D"MsoNormal">All works OK for VM’s that are assigned a logi= cal network that is only defined once (i.e. has not been added to both data= centers).<o:p></o:p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> <p class=3D"MsoNormal">This configuration is on oVirt Engine 3.3.2-1.el6. I= am planning on moving to 3.4 very soon so will test out again on that vers= ion. <o:p></o:p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> <p class=3D"MsoNormal">Thanks,<o:p></o:p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> <p class=3D"MsoNormal">Simon<o:p></o:p></p> <p class=3D"MsoNormal"><o:p> </o:p></p> </div> </body> </html> --_000_D86C48DF8800164BBE50B87623F7AC95481F3217ln2wio001devtra_--

3 3

[Users] Where is the hosted engine storage?
by Jimmy Dorff 02 Apr '14

02 Apr '14

This is a cryptographically signed message in MIME format. --------------ms010202000106020805010209 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: quoted-printable I installed a test system with 3.4 and hosted-engine. Everything worked! Post-install I didn't have a Storage Domain and the Data Center wasn't=20 initialized. I added a second NFS path (everything on a NFS appliance)=20 and setup a Storage Domain and such. My question is where is the storage for the engine vm? Clearly it's on=20 the NFS path I provided during the setup, but that isn't visible in the=20 oVirt GUI at all. The "HostedEngine" Disks tab is blank. Just checking that this is the design intent. Thanks, Jimmy --------------ms010202000106020805010209 Content-Type: application/pkcs7-signature; name="smime.p7s" Content-Transfer-Encoding: base64 Content-Disposition: attachment; filename="smime.p7s" Content-Description: S/MIME Cryptographic Signature MIAGCSqGSIb3DQEHAqCAMIACAQExCzAJBgUrDgMCGgUAMIAGCSqGSIb3DQEHAQAAoIIKYDCC BOowggPSoAMCAQICECeaPwnGr8aRr9rqNxvvpm8wDQYJKoZIhvcNAQEFBQAwga4xCzAJBgNV BAYTAlVTMQswCQYDVQQIEwJVVDEXMBUGA1UEBxMOU2FsdCBMYWtlIENpdHkxHjAcBgNVBAoT FVRoZSBVU0VSVFJVU1QgTmV0d29yazEhMB8GA1UECxMYaHR0cDovL3d3dy51c2VydHJ1c3Qu Y29tMTYwNAYDVQQDEy1VVE4tVVNFUkZpcnN0LUNsaWVudCBBdXRoZW50aWNhdGlvbiBhbmQg RW1haWwwHhcNMTEwMjExMDAwMDAwWhcNMjAwNTMwMTA0ODM4WjBkMQswCQYDVQQGEwJVUzES MBAGA1UEChMJSW50ZXJuZXQyMREwDwYDVQQLEwhJbkNvbW1vbjEuMCwGA1UEAxMlSW5Db21t b24gU3RhbmRhcmQgQXNzdXJhbmNlIENsaWVudCBDQTCCASIwDQYJKoZIhvcNAQEBBQADggEP ADCCAQoCggEBANPgFQJ7+XaC6f6RlvPwHiFotQ1hML4Jl2bdHSOn+x+GeDrml2rHwaR/iU2z nlmzOAsfOSJrAigSvymW0TX0lSpsBpgcGWRcpn5FbiBrkbh0DzxTyIMZaxGjxyyFBR6H1Mug CAUGLmaJPXing5NUt9gX6tPufWbUNN6Jyhn9N8rQg95RaNha9XTr3YICWvogzqrPQCvTyJ6F 6YEzm9bxT5FF2Y0zKRxdB5qF6f/JErJMDEIq7TD019yZtc8LgOp53qcjYp+16Zai1rIaRdDY Ex2SefSEgT22FY/ubywaxuxdrJOPC0dNOjsJ1d4GIjoRlZBvTnsfci4nkTM+iFfCvwkCAwEA AaOCAUswggFHMB8GA1UdIwQYMBaAFImCZ33EnSZwAEu0UEh83j2uBG59MB0GA1UdDgQWBBTo 172Wqt3QCO+hM55eWZg8ErebkTAOBgNVHQ8BAf8EBAMCAQYwEgYDVR0TAQH/BAgwBgEB/wIB ADARBgNVHSAECjAIMAYGBFUdIAAwWAYDVR0fBFEwTzBNoEugSYZHaHR0cDovL2NybC51c2Vy dHJ1c3QuY29tL1VUTi1VU0VSRmlyc3QtQ2xpZW50QXV0aGVudGljYXRpb25hbmRFbWFpbC5j cmwwdAYIKwYBBQUHAQEEaDBmMD0GCCsGAQUFBzAChjFodHRwOi8vY3J0LnVzZXJ0cnVzdC5j b20vVVROQWRkVHJ1c3RDbGllbnRfQ0EuY3J0MCUGCCsGAQUFBzABhhlodHRwOi8vb2NzcC51 c2VydHJ1c3QuY29tMA0GCSqGSIb3DQEBBQUAA4IBAQAO7bbgOJ86aKIBwfCE5Sbu/IHS14zJ GyRDoMmxheaf5OAq0UTe8qBwrYgYKEiWExJHSfnKOaNX181ThU239y8f2atTemkHTshCv+Ls Xjt79D6ypEmWkRZ7+U9qXkJyMdHTQ6E5BYk+y7XR6T4RxoR1zjW/9S3ooqv1pAFOcBwmZasW w70y5ikQL2tqskjWcAYG9SjoVH7MEMVprhHpBes7OMFiWtAeGOlcTVP49Srh3ZI1hPoljx/H I1ka+KIlpVZi4t44rNTfM1diATgqUSo7TLlHsHHpySHBYY7SY626SSsiAKw2NRBIDl7B7B2x 22JekwPq5VBI71GrIYr7YMXCMIIFbjCCBFagAwIBAgIRAJii4SGmG4xeMs7zeGP14o4wDQYJ KoZIhvcNAQEFBQAwZDELMAkGA1UEBhMCVVMxEjAQBgNVBAoTCUludGVybmV0MjERMA8GA1UE CxMISW5Db21tb24xLjAsBgNVBAMTJUluQ29tbW9uIFN0YW5kYXJkIEFzc3VyYW5jZSBDbGll bnQgQ0EwHhcNMTMxMDMxMDAwMDAwWhcNMTgxMDMxMjM1OTU5WjCBnDEOMAwGA1UEERMFMjc3 MDExJTAjBgNVBAoTHER1a2UgVW5pdmVyc2l0eSAtIEVuY3J5cHRpb24xCzAJBgNVBAgTAk5D MQ8wDQYDVQQHEwZEdXJoYW0xCzAJBgNVBAYTAlVTMRQwEgYDVQQDEwtKaW1teSBEb3JmZjEi MCAGCSqGSIb3DQEJARYTamRvcmZmQHBoeS5kdWtlLmVkdTCCASIwDQYJKoZIhvcNAQEBBQAD ggEPADCCAQoCggEBALHXuvlDP6Pjn3MAVN2+FB2Yu+Bjl8VzAxBmqrrflkFWvpOv/xpI0/QI A1NhPOER1zKev2GK35R44eM4P7jyz3rbvM2SBH4OVpk6l5aTJnM5shneFyxMr9TmlHi6gMeA MuCwGZvTeP+nfUgf0Gdrf3dPMlwxMeoPDjb0nApn8f5ZtuNRB46yuHwsXmSi6MNrufOgclkm AtjirEydxysjl/Ck1AOzI6dUyFFmGR63lddOWIPFR+K1Seh7t1auVx4Z8VuW2wCSzurcBOJV j0RnsduOxIzHZgQcoy5QaNi6h0/61fUvKB4S1K8mGY18rdtTz5DWCgf0lq276E2Wy6JidkMC AwEAAaOCAeAwggHcMB8GA1UdIwQYMBaAFOjXvZaq3dAI76Eznl5ZmDwSt5uRMB0GA1UdDgQW BBT3kO8ynA7nwzcRrZ/97bI0/CQ9iDAOBgNVHQ8BAf8EBAMCBaAwDAYDVR0TAQH/BAIwADAd BgNVHSUEFjAUBggrBgEFBQcDAgYIKwYBBQUHAwQwagYDVR0gBGMwYTBfBg0rBgEEAa4jAQQD AwABME4wTAYIKwYBBQUHAgEWQGh0dHBzOi8vd3d3LmluY29tbW9uLm9yZy9jZXJ0L3JlcG9z aXRvcnkvY3BzX3N0YW5kYXJkX2NsaWVudC5wZGYwTgYDVR0fBEcwRTBDoEGgP4Y9aHR0cDov L2NybC5pbmNvbW1vbi5vcmcvSW5Db21tb25TdGFuZGFyZEFzc3VyYW5jZUNsaWVudENBLmNy bDCBgAYIKwYBBQUHAQEEdDByMEoGCCsGAQUFBzAChj5odHRwOi8vY2VydC5pbmNvbW1vbi5v cmcvSW5Db21tb25TdGFuZGFyZEFzc3VyYW5jZUNsaWVudENBLmNydDAkBggrBgEFBQcwAYYY aHR0cDovL29jc3AuaW5jb21tb24ub3JnMB4GA1UdEQQXMBWBE2pkb3JmZkBwaHkuZHVrZS5l ZHUwDQYJKoZIhvcNAQEFBQADggEBAISQv2tq3fwqcnd7y7gjlPcxAvJgiXrgKoeED2KFYrbB fg8JeBEi1fqRZnk38LDobfgFFbCaBmVZ37sSW0XPxeQIwQdbY5FpcjMpb2dG1SIPEzV5nuos Bsh+SAGDia86e4SxMpagT39aKcc/5TfR9iBquKEgvriUNJW8VAJzOd9/VmMzsjBz0mEGpEYJ 6cV+7D2uITp4nbpU34YOVENvLUlV44Lyw52DJ6ZeKP+hmNrJx1sz93LD841B9Q494/wC6H4Q G/OjWB7TEA1EqVNvr9/WiXt5WJDLCT+WSuFwIfq1sY5252u8PCOB8kdBMEvWT23YJ9UdoZPK K9bYrCXTEn4xggOHMIIDgwIBATB5MGQxCzAJBgNVBAYTAlVTMRIwEAYDVQQKEwlJbnRlcm5l dDIxETAPBgNVBAsTCEluQ29tbW9uMS4wLAYDVQQDEyVJbkNvbW1vbiBTdGFuZGFyZCBBc3N1 cmFuY2UgQ2xpZW50IENBAhEAmKLhIaYbjF4yzvN4Y/XijjAJBgUrDgMCGgUAoIIB4zAYBgkq hkiG9w0BCQMxCwYJKoZIhvcNAQcBMBwGCSqGSIb3DQEJBTEPFw0xNDA0MDExODUxNTVaMCMG CSqGSIb3DQEJBDEWBBSsqibTB91/Qyqg7dmPk+T/uRbz/jBsBgkqhkiG9w0BCQ8xXzBdMAsG CWCGSAFlAwQBKjALBglghkgBZQMEAQIwCgYIKoZIhvcNAwcwDgYIKoZIhvcNAwICAgCAMA0G CCqGSIb3DQMCAgFAMAcGBSsOAwIHMA0GCCqGSIb3DQMCAgEoMIGIBgkrBgEEAYI3EAQxezB5 MGQxCzAJBgNVBAYTAlVTMRIwEAYDVQQKEwlJbnRlcm5ldDIxETAPBgNVBAsTCEluQ29tbW9u MS4wLAYDVQQDEyVJbkNvbW1vbiBTdGFuZGFyZCBBc3N1cmFuY2UgQ2xpZW50IENBAhEAmKLh IaYbjF4yzvN4Y/XijjCBigYLKoZIhvcNAQkQAgsxe6B5MGQxCzAJBgNVBAYTAlVTMRIwEAYD VQQKEwlJbnRlcm5ldDIxETAPBgNVBAsTCEluQ29tbW9uMS4wLAYDVQQDEyVJbkNvbW1vbiBT dGFuZGFyZCBBc3N1cmFuY2UgQ2xpZW50IENBAhEAmKLhIaYbjF4yzvN4Y/XijjANBgkqhkiG 9w0BAQEFAASCAQBENeOvjbra1fRISNeBQK0yyXjmdrheZxSvA8Hs4mL6U/xXkLFRq5l7pt5K f1P67bHLN2FR1AHpIfWJ+LYgWPpMwAEu8CW1sAgHLpz/BdyJjJ2lBlWJZw+KEfGmFNnRGmmL EvcjS5w4ZsvoY8eD6Jm7f3orT8ces+KCz7uD7VU/JUhAeS+/PkrCr0BrE8OaZ13rNtl930cd ZTcMyPCFgoThSinBKaLkYs5hvgok43l8f/jSP09oMicY03xfGewAzXCY9BzVIGEjzfgsBgVc 8sAT+gp7DV0QGtYfnLaZqvUENsvvLAyMWkU3TNAIPZ1whp9uHXBbshkK466HoiVPQ7O3AAAA AAAA --------------ms010202000106020805010209--

2 1

[Users] Clome thin provision to thin provision??
by Federico Alberto Sayd 01 Apr '14

01 Apr '14

Hello: Is it possible to clone a vm with thin provisioned disks to another vm with thin provisioned disks too? Currently when I clone a VM from a snapshot, although I choose "thin provision" in "resource allocation"->"Storage Allocation" from the "Clone VM from Snapshot dialog", the VM is created with preallocated disk(s) Is this the default or there is a problem with my engine/nodes installation? Engine: Centos 6.5, ovirt-engine 3.4.0-1.el6 Nodes: Centos 6.5, vdsm-4.13.3-2.el6

2 3

[Users] How-to NAT / host-only virtual machine network
by Alexander von Gluck 01 Apr '14

01 Apr '14

Found a way to get easy NAT / host only virtual networks in oVirt 3.4. I didn't see this documented, so posting it here: 1. Create a fake ethernet card - ip link set name vmnet0dummy dev dummy0 Now you can create a new network (bridge) named vmnet0, and attach it to vmnet0dummy. The issue is that as posted on http://www.ovirt.org/Features/Nicless_Network#Benefit_to_oVirt: " I am told that implementing this in Engine is quite a pain, as network external interfaces are currently used as keys. A network without nics is currently unsearchable. " You'll need to figure out your own dhcp / NAT using libvirt or something running on your server, but a *lot* easier and less hacky than http://lists.ovirt.org/pipermail/users/2012-April/001751.html :-) -- Alex

1 0

Re: [Users] upgrade from 3.3 to 3.4 on fedora 19 failed
by Alastair Neil 01 Apr '14

01 Apr '14

Thanks Alon, that did the trick and my upgrade completed successfully. On 31 March 2014 23:18, Alastair Neil <ajneil.tech(a)gmail.com> wrote: > Thanks Alon > > > On 31 March 2014 19:22, Alon Bar-Lev <alonbl(a)redhat.com> wrote: > >> >> >> ----- Original Message ----- >> > From: "Alastair Neil" <ajneil.tech(a)gmail.com> >> > To: "Alon Bar-Lev" <alonbl(a)redhat.com> >> > Sent: Tuesday, April 1, 2014 2:10:03 AM >> > Subject: Re: [Users] upgrade from 3.3 to 3.4 on fedora 19 failed >> > >> > So are there any specific actions I need to do or just try tomorrow? >> >> You can edit: >> /usr/share/ovirt-engine/setup/ovirt_engine_setup/constants.py >> >> Replace: >> UPGRADE_YUM_GROUP = 'ovirt-engine-3.4' >> With: >> UPGRADE_YUM_GROUP = 'ovirt-engine-XXX' >> >> This will make setup ignore the bad group and upgrade based on package >> name. >> >> > >> > -Alastair >> > >> > >> > >> > On 31 March 2014 19:07, Alon Bar-Lev <alonbl(a)redhat.com> wrote: >> > >> > > >> > > The group within repository[1] is invalid. >> > > >> > > Sandro, kiril, please modify ovirt->ovirt-enigne and check that all >> > > packages are OK. >> > > >> > > I also do not know what this file[2] is at that location. >> > > >> > > Alastair, all the issues are caused by this, as product is not >> upgraded to >> > > 3.4, so setup package of 3.4 is trying to setup 3.3 product. >> > > >> > > Thank you for the report! >> > > >> > > Alon >> > > >> > > [1] >> > > >> http://resources.ovirt.org/pub/ovirt-3.4/rpm/fc19/repodata/6cf75ccf11a0c245… >> > > [2] http://resources.ovirt.org/pub/ovirt-3.4/ovirt34.xml >> > > >> > > ----- Original Message ----- >> > > > From: "Alastair Neil" <ajneil.tech(a)gmail.com> >> > > > To: "Alon Bar-Lev" <alonbl(a)redhat.com> >> > > > Cc: users(a)ovirt.org >> > > > Sent: Tuesday, April 1, 2014 1:59:16 AM >> > > > Subject: Re: [Users] upgrade from 3.3 to 3.4 on fedora 19 failed >> > > > >> > > > I tried manually copying the directory /etc/ovirt-engine/sysprep to >> > > > /usr/share/ovirt-engine/conf, and then running engine-setup >> > > > >> > > > I now get a different failure: >> > > > >> > > > [ ERROR ] Failed to execute stage 'Misc configuration': function >> > > > inst_add_glance_provider(unknown, unknown, unknown, unknown, >> unknown) >> > > does >> > > > not exist LINE 2: select inst_add_glance_provider( >> > > > ^ HINT: No function matches the given name >> and >> > > > argument types. You might need to add explicit type casts. >> > > > >> > > > I am attaching the logs from both in case this was a bogus thing to >> try >> > > > >> > > > -20140331175537.log has the original failure and the second in in >> the >> > > > other file. >> > > > >> > > > Thanks >> > > > >> > > > -Alastair >> > > > >> > > > >> > > > >> > > > On 31 March 2014 18:24, Alon Bar-Lev <alonbl(a)redhat.com> wrote: >> > > > >> > > > > >> > > > > >> > > > > ----- Original Message ----- >> > > > > > From: "Alastair Neil" <ajneil.tech(a)gmail.com> >> > > > > > To: users(a)ovirt.org >> > > > > > Sent: Tuesday, April 1, 2014 1:18:37 AM >> > > > > > Subject: [Users] upgrade from 3.3 to 3.4 on fedora 19 failed >> > > > > > >> > > > > > Any suggestions what to look for? >> > > > > >> > > > > Please attach: >> > > > > /var/log/ovirt-engine/setup/ovirt-engine-setup-20140331175537.log >> > > > > >> > > > > > Thanks >> > > > > > Alastair >> > > > > > >> > > > > > >> > > > > > >> > > > > > [root@ovirt-admin ~]# engine-setup >> > > > > > [ INFO ] Stage: Initializing >> > > > > > [ INFO ] Stage: Environment setup >> > > > > > Configuration files: >> > > ['/etc/ovirt-engine-setup.conf.d/10-packaging.conf', >> > > > > > '/etc/ovirt-engine-setup.conf.d/20-setup-ovirt-post.conf'] >> > > > > > Log file: >> > > > > /var/log/ovirt-engine/setup/ovirt-engine-setup-20140331175537.log >> > > > > > Version: otopi-1.2.0 (otopi-1.2.0-1.fc19) >> > > > > > [ INFO ] Stage: Environment packages setup >> > > > > > [ INFO ] Stage: Programs detection >> > > > > > [ INFO ] Stage: Environment setup >> > > > > > [ INFO ] Stage: Environment customization >> > > > > > >> > > > > > --== PRODUCT OPTIONS ==-- >> > > > > > >> > > > > > >> > > > > > --== PACKAGES ==-- >> > > > > > >> > > > > > [ INFO ] Checking for product updates... >> > > > > > [ INFO ] No product updates found >> > > > > > >> > > > > > --== NETWORK CONFIGURATION ==-- >> > > > > > >> > > > > > Setup can automatically configure the firewall on this system. >> > > > > > Note: automatic configuration of the firewall may overwrite >> current >> > > > > settings. >> > > > > > Do you want Setup to configure the firewall? (Yes, No) [Yes]: >> > > > > > [ INFO ] firewalld will be configured as firewall manager. >> > > > > > >> > > > > > --== DATABASE CONFIGURATION ==-- >> > > > > > >> > > > > > --== OVIRT ENGINE CONFIGURATION ==-- >> > > > > > >> > > > > > Skipping storing options as database already prepared >> > > > > > >> > > > > > --== PKI CONFIGURATION ==-- >> > > > > > >> > > > > > PKI is already configured >> > > > > > --== APACHE CONFIGURATION ==-- >> > > > > > >> > > > > > --== SYSTEM CONFIGURATION ==-- >> > > > > > >> > > > > > --== MISC CONFIGURATION ==-- >> > > > > > >> > > > > > --== END OF CONFIGURATION ==-- >> > > > > > >> > > > > > [ INFO ] Stage: Setup validation >> > > > > > [WARNING] Less than 16384MB of memory is available >> > > > > > [ INFO ] Cleaning stale zombie tasks >> > > > > > >> > > > > > --== CONFIGURATION PREVIEW ==-- >> > > > > > >> > > > > > Engine database name : engine >> > > > > > Engine database secured connection : False >> > > > > > Engine database host : localhost >> > > > > > Engine database user name : engine >> > > > > > Engine database host name validation : False >> > > > > > Engine database port : 5432 >> > > > > > NFS setup : True >> > > > > > Firewall manager : firewalld >> > > > > > Update Firewall : True >> > > > > > Configure WebSocket Proxy : True >> > > > > > Host FQDN : ovirt-admin.xxxxx.xxx.xxx >> > > > > > NFS mount point : /var/lib/exports/iso >> > > > > > Set application as default page : True >> > > > > > Configure Apache SSL : True >> > > > > > >> > > > > > Please confirm installation settings (OK, Cancel) [OK]: >> > > > > > [ INFO ] Cleaning async tasks and compensations >> > > > > > [ INFO ] Checking the Engine database consistency >> > > > > > [ INFO ] Stage: Transaction setup >> > > > > > [ INFO ] Stopping engine service >> > > > > > [ INFO ] Stage: Misc configuration >> > > > > > [ INFO ] Stage: Package installation >> > > > > > [ INFO ] Stage: Misc configuration >> > > > > > [ INFO ] Backing up database localhost:engine to >> > > > > > >> '/var/lib/ovirt-engine/backups/engine-20140331175747.lV9QoZ.sql'. >> > > > > > [ INFO ] Updating Engine database schema >> > > > > > [ ERROR ] Failed to execute stage 'Misc configuration': [Errno >> 2] No >> > > such >> > > > > > file or directory: >> '/usr/share/ovirt-engine/conf/sysprep/sysprep.2k3' >> > > > > > [ INFO ] Yum Performing yum transaction rollback >> > > > > > [ INFO ] Rolling back database schema >> > > > > > [ INFO ] Clearing Engine database engine >> > > > > > [ INFO ] Restoring Engine database engine >> > > > > > [ INFO ] Stage: Clean up >> > > > > > Log file is located at >> > > > > > >> /var/log/ovirt-engine/setup/ovirt-engine-setup-20140331175537.log >> > > > > > [ INFO ] Stage: Pre-termination >> > > > > > [ INFO ] Stage: Termination >> > > > > > [ ERROR ] Execution of setup failed >> > > > > > >> > > > > > >> > > > > > _______________________________________________ >> > > > > > Users mailing list >> > > > > > Users(a)ovirt.org >> > > > > > http://lists.ovirt.org/mailman/listinfo/users >> > > > > > >> > > > > >> > > > >> > > >> > >> > >

1 0

Re: [Users] Error installing self hosted engine
by ovirt-test＠arcor.de 01 Apr '14

01 Apr '14

------=_Part_594693_2003216810.1396362976018 Content-Type: multipart/alternative; boundary="----=_Part_594692_1360520117.1396362976018" ------=_Part_594692_1360520117.1396362976018 Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: 7bit > Can you please attach also vdsm logs? Thank you. I have attached the logfiles (mom.log, supervdsm.log, vdsm.log). metadata.log is empty ------=_Part_594692_1360520117.1396362976018-- ------=_Part_594693_2003216810.1396362976018 Content-Type: text/x-log Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename=vdsm.log MainThread::INFO::2014-04-01 12:50:15,497::vdsm::119::vds::(run) (PID: 5366) I am the actual vdsm 4.14.6-0.el6 ovirt01.mydomain (2.6.32-431.11.2.el6.x86_64) MainThread::DEBUG::2014-04-01 12:50:15,498::resourceManager::421::ResourceManager::(registerNamespace) Registering namespace 'Storage' MainThread::DEBUG::2014-04-01 12:50:15,499::threadPool::35::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0 MainThread::WARNING::2014-04-01 12:50:15,504::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/mnt already exists MainThread::DEBUG::2014-04-01 12:50:15,994::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm MainThread::DEBUG::2014-04-01 12:50:16,020::multipath::142::Storage.Multipath::(isEnabled) Current revision of multipath.conf detected, preserving MainThread::DEBUG::2014-04-01 12:50:16,020::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None) MainThread::DEBUG::2014-04-01 12:50:16,211::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 12:50:16,212::hsm::447::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center' MainThread::DEBUG::2014-04-01 12:50:16,216::hsm::479::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt'] MainThread::DEBUG::2014-04-01 12:50:16,217::hsm::480::Storage.HSM::(__cleanStorageRepository) Mount list: [] MainThread::DEBUG::2014-04-01 12:50:16,217::hsm::482::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers MainThread::DEBUG::2014-04-01 12:50:16,218::hsm::525::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center' storageRefresh::DEBUG::2014-04-01 12:50:16,218::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage) MainThread::INFO::2014-04-01 12:50:16,220::dispatcher::97::Storage.Dispatcher::(__init__) Starting StorageDispatcher... storageRefresh::DEBUG::2014-04-01 12:50:16,235::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 12:50:16,240::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) storageRefresh::DEBUG::2014-04-01 12:50:16,254::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 12:50:16,255::iscsi::393::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds storageRefresh::DEBUG::2014-04-01 12:50:16,257::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None) storageRefresh::DEBUG::2014-04-01 12:50:16,273::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 12:50:16,274::multipath::110::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath -r' (cwd None) MainThread::DEBUG::2014-04-01 12:50:16,302::task::595::TaskManager.Task::(_updateState) Task=`e024f2c2-4487-4115-869a-954a2d3eb26f`::moving from state init -> state preparing MainThread::INFO::2014-04-01 12:50:16,302::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF instance at 0x2d27fc8>>) MainThread::INFO::2014-04-01 12:50:16,303::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None MainThread::DEBUG::2014-04-01 12:50:16,303::task::1185::TaskManager.Task::(prepare) Task=`e024f2c2-4487-4115-869a-954a2d3eb26f`::finished: None MainThread::DEBUG::2014-04-01 12:50:16,303::task::595::TaskManager.Task::(_updateState) Task=`e024f2c2-4487-4115-869a-954a2d3eb26f`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 12:50:16,303::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 12:50:16,303::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 12:50:16,303::task::990::TaskManager.Task::(_decref) Task=`e024f2c2-4487-4115-869a-954a2d3eb26f`::ref 0 aborting False MainThread::INFO::2014-04-01 12:50:16,312::momIF::47::MOM::(__init__) Starting up MOM MainThread::INFO::2014-04-01 12:50:16,318::vmChannels::187::vds::(settimeout) Setting channels' timeout to 30 seconds. VM Channels Listener::INFO::2014-04-01 12:50:16,349::vmChannels::170::vds::(run) Starting VM channels listener thread. clientIFinit::DEBUG::2014-04-01 12:50:16,350::libvirtconnection::145::root::(get) trying to connect libvirt MainThread::WARNING::2014-04-01 12:50:16,363::clientIF::181::vds::(_prepareBindings) Unable to load the json rpc server module. Please make sure it is installed. storageRefresh::DEBUG::2014-04-01 12:50:16,379::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:50:16,381::lvm::496::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,381::lvm::498::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,382::lvm::507::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,382::lvm::509::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,382::lvm::527::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,383::lvm::529::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,383::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 12:50:16,383::lvm::324::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,387::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None) storageRefresh::DEBUG::2014-04-01 12:50:16,564::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:50:16,567::lvm::349::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,570::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,581::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 12:50:16,729::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:50:16,729::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,730::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None) storageRefresh::DEBUG::2014-04-01 12:50:16,873::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:50:16,873::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:50:16,874::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 12:50:17,016::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:50:17,017::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::WARNING::2014-04-01 12:50:17,017::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/hsm-tasks already exists storageRefresh::DEBUG::2014-04-01 12:50:17,018::hsm::391::Storage.HSM::(storageRefresh) HSM is ready MainThread::DEBUG::2014-04-01 12:50:31,127::vdsm::55::vds::(sigtermHandler) Received signal 15 MainThread::INFO::2014-04-01 12:50:31,387::vmChannels::183::vds::(stop) VM channels listener was stopped. MainThread::INFO::2014-04-01 12:50:31,387::momIF::84::MOM::(stop) Shutting down MOM MainThread::DEBUG::2014-04-01 12:50:31,388::task::595::TaskManager.Task::(_updateState) Task=`a07b1802-4b0c-4e13-ba61-7a95b67c2c00`::moving from state init -> state preparing MainThread::INFO::2014-04-01 12:50:31,389::logUtils::44::dispatcher::(wrapper) Run and protect: prepareForShutdown(options=None) Thread-11::DEBUG::2014-04-01 12:50:31,389::storageServer::698::ConnectionMonitor::(_monitorConnections) Monitoring stopped MainThread::INFO::2014-04-01 12:50:31,882::domainMonitor::125::Storage.DomainMonitor::(close) Stopping domain monitors MainThread::DEBUG::2014-04-01 12:50:31,882::taskManager::80::TaskManager::(prepareForShutdown) Request to stop all tasks MainThread::INFO::2014-04-01 12:50:31,907::logUtils::47::dispatcher::(wrapper) Run and protect: prepareForShutdown, Return response: None MainThread::DEBUG::2014-04-01 12:50:31,907::task::1185::TaskManager.Task::(prepare) Task=`a07b1802-4b0c-4e13-ba61-7a95b67c2c00`::finished: None MainThread::DEBUG::2014-04-01 12:50:31,907::task::595::TaskManager.Task::(_updateState) Task=`a07b1802-4b0c-4e13-ba61-7a95b67c2c00`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 12:50:31,907::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 12:50:31,908::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 12:50:31,908::task::990::TaskManager.Task::(_decref) Task=`a07b1802-4b0c-4e13-ba61-7a95b67c2c00`::ref 0 aborting False MainThread::INFO::2014-04-01 12:50:31,908::vdsm::125::vds::(run) VDSM main thread ended. Waiting for 14 other threads... MainThread::INFO::2014-04-01 12:50:31,908::vdsm::129::vds::(run) <_MainThread(MainThread, started 140606334629632)> MainThread::INFO::2014-04-01 12:50:31,908::vdsm::129::vds::(run) <Thread(libvirtEventLoop, started daemon 140606066681600)> MainThread::INFO::2014-04-01 12:50:31,908::vdsm::129::vds::(run) <WorkerThread(Thread-4, started daemon 140605820761856)> MainThread::INFO::2014-04-01 12:50:31,908::vdsm::129::vds::(run) <PolicyEngine(PolicyEngine, started daemon 140605298550528)> MainThread::INFO::2014-04-01 12:50:31,909::momIF::84::MOM::(stop) Shutting down MOM MainThread::INFO::2014-04-01 12:50:31,909::vdsm::129::vds::(run) <MomThread(MOM, started 140605309040384)> MainThread::INFO::2014-04-01 12:50:31,909::vdsm::129::vds::(run) <WorkerThread(Thread-7, started daemon 140605789292288)> MainThread::INFO::2014-04-01 12:50:31,909::vmChannels::183::vds::(stop) VM channels listener was stopped. MainThread::INFO::2014-04-01 12:50:31,909::vdsm::129::vds::(run) <Listener(VM Channels Listener, started daemon 140604881237760)> MainThread::INFO::2014-04-01 12:50:31,909::vdsm::129::vds::(run) <WorkerThread(Thread-10, started daemon 140605350999808)> MainThread::INFO::2014-04-01 12:50:31,909::vdsm::129::vds::(run) <WorkerThread(Thread-3, started daemon 140606035212032)> MainThread::INFO::2014-04-01 12:50:31,910::vdsm::129::vds::(run) <WorkerThread(Thread-2, started daemon 140606045701888)> MainThread::INFO::2014-04-01 12:50:31,910::vdsm::129::vds::(run) <WorkerThread(Thread-1, started daemon 140606056191744)> MainThread::INFO::2014-04-01 12:50:31,910::vdsm::129::vds::(run) <WorkerThread(Thread-6, started daemon 140605799782144)> MainThread::INFO::2014-04-01 12:50:31,910::vdsm::129::vds::(run) <WorkerThread(Thread-8, started daemon 140605778802432)> MainThread::INFO::2014-04-01 12:50:31,910::vdsm::129::vds::(run) <WorkerThread(Thread-9, started daemon 140605768312576)> MainThread::INFO::2014-04-01 12:50:31,910::vdsm::129::vds::(run) <WorkerThread(Thread-5, started daemon 140605810272000)> VM Channels Listener::INFO::2014-04-01 12:50:32,368::vmChannels::178::vds::(run) VM channels listener thread has ended. MainThread::INFO::2014-04-01 12:52:42,089::vdsm::119::vds::(run) (PID: 1954) I am the actual vdsm 4.14.6-0.el6 ovirt01.mydomain (2.6.32-431.11.2.el6.x86_64) MainThread::DEBUG::2014-04-01 12:52:42,093::resourceManager::421::ResourceManager::(registerNamespace) Registering namespace 'Storage' MainThread::DEBUG::2014-04-01 12:52:42,093::threadPool::35::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0 MainThread::WARNING::2014-04-01 12:52:42,105::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/mnt already exists MainThread::DEBUG::2014-04-01 12:52:42,618::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm MainThread::DEBUG::2014-04-01 12:52:42,650::multipath::142::Storage.Multipath::(isEnabled) Current revision of multipath.conf detected, preserving MainThread::DEBUG::2014-04-01 12:52:42,650::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None) MainThread::DEBUG::2014-04-01 12:52:42,844::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 12:52:42,844::hsm::447::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center' MainThread::DEBUG::2014-04-01 12:52:42,848::hsm::479::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt'] MainThread::DEBUG::2014-04-01 12:52:42,849::hsm::480::Storage.HSM::(__cleanStorageRepository) Mount list: [] MainThread::DEBUG::2014-04-01 12:52:42,849::hsm::482::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers MainThread::DEBUG::2014-04-01 12:52:42,850::hsm::525::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center' storageRefresh::DEBUG::2014-04-01 12:52:42,850::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage) MainThread::INFO::2014-04-01 12:52:42,852::dispatcher::97::Storage.Dispatcher::(__init__) Starting StorageDispatcher... storageRefresh::DEBUG::2014-04-01 12:52:43,018::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 12:52:43,021::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) storageRefresh::DEBUG::2014-04-01 12:52:43,088::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 12:52:43,088::iscsi::393::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds storageRefresh::DEBUG::2014-04-01 12:52:43,089::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None) storageRefresh::DEBUG::2014-04-01 12:52:43,157::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 12:52:43,158::multipath::110::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath -r' (cwd None) MainThread::DEBUG::2014-04-01 12:52:43,225::task::595::TaskManager.Task::(_updateState) Task=`7c021db9-d327-4ca2-8f58-5ab2bd8dcf2f`::moving from state init -> state preparing MainThread::INFO::2014-04-01 12:52:43,225::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF instance at 0x11dc098>>) MainThread::INFO::2014-04-01 12:52:43,225::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None MainThread::DEBUG::2014-04-01 12:52:43,226::task::1185::TaskManager.Task::(prepare) Task=`7c021db9-d327-4ca2-8f58-5ab2bd8dcf2f`::finished: None MainThread::DEBUG::2014-04-01 12:52:43,226::task::595::TaskManager.Task::(_updateState) Task=`7c021db9-d327-4ca2-8f58-5ab2bd8dcf2f`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 12:52:43,226::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 12:52:43,226::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 12:52:43,227::task::990::TaskManager.Task::(_decref) Task=`7c021db9-d327-4ca2-8f58-5ab2bd8dcf2f`::ref 0 aborting False storageRefresh::DEBUG::2014-04-01 12:52:43,228::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:52:43,229::lvm::496::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,229::lvm::498::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,229::lvm::507::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,229::lvm::509::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,230::lvm::527::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,230::lvm::529::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,230::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 12:52:43,230::lvm::324::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,233::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None) MainThread::INFO::2014-04-01 12:52:43,244::momIF::47::MOM::(__init__) Starting up MOM MainThread::INFO::2014-04-01 12:52:43,253::vmChannels::187::vds::(settimeout) Setting channels' timeout to 30 seconds. VM Channels Listener::INFO::2014-04-01 12:52:43,255::vmChannels::170::vds::(run) Starting VM channels listener thread. MainThread::WARNING::2014-04-01 12:52:43,396::clientIF::181::vds::(_prepareBindings) Unable to load the json rpc server module. Please make sure it is installed. clientIFinit::DEBUG::2014-04-01 12:52:43,399::libvirtconnection::145::root::(get) trying to connect libvirt storageRefresh::DEBUG::2014-04-01 12:52:43,417::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:52:43,418::lvm::349::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,418::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,419::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 12:52:43,575::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:52:43,578::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,606::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None) storageRefresh::DEBUG::2014-04-01 12:52:43,767::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:52:43,767::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 12:52:43,768::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 12:52:43,930::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 12:52:43,930::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::WARNING::2014-04-01 12:52:43,931::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/hsm-tasks already exists storageRefresh::DEBUG::2014-04-01 12:52:43,939::hsm::391::Storage.HSM::(storageRefresh) HSM is ready Thread-13::DEBUG::2014-04-01 12:55:50,786::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-13::DEBUG::2014-04-01 12:55:50,929::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-14::DEBUG::2014-04-01 13:02:22,261::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-14::DEBUG::2014-04-01 13:02:22,285::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-15::DEBUG::2014-04-01 13:04:41,953::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-15::DEBUG::2014-04-01 13:04:41,955::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-16::DEBUG::2014-04-01 13:04:49,768::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-16::DEBUG::2014-04-01 13:04:49,777::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-17::DEBUG::2014-04-01 13:06:19,190::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-17::DEBUG::2014-04-01 13:06:19,192::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} MainThread::DEBUG::2014-04-01 13:11:01,685::vdsm::55::vds::(sigtermHandler) Received signal 15 MainThread::INFO::2014-04-01 13:11:02,459::vmChannels::183::vds::(stop) VM channels listener was stopped. MainThread::INFO::2014-04-01 13:11:02,460::momIF::84::MOM::(stop) Shutting down MOM MainThread::DEBUG::2014-04-01 13:11:02,460::task::595::TaskManager.Task::(_updateState) Task=`45e391ef-1ad3-4a09-a344-988a3172b11e`::moving from state init -> state preparing MainThread::INFO::2014-04-01 13:11:02,461::logUtils::44::dispatcher::(wrapper) Run and protect: prepareForShutdown(options=None) Thread-11::DEBUG::2014-04-01 13:11:02,462::storageServer::698::ConnectionMonitor::(_monitorConnections) Monitoring stopped VM Channels Listener::INFO::2014-04-01 13:11:02,601::vmChannels::178::vds::(run) VM channels listener thread has ended. MainThread::INFO::2014-04-01 13:11:02,971::domainMonitor::125::Storage.DomainMonitor::(close) Stopping domain monitors MainThread::DEBUG::2014-04-01 13:11:02,971::taskManager::80::TaskManager::(prepareForShutdown) Request to stop all tasks MainThread::INFO::2014-04-01 13:11:02,971::logUtils::47::dispatcher::(wrapper) Run and protect: prepareForShutdown, Return response: None MainThread::DEBUG::2014-04-01 13:11:02,971::task::1185::TaskManager.Task::(prepare) Task=`45e391ef-1ad3-4a09-a344-988a3172b11e`::finished: None MainThread::DEBUG::2014-04-01 13:11:02,971::task::595::TaskManager.Task::(_updateState) Task=`45e391ef-1ad3-4a09-a344-988a3172b11e`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 13:11:02,971::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 13:11:02,972::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 13:11:02,972::task::990::TaskManager.Task::(_decref) Task=`45e391ef-1ad3-4a09-a344-988a3172b11e`::ref 0 aborting False MainThread::INFO::2014-04-01 13:11:02,972::vdsm::125::vds::(run) VDSM main thread ended. Waiting for 15 other threads... MainThread::INFO::2014-04-01 13:11:02,972::vdsm::129::vds::(run) <_MainThread(MainThread, started 139901802637056)> MainThread::INFO::2014-04-01 13:11:02,972::vdsm::129::vds::(run) <Thread(libvirtEventLoop, started daemon 139901535491840)> MainThread::INFO::2014-04-01 13:11:02,972::vdsm::129::vds::(run) <GuestManager(GuestManager, started daemon 139900036839168)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <WorkerThread(Thread-2, started daemon 139901446125312)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <WorkerThread(Thread-1, started daemon 139901525001984)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <WorkerThread(Thread-6, started daemon 139901404165888)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <WorkerThread(Thread-9, started daemon 139900965873408)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <WorkerThread(Thread-5, started daemon 139901414655744)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <WorkerThread(Thread-4, started daemon 139901425145600)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <PolicyEngine(PolicyEngine, started daemon 139900496111360)> MainThread::INFO::2014-04-01 13:11:02,973::vdsm::129::vds::(run) <WorkerThread(Thread-8, started daemon 139900976363264)> MainThread::INFO::2014-04-01 13:11:02,974::vdsm::129::vds::(run) <WorkerThread(Thread-10, started daemon 139900955383552)> MainThread::INFO::2014-04-01 13:11:02,974::vdsm::129::vds::(run) <WorkerThread(Thread-7, started daemon 139901393676032)> MainThread::INFO::2014-04-01 13:11:02,974::vdsm::129::vds::(run) <WorkerThread(Thread-3, started daemon 139901435635456)> MainThread::INFO::2014-04-01 13:11:02,974::vdsm::129::vds::(run) <HostMonitor(HostMonitor, started daemon 139900453762816)> MainThread::INFO::2014-04-01 13:11:02,974::momIF::84::MOM::(stop) Shutting down MOM MainThread::INFO::2014-04-01 13:11:02,974::vdsm::129::vds::(run) <MomThread(MOM, started 139900506601216)> MainThread::INFO::2014-04-01 13:11:10,078::vdsm::119::vds::(run) (PID: 3343) I am the actual vdsm 4.14.6-0.el6 ovirt01.mydomain (2.6.32-431.11.2.el6.x86_64) MainThread::DEBUG::2014-04-01 13:11:10,080::resourceManager::421::ResourceManager::(registerNamespace) Registering namespace 'Storage' MainThread::DEBUG::2014-04-01 13:11:10,080::threadPool::35::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0 MainThread::WARNING::2014-04-01 13:11:10,086::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/mnt already exists MainThread::DEBUG::2014-04-01 13:11:10,586::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm MainThread::DEBUG::2014-04-01 13:11:10,616::multipath::142::Storage.Multipath::(isEnabled) Current revision of multipath.conf detected, preserving MainThread::DEBUG::2014-04-01 13:11:10,616::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None) MainThread::DEBUG::2014-04-01 13:11:10,809::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 13:11:10,809::hsm::447::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center' MainThread::DEBUG::2014-04-01 13:11:10,814::hsm::479::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt'] MainThread::DEBUG::2014-04-01 13:11:10,814::hsm::480::Storage.HSM::(__cleanStorageRepository) Mount list: [] MainThread::DEBUG::2014-04-01 13:11:10,814::hsm::482::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers MainThread::DEBUG::2014-04-01 13:11:10,815::hsm::525::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center' storageRefresh::DEBUG::2014-04-01 13:11:10,816::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage) MainThread::INFO::2014-04-01 13:11:10,817::dispatcher::97::Storage.Dispatcher::(__init__) Starting StorageDispatcher... storageRefresh::DEBUG::2014-04-01 13:11:10,818::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:11:10,838::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) storageRefresh::DEBUG::2014-04-01 13:11:10,841::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:11:10,848::iscsi::393::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds storageRefresh::DEBUG::2014-04-01 13:11:10,855::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None) storageRefresh::DEBUG::2014-04-01 13:11:10,871::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 13:11:10,872::multipath::110::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath -r' (cwd None) MainThread::DEBUG::2014-04-01 13:11:10,906::task::595::TaskManager.Task::(_updateState) Task=`1bb370e2-557f-4392-93f8-2120ebe30369`::moving from state init -> state preparing MainThread::INFO::2014-04-01 13:11:10,907::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF instance at 0x2c8bfc8>>) MainThread::INFO::2014-04-01 13:11:10,907::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None MainThread::DEBUG::2014-04-01 13:11:10,907::task::1185::TaskManager.Task::(prepare) Task=`1bb370e2-557f-4392-93f8-2120ebe30369`::finished: None MainThread::DEBUG::2014-04-01 13:11:10,907::task::595::TaskManager.Task::(_updateState) Task=`1bb370e2-557f-4392-93f8-2120ebe30369`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 13:11:10,908::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 13:11:10,908::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 13:11:10,908::task::990::TaskManager.Task::(_decref) Task=`1bb370e2-557f-4392-93f8-2120ebe30369`::ref 0 aborting False MainThread::INFO::2014-04-01 13:11:10,917::momIF::47::MOM::(__init__) Starting up MOM MainThread::INFO::2014-04-01 13:11:10,927::vmChannels::187::vds::(settimeout) Setting channels' timeout to 30 seconds. VM Channels Listener::INFO::2014-04-01 13:11:10,936::vmChannels::170::vds::(run) Starting VM channels listener thread. clientIFinit::DEBUG::2014-04-01 13:11:10,959::libvirtconnection::145::root::(get) trying to connect libvirt MainThread::WARNING::2014-04-01 13:11:10,979::clientIF::181::vds::(_prepareBindings) Unable to load the json rpc server module. Please make sure it is installed. storageRefresh::DEBUG::2014-04-01 13:11:10,993::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:11:10,994::lvm::496::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:10,994::lvm::498::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:10,994::lvm::507::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:10,995::lvm::509::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:10,995::lvm::527::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:10,995::lvm::529::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:10,996::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 13:11:10,996::lvm::324::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:10,999::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None) storageRefresh::DEBUG::2014-04-01 13:11:11,209::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:11:11,212::lvm::349::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:11,215::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:11,249::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 13:11:11,396::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:11:11,396::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:11,397::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None) storageRefresh::DEBUG::2014-04-01 13:11:11,539::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:11:11,540::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:11:11,540::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 13:11:11,682::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:11:11,683::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::WARNING::2014-04-01 13:11:11,684::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/hsm-tasks already exists storageRefresh::DEBUG::2014-04-01 13:11:11,685::hsm::391::Storage.HSM::(storageRefresh) HSM is ready Thread-13::DEBUG::2014-04-01 13:11:22,955::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-13::DEBUG::2014-04-01 13:11:23,031::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} MainThread::DEBUG::2014-04-01 13:13:29,535::vdsm::55::vds::(sigtermHandler) Received signal 15 MainThread::INFO::2014-04-01 13:13:30,050::vmChannels::183::vds::(stop) VM channels listener was stopped. MainThread::INFO::2014-04-01 13:13:30,050::momIF::84::MOM::(stop) Shutting down MOM MainThread::DEBUG::2014-04-01 13:13:30,051::task::595::TaskManager.Task::(_updateState) Task=`94e511a0-85eb-48d4-beae-cdd00fac1cbe`::moving from state init -> state preparing MainThread::INFO::2014-04-01 13:13:30,051::logUtils::44::dispatcher::(wrapper) Run and protect: prepareForShutdown(options=None) Thread-11::DEBUG::2014-04-01 13:13:30,052::storageServer::698::ConnectionMonitor::(_monitorConnections) Monitoring stopped VM Channels Listener::INFO::2014-04-01 13:13:30,105::vmChannels::178::vds::(run) VM channels listener thread has ended. MainThread::INFO::2014-04-01 13:13:30,540::domainMonitor::125::Storage.DomainMonitor::(close) Stopping domain monitors MainThread::DEBUG::2014-04-01 13:13:30,540::taskManager::80::TaskManager::(prepareForShutdown) Request to stop all tasks MainThread::INFO::2014-04-01 13:13:30,540::logUtils::47::dispatcher::(wrapper) Run and protect: prepareForShutdown, Return response: None MainThread::DEBUG::2014-04-01 13:13:30,540::task::1185::TaskManager.Task::(prepare) Task=`94e511a0-85eb-48d4-beae-cdd00fac1cbe`::finished: None MainThread::DEBUG::2014-04-01 13:13:30,541::task::595::TaskManager.Task::(_updateState) Task=`94e511a0-85eb-48d4-beae-cdd00fac1cbe`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 13:13:30,541::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 13:13:30,541::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 13:13:30,541::task::990::TaskManager.Task::(_decref) Task=`94e511a0-85eb-48d4-beae-cdd00fac1cbe`::ref 0 aborting False MainThread::INFO::2014-04-01 13:13:30,541::vdsm::125::vds::(run) VDSM main thread ended. Waiting for 15 other threads... MainThread::INFO::2014-04-01 13:13:30,542::vdsm::129::vds::(run) <_MainThread(MainThread, started 140616788940544)> MainThread::INFO::2014-04-01 13:13:30,542::vdsm::129::vds::(run) <Thread(libvirtEventLoop, started daemon 140616522061568)> MainThread::INFO::2014-04-01 13:13:30,542::vdsm::129::vds::(run) <WorkerThread(Thread-2, started daemon 140616423962368)> MainThread::INFO::2014-04-01 13:13:30,542::vdsm::129::vds::(run) <GuestManager(GuestManager, started daemon 140615429867264)> MainThread::INFO::2014-04-01 13:13:30,542::vdsm::129::vds::(run) <HostMonitor(HostMonitor, started daemon 140615440357120)> MainThread::INFO::2014-04-01 13:13:30,542::momIF::84::MOM::(stop) Shutting down MOM MainThread::INFO::2014-04-01 13:13:30,542::vdsm::129::vds::(run) <MomThread(MOM, started 140615484438272)> MainThread::INFO::2014-04-01 13:13:30,542::vdsm::129::vds::(run) <WorkerThread(Thread-7, started daemon 140616371513088)> MainThread::INFO::2014-04-01 13:13:30,543::vdsm::129::vds::(run) <WorkerThread(Thread-6, started daemon 140616382002944)> MainThread::INFO::2014-04-01 13:13:30,543::vdsm::129::vds::(run) <WorkerThread(Thread-9, started daemon 140615943710464)> MainThread::INFO::2014-04-01 13:13:30,543::vdsm::129::vds::(run) <WorkerThread(Thread-5, started daemon 140616392492800)> MainThread::INFO::2014-04-01 13:13:30,543::vdsm::129::vds::(run) <PolicyEngine(PolicyEngine, started daemon 140615473948416)> MainThread::INFO::2014-04-01 13:13:30,543::vdsm::129::vds::(run) <WorkerThread(Thread-8, started daemon 140615954200320)> MainThread::INFO::2014-04-01 13:13:30,543::vdsm::129::vds::(run) <WorkerThread(Thread-4, started daemon 140616402982656)> MainThread::INFO::2014-04-01 13:13:30,543::vdsm::129::vds::(run) <WorkerThread(Thread-3, started daemon 140616413472512)> MainThread::INFO::2014-04-01 13:13:30,544::vdsm::129::vds::(run) <WorkerThread(Thread-1, started daemon 140616511571712)> MainThread::INFO::2014-04-01 13:13:30,544::vdsm::129::vds::(run) <WorkerThread(Thread-10, started daemon 140615933220608)> MainThread::INFO::2014-04-01 13:16:43,521::vdsm::119::vds::(run) (PID: 4430) I am the actual vdsm 4.14.6-0.el6 ovirt01.mydomain (2.6.32-431.11.2.el6.x86_64) MainThread::DEBUG::2014-04-01 13:16:43,522::resourceManager::421::ResourceManager::(registerNamespace) Registering namespace 'Storage' MainThread::DEBUG::2014-04-01 13:16:43,523::threadPool::35::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0 MainThread::DEBUG::2014-04-01 13:16:44,023::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm MainThread::DEBUG::2014-04-01 13:16:44,054::multipath::142::Storage.Multipath::(isEnabled) Current revision of multipath.conf detected, preserving MainThread::DEBUG::2014-04-01 13:16:44,054::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None) MainThread::DEBUG::2014-04-01 13:16:44,258::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 13:16:44,259::hsm::447::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center' MainThread::DEBUG::2014-04-01 13:16:44,264::hsm::479::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt'] MainThread::DEBUG::2014-04-01 13:16:44,264::hsm::480::Storage.HSM::(__cleanStorageRepository) Mount list: [] MainThread::DEBUG::2014-04-01 13:16:44,265::hsm::482::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers MainThread::DEBUG::2014-04-01 13:16:44,266::hsm::525::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center' storageRefresh::DEBUG::2014-04-01 13:16:44,267::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage) MainThread::INFO::2014-04-01 13:16:44,269::dispatcher::97::Storage.Dispatcher::(__init__) Starting StorageDispatcher... storageRefresh::DEBUG::2014-04-01 13:16:44,269::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:16:44,292::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) storageRefresh::DEBUG::2014-04-01 13:16:44,300::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:16:44,306::iscsi::393::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds storageRefresh::DEBUG::2014-04-01 13:16:44,307::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None) storageRefresh::DEBUG::2014-04-01 13:16:44,325::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 13:16:44,326::multipath::110::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath -r' (cwd None) MainThread::DEBUG::2014-04-01 13:16:44,354::task::595::TaskManager.Task::(_updateState) Task=`a3b79013-b275-4de1-b015-9e11f01a74a5`::moving from state init -> state preparing MainThread::INFO::2014-04-01 13:16:44,354::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF instance at 0x20ee9e0>>) MainThread::INFO::2014-04-01 13:16:44,354::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None MainThread::DEBUG::2014-04-01 13:16:44,354::task::1185::TaskManager.Task::(prepare) Task=`a3b79013-b275-4de1-b015-9e11f01a74a5`::finished: None MainThread::DEBUG::2014-04-01 13:16:44,355::task::595::TaskManager.Task::(_updateState) Task=`a3b79013-b275-4de1-b015-9e11f01a74a5`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 13:16:44,355::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 13:16:44,355::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 13:16:44,355::task::990::TaskManager.Task::(_decref) Task=`a3b79013-b275-4de1-b015-9e11f01a74a5`::ref 0 aborting False MainThread::INFO::2014-04-01 13:16:44,367::momIF::47::MOM::(__init__) Starting up MOM MainThread::INFO::2014-04-01 13:16:44,377::vmChannels::187::vds::(settimeout) Setting channels' timeout to 30 seconds. VM Channels Listener::INFO::2014-04-01 13:16:44,381::vmChannels::170::vds::(run) Starting VM channels listener thread. clientIFinit::DEBUG::2014-04-01 13:16:44,399::libvirtconnection::145::root::(get) trying to connect libvirt MainThread::WARNING::2014-04-01 13:16:44,417::clientIF::181::vds::(_prepareBindings) Unable to load the json rpc server module. Please make sure it is installed. storageRefresh::DEBUG::2014-04-01 13:16:44,448::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:16:44,451::lvm::496::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,454::lvm::498::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,457::lvm::507::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,463::lvm::509::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,470::lvm::527::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,476::lvm::529::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,481::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 13:16:44,488::lvm::324::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,524::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None) storageRefresh::DEBUG::2014-04-01 13:16:44,705::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:16:44,709::lvm::349::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,712::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,720::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 13:16:44,863::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:16:44,863::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:44,864::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None) storageRefresh::DEBUG::2014-04-01 13:16:45,006::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:16:45,007::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:45,008::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 13:16:45,149::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:16:45,150::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:16:45,151::hsm::391::Storage.HSM::(storageRefresh) HSM is ready Thread-13::DEBUG::2014-04-01 13:16:45,271::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-13::DEBUG::2014-04-01 13:16:45,344::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-14::DEBUG::2014-04-01 13:20:39,283::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-14::DEBUG::2014-04-01 13:20:39,297::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-15::DEBUG::2014-04-01 13:21:11,414::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-15::DEBUG::2014-04-01 13:21:11,423::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} MainThread::DEBUG::2014-04-01 13:22:10,217::vdsm::55::vds::(sigtermHandler) Received signal 15 MainThread::INFO::2014-04-01 13:22:10,439::vmChannels::183::vds::(stop) VM channels listener was stopped. MainThread::INFO::2014-04-01 13:22:10,439::momIF::84::MOM::(stop) Shutting down MOM MainThread::DEBUG::2014-04-01 13:22:10,440::task::595::TaskManager.Task::(_updateState) Task=`d46a208f-52dd-4136-9bcf-faa8cc47c55f`::moving from state init -> state preparing MainThread::INFO::2014-04-01 13:22:10,440::logUtils::44::dispatcher::(wrapper) Run and protect: prepareForShutdown(options=None) Thread-11::DEBUG::2014-04-01 13:22:10,441::storageServer::698::ConnectionMonitor::(_monitorConnections) Monitoring stopped VM Channels Listener::INFO::2014-04-01 13:22:10,785::vmChannels::178::vds::(run) VM channels listener thread has ended. MainThread::INFO::2014-04-01 13:22:10,889::domainMonitor::125::Storage.DomainMonitor::(close) Stopping domain monitors MainThread::DEBUG::2014-04-01 13:22:10,889::taskManager::80::TaskManager::(prepareForShutdown) Request to stop all tasks MainThread::INFO::2014-04-01 13:22:10,889::logUtils::47::dispatcher::(wrapper) Run and protect: prepareForShutdown, Return response: None MainThread::DEBUG::2014-04-01 13:22:10,889::task::1185::TaskManager.Task::(prepare) Task=`d46a208f-52dd-4136-9bcf-faa8cc47c55f`::finished: None MainThread::DEBUG::2014-04-01 13:22:10,889::task::595::TaskManager.Task::(_updateState) Task=`d46a208f-52dd-4136-9bcf-faa8cc47c55f`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 13:22:10,890::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 13:22:10,890::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 13:22:10,890::task::990::TaskManager.Task::(_decref) Task=`d46a208f-52dd-4136-9bcf-faa8cc47c55f`::ref 0 aborting False MainThread::INFO::2014-04-01 13:22:10,890::vdsm::125::vds::(run) VDSM main thread ended. Waiting for 15 other threads... MainThread::INFO::2014-04-01 13:22:10,890::vdsm::129::vds::(run) <_MainThread(MainThread, started 140406153848576)> MainThread::INFO::2014-04-01 13:22:10,890::vdsm::129::vds::(run) <Thread(libvirtEventLoop, started daemon 140405886969600)> MainThread::INFO::2014-04-01 13:22:10,891::vdsm::129::vds::(run) <WorkerThread(Thread-10, started daemon 140405165258496)> MainThread::INFO::2014-04-01 13:22:10,891::vdsm::129::vds::(run) <WorkerThread(Thread-5, started daemon 140405624530688)> MainThread::INFO::2014-04-01 13:22:10,891::vdsm::129::vds::(run) <WorkerThread(Thread-9, started daemon 140405582571264)> MainThread::INFO::2014-04-01 13:22:10,891::vdsm::129::vds::(run) <WorkerThread(Thread-8, started daemon 140405593061120)> MainThread::INFO::2014-04-01 13:22:10,891::vdsm::129::vds::(run) <WorkerThread(Thread-7, started daemon 140405603550976)> MainThread::INFO::2014-04-01 13:22:10,891::vdsm::129::vds::(run) <WorkerThread(Thread-6, started daemon 140405614040832)> MainThread::INFO::2014-04-01 13:22:10,891::vdsm::129::vds::(run) <WorkerThread(Thread-3, started daemon 140405855500032)> MainThread::INFO::2014-04-01 13:22:10,892::vdsm::129::vds::(run) <WorkerThread(Thread-2, started daemon 140405865989888)> MainThread::INFO::2014-04-01 13:22:10,892::vdsm::129::vds::(run) <WorkerThread(Thread-1, started daemon 140405876479744)> MainThread::INFO::2014-04-01 13:22:10,892::vdsm::129::vds::(run) <WorkerThread(Thread-4, started daemon 140405635020544)> MainThread::INFO::2014-04-01 13:22:10,892::momIF::84::MOM::(stop) Shutting down MOM MainThread::INFO::2014-04-01 13:22:10,892::vdsm::129::vds::(run) <MomThread(MOM, started 140405123299072)> MainThread::INFO::2014-04-01 13:22:10,892::vdsm::129::vds::(run) <PolicyEngine(PolicyEngine, started daemon 140405112809216)> MainThread::INFO::2014-04-01 13:22:10,892::vdsm::129::vds::(run) <GuestManager(GuestManager, started daemon 140404659783424)> MainThread::INFO::2014-04-01 13:22:10,892::vdsm::129::vds::(run) <HostMonitor(HostMonitor, started daemon 140404670273280)> MainThread::INFO::2014-04-01 13:22:28,275::vdsm::119::vds::(run) (PID: 5891) I am the actual vdsm 4.14.6-0.el6 ovirt01.mydomain (2.6.32-431.11.2.el6.x86_64) MainThread::DEBUG::2014-04-01 13:22:28,283::resourceManager::421::ResourceManager::(registerNamespace) Registering namespace 'Storage' MainThread::DEBUG::2014-04-01 13:22:28,283::threadPool::35::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0 MainThread::WARNING::2014-04-01 13:22:28,289::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/mnt already exists MainThread::DEBUG::2014-04-01 13:22:28,786::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm MainThread::DEBUG::2014-04-01 13:22:28,809::multipath::142::Storage.Multipath::(isEnabled) Current revision of multipath.conf detected, preserving MainThread::DEBUG::2014-04-01 13:22:28,809::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None) MainThread::DEBUG::2014-04-01 13:22:29,015::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 13:22:29,016::hsm::447::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center' MainThread::DEBUG::2014-04-01 13:22:29,020::hsm::479::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt'] MainThread::DEBUG::2014-04-01 13:22:29,021::hsm::480::Storage.HSM::(__cleanStorageRepository) Mount list: [] MainThread::DEBUG::2014-04-01 13:22:29,021::hsm::482::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers MainThread::DEBUG::2014-04-01 13:22:29,022::hsm::525::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center' MainThread::INFO::2014-04-01 13:22:29,024::dispatcher::97::Storage.Dispatcher::(__init__) Starting StorageDispatcher... storageRefresh::DEBUG::2014-04-01 13:22:29,024::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage) storageRefresh::DEBUG::2014-04-01 13:22:29,044::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:22:29,048::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) storageRefresh::DEBUG::2014-04-01 13:22:29,055::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:22:29,062::iscsi::393::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds storageRefresh::DEBUG::2014-04-01 13:22:29,063::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None) storageRefresh::DEBUG::2014-04-01 13:22:29,080::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 13:22:29,081::multipath::110::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath -r' (cwd None) MainThread::DEBUG::2014-04-01 13:22:29,114::task::595::TaskManager.Task::(_updateState) Task=`c687f922-eb71-4870-89f2-79997db11eb2`::moving from state init -> state preparing MainThread::INFO::2014-04-01 13:22:29,115::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF instance at 0x1b73fc8>>) MainThread::INFO::2014-04-01 13:22:29,115::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None MainThread::DEBUG::2014-04-01 13:22:29,115::task::1185::TaskManager.Task::(prepare) Task=`c687f922-eb71-4870-89f2-79997db11eb2`::finished: None MainThread::DEBUG::2014-04-01 13:22:29,115::task::595::TaskManager.Task::(_updateState) Task=`c687f922-eb71-4870-89f2-79997db11eb2`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 13:22:29,115::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 13:22:29,116::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 13:22:29,116::task::990::TaskManager.Task::(_decref) Task=`c687f922-eb71-4870-89f2-79997db11eb2`::ref 0 aborting False MainThread::INFO::2014-04-01 13:22:29,127::momIF::47::MOM::(__init__) Starting up MOM MainThread::INFO::2014-04-01 13:22:29,130::vmChannels::187::vds::(settimeout) Setting channels' timeout to 30 seconds. clientIFinit::DEBUG::2014-04-01 13:22:29,147::libvirtconnection::145::root::(get) trying to connect libvirt VM Channels Listener::INFO::2014-04-01 13:22:29,189::vmChannels::170::vds::(run) Starting VM channels listener thread. storageRefresh::DEBUG::2014-04-01 13:22:29,229::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:22:29,256::lvm::496::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,262::lvm::498::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,275::lvm::507::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,283::lvm::509::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,293::lvm::527::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,311::lvm::529::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,313::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 13:22:29,322::lvm::324::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,411::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None) storageRefresh::DEBUG::2014-04-01 13:22:29,637::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:22:29,638::lvm::349::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,638::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,639::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 13:22:29,789::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:22:29,789::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,790::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None) storageRefresh::DEBUG::2014-04-01 13:22:29,933::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:22:29,934::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:22:29,935::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) Thread-13::DEBUG::2014-04-01 13:22:30,032::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-13::DEBUG::2014-04-01 13:22:30,032::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Recovering from crash or Initializing', 'code': 99}} storageRefresh::DEBUG::2014-04-01 13:22:30,078::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:22:30,079::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::WARNING::2014-04-01 13:22:30,079::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/hsm-tasks already exists storageRefresh::DEBUG::2014-04-01 13:22:30,079::hsm::391::Storage.HSM::(storageRefresh) HSM is ready Thread-14::DEBUG::2014-04-01 13:22:31,092::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-14::DEBUG::2014-04-01 13:22:31,166::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} MainThread::DEBUG::2014-04-01 13:43:11,588::vdsm::55::vds::(sigtermHandler) Received signal 15 MainThread::INFO::2014-04-01 13:43:12,407::vmChannels::183::vds::(stop) VM channels listener was stopped. MainThread::INFO::2014-04-01 13:43:12,407::momIF::84::MOM::(stop) Shutting down MOM MainThread::DEBUG::2014-04-01 13:43:12,408::task::595::TaskManager.Task::(_updateState) Task=`d71c704e-bf1f-4d6d-97c2-41a9f97087d3`::moving from state init -> state preparing MainThread::INFO::2014-04-01 13:43:12,408::logUtils::44::dispatcher::(wrapper) Run and protect: prepareForShutdown(options=None) Thread-11::DEBUG::2014-04-01 13:43:12,410::storageServer::698::ConnectionMonitor::(_monitorConnections) Monitoring stopped VM Channels Listener::INFO::2014-04-01 13:43:12,529::vmChannels::178::vds::(run) VM channels listener thread has ended. MainThread::INFO::2014-04-01 13:43:12,872::domainMonitor::125::Storage.DomainMonitor::(close) Stopping domain monitors MainThread::DEBUG::2014-04-01 13:43:12,872::taskManager::80::TaskManager::(prepareForShutdown) Request to stop all tasks MainThread::INFO::2014-04-01 13:43:12,872::logUtils::47::dispatcher::(wrapper) Run and protect: prepareForShutdown, Return response: None MainThread::DEBUG::2014-04-01 13:43:12,873::task::1185::TaskManager.Task::(prepare) Task=`d71c704e-bf1f-4d6d-97c2-41a9f97087d3`::finished: None MainThread::DEBUG::2014-04-01 13:43:12,873::task::595::TaskManager.Task::(_updateState) Task=`d71c704e-bf1f-4d6d-97c2-41a9f97087d3`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 13:43:12,873::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 13:43:12,873::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 13:43:12,873::task::990::TaskManager.Task::(_decref) Task=`d71c704e-bf1f-4d6d-97c2-41a9f97087d3`::ref 0 aborting False MainThread::INFO::2014-04-01 13:43:12,873::vdsm::125::vds::(run) VDSM main thread ended. Waiting for 15 other threads... MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <_MainThread(MainThread, started 140370161301248)> MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <Thread(libvirtEventLoop, started daemon 140369894422272)> MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <WorkerThread(Thread-8, started daemon 140369329125120)> MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <WorkerThread(Thread-6, started daemon 140369756927744)> MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <WorkerThread(Thread-1, started daemon 140369883932416)> MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <WorkerThread(Thread-9, started daemon 140369318635264)> MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <WorkerThread(Thread-3, started daemon 140369788397312)> MainThread::INFO::2014-04-01 13:43:12,874::vdsm::129::vds::(run) <WorkerThread(Thread-5, started daemon 140369767417600)> MainThread::INFO::2014-04-01 13:43:12,875::vdsm::129::vds::(run) <GuestManager(GuestManager, started daemon 140368848873216)> MainThread::INFO::2014-04-01 13:43:12,875::vdsm::129::vds::(run) <WorkerThread(Thread-10, started daemon 140369308145408)> MainThread::INFO::2014-04-01 13:43:12,875::momIF::84::MOM::(stop) Shutting down MOM MainThread::INFO::2014-04-01 13:43:12,875::vdsm::129::vds::(run) <MomThread(MOM, started 140368859363072)> MainThread::INFO::2014-04-01 13:43:12,875::vdsm::129::vds::(run) <PolicyEngine(PolicyEngine, started daemon 140368368621312)> MainThread::INFO::2014-04-01 13:43:12,875::vdsm::129::vds::(run) <HostMonitor(HostMonitor, started daemon 140368827893504)> MainThread::INFO::2014-04-01 13:43:12,875::vdsm::129::vds::(run) <WorkerThread(Thread-2, started daemon 140369798887168)> MainThread::INFO::2014-04-01 13:43:12,876::vdsm::129::vds::(run) <WorkerThread(Thread-4, started daemon 140369777907456)> MainThread::INFO::2014-04-01 13:43:12,876::vdsm::129::vds::(run) <WorkerThread(Thread-7, started daemon 140369746437888)> MainThread::INFO::2014-04-01 13:45:23,951::vdsm::119::vds::(run) (PID: 1960) I am the actual vdsm 4.14.6-0.el6 ovirt01.mydomain (2.6.32-431.11.2.el6.x86_64) MainThread::DEBUG::2014-04-01 13:45:23,962::resourceManager::421::ResourceManager::(registerNamespace) Registering namespace 'Storage' MainThread::DEBUG::2014-04-01 13:45:23,962::threadPool::35::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0 MainThread::WARNING::2014-04-01 13:45:23,976::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/mnt already exists MainThread::DEBUG::2014-04-01 13:45:24,512::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm MainThread::DEBUG::2014-04-01 13:45:24,567::multipath::142::Storage.Multipath::(isEnabled) Current revision of multipath.conf detected, preserving MainThread::DEBUG::2014-04-01 13:45:24,567::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None) MainThread::DEBUG::2014-04-01 13:45:24,765::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 13:45:24,766::hsm::447::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center' MainThread::DEBUG::2014-04-01 13:45:24,770::hsm::479::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt'] MainThread::DEBUG::2014-04-01 13:45:24,771::hsm::480::Storage.HSM::(__cleanStorageRepository) Mount list: [] MainThread::DEBUG::2014-04-01 13:45:24,771::hsm::482::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers MainThread::DEBUG::2014-04-01 13:45:24,772::hsm::525::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center' storageRefresh::DEBUG::2014-04-01 13:45:24,772::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage) MainThread::INFO::2014-04-01 13:45:24,774::dispatcher::97::Storage.Dispatcher::(__init__) Starting StorageDispatcher... storageRefresh::DEBUG::2014-04-01 13:45:24,775::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:45:24,946::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) storageRefresh::DEBUG::2014-04-01 13:45:24,950::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 13:45:24,982::iscsi::393::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds storageRefresh::DEBUG::2014-04-01 13:45:25,011::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None) storageRefresh::DEBUG::2014-04-01 13:45:25,071::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 13:45:25,072::multipath::110::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath -r' (cwd None) storageRefresh::DEBUG::2014-04-01 13:45:25,153::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 13:45:25,153::task::595::TaskManager.Task::(_updateState) Task=`ce193c89-44a5-4e88-a22a-85954714c93f`::moving from state init -> state preparing storageRefresh::DEBUG::2014-04-01 13:45:25,154::lvm::496::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex MainThread::INFO::2014-04-01 13:45:25,161::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF instance at 0x2918098>>) storageRefresh::DEBUG::2014-04-01 13:45:25,162::lvm::498::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex MainThread::INFO::2014-04-01 13:45:25,162::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None storageRefresh::DEBUG::2014-04-01 13:45:25,162::lvm::507::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex MainThread::DEBUG::2014-04-01 13:45:25,163::task::1185::TaskManager.Task::(prepare) Task=`ce193c89-44a5-4e88-a22a-85954714c93f`::finished: None storageRefresh::DEBUG::2014-04-01 13:45:25,163::lvm::509::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex MainThread::DEBUG::2014-04-01 13:45:25,163::task::595::TaskManager.Task::(_updateState) Task=`ce193c89-44a5-4e88-a22a-85954714c93f`::moving from state preparing -> state finished storageRefresh::DEBUG::2014-04-01 13:45:25,164::lvm::527::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex MainThread::DEBUG::2014-04-01 13:45:25,164::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} storageRefresh::DEBUG::2014-04-01 13:45:25,164::lvm::529::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex MainThread::DEBUG::2014-04-01 13:45:25,165::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} storageRefresh::DEBUG::2014-04-01 13:45:25,165::misc::772::SamplingMethod::(__call__) Returning last result MainThread::DEBUG::2014-04-01 13:45:25,166::task::990::TaskManager.Task::(_decref) Task=`ce193c89-44a5-4e88-a22a-85954714c93f`::ref 0 aborting False storageRefresh::DEBUG::2014-04-01 13:45:25,166::lvm::324::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:45:25,170::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None) MainThread::INFO::2014-04-01 13:45:25,181::momIF::47::MOM::(__init__) Starting up MOM MainThread::INFO::2014-04-01 13:45:25,191::vmChannels::187::vds::(settimeout) Setting channels' timeout to 30 seconds. VM Channels Listener::INFO::2014-04-01 13:45:25,194::vmChannels::170::vds::(run) Starting VM channels listener thread. clientIFinit::DEBUG::2014-04-01 13:45:25,305::libvirtconnection::145::root::(get) trying to connect libvirt storageRefresh::DEBUG::2014-04-01 13:45:25,372::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:45:25,406::lvm::349::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:45:25,422::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:45:25,431::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 13:45:25,619::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:45:25,622::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 13:45:25,629::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None) storageRefresh::DEBUG::2014-04-01 13:45:25,803::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:45:25,804::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 13:45:25,804::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 13:45:25,968::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 13:45:25,969::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::WARNING::2014-04-01 13:45:25,970::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/hsm-tasks already exists storageRefresh::DEBUG::2014-04-01 13:45:25,978::hsm::391::Storage.HSM::(storageRefresh) HSM is ready Thread-13::DEBUG::2014-04-01 13:47:59,432::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-13::DEBUG::2014-04-01 13:47:59,569::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-14::DEBUG::2014-04-01 13:49:32,208::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-14::DEBUG::2014-04-01 13:49:32,240::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-15::DEBUG::2014-04-01 13:51:17,857::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-15::DEBUG::2014-04-01 13:51:17,888::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-16::DEBUG::2014-04-01 13:57:54,486::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-16::DEBUG::2014-04-01 13:57:54,510::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-17::DEBUG::2014-04-01 14:02:37,349::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-17::DEBUG::2014-04-01 14:02:37,360::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-18::DEBUG::2014-04-01 14:24:48,882::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-18::DEBUG::2014-04-01 14:24:48,931::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-19::DEBUG::2014-04-01 14:26:09,043::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-19::DEBUG::2014-04-01 14:26:09,067::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} MainThread::DEBUG::2014-04-01 14:26:18,534::vdsm::55::vds::(sigtermHandler) Received signal 15 MainThread::INFO::2014-04-01 14:26:19,015::vmChannels::183::vds::(stop) VM channels listener was stopped. MainThread::INFO::2014-04-01 14:26:19,015::momIF::84::MOM::(stop) Shutting down MOM MainThread::DEBUG::2014-04-01 14:26:19,016::task::595::TaskManager.Task::(_updateState) Task=`bba14d83-2a49-4970-8f76-2be58762768a`::moving from state init -> state preparing MainThread::INFO::2014-04-01 14:26:19,016::logUtils::44::dispatcher::(wrapper) Run and protect: prepareForShutdown(options=None) Thread-11::DEBUG::2014-04-01 14:26:19,051::storageServer::698::ConnectionMonitor::(_monitorConnections) Monitoring stopped VM Channels Listener::INFO::2014-04-01 14:26:19,075::vmChannels::178::vds::(run) VM channels listener thread has ended. MainThread::INFO::2014-04-01 14:26:19,527::domainMonitor::125::Storage.DomainMonitor::(close) Stopping domain monitors MainThread::DEBUG::2014-04-01 14:26:19,528::taskManager::80::TaskManager::(prepareForShutdown) Request to stop all tasks MainThread::INFO::2014-04-01 14:26:19,528::logUtils::47::dispatcher::(wrapper) Run and protect: prepareForShutdown, Return response: None MainThread::DEBUG::2014-04-01 14:26:19,528::task::1185::TaskManager.Task::(prepare) Task=`bba14d83-2a49-4970-8f76-2be58762768a`::finished: None MainThread::DEBUG::2014-04-01 14:26:19,528::task::595::TaskManager.Task::(_updateState) Task=`bba14d83-2a49-4970-8f76-2be58762768a`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 14:26:19,528::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 14:26:19,528::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 14:26:19,529::task::990::TaskManager.Task::(_decref) Task=`bba14d83-2a49-4970-8f76-2be58762768a`::ref 0 aborting False MainThread::INFO::2014-04-01 14:26:19,529::vdsm::125::vds::(run) VDSM main thread ended. Waiting for 15 other threads... MainThread::INFO::2014-04-01 14:26:19,529::vdsm::129::vds::(run) <_MainThread(MainThread, started 139946453554944)> MainThread::INFO::2014-04-01 14:26:19,529::vdsm::129::vds::(run) <Thread(libvirtEventLoop, started daemon 139946186409728)> MainThread::INFO::2014-04-01 14:26:19,529::vdsm::129::vds::(run) <WorkerThread(Thread-4, started daemon 139945939302144)> MainThread::INFO::2014-04-01 14:26:19,529::vdsm::129::vds::(run) <PolicyEngine(PolicyEngine, started daemon 139944530016000)> MainThread::INFO::2014-04-01 14:26:19,529::vdsm::129::vds::(run) <WorkerThread(Thread-9, started daemon 139945886852864)> MainThread::INFO::2014-04-01 14:26:19,530::vdsm::129::vds::(run) <WorkerThread(Thread-8, started daemon 139945897342720)> MainThread::INFO::2014-04-01 14:26:19,530::vdsm::129::vds::(run) <WorkerThread(Thread-7, started daemon 139945907832576)> MainThread::INFO::2014-04-01 14:26:19,530::vdsm::129::vds::(run) <WorkerThread(Thread-5, started daemon 139945928812288)> MainThread::INFO::2014-04-01 14:26:19,530::vdsm::129::vds::(run) <WorkerThread(Thread-10, started daemon 139945469540096)> MainThread::INFO::2014-04-01 14:26:19,530::vdsm::129::vds::(run) <GuestManager(GuestManager, started daemon 139944980776704)> MainThread::INFO::2014-04-01 14:26:19,530::vdsm::129::vds::(run) <WorkerThread(Thread-3, started daemon 139946154940160)> MainThread::INFO::2014-04-01 14:26:19,530::vdsm::129::vds::(run) <WorkerThread(Thread-2, started daemon 139946165430016)> MainThread::INFO::2014-04-01 14:26:19,531::vdsm::129::vds::(run) <WorkerThread(Thread-1, started daemon 139946175919872)> MainThread::INFO::2014-04-01 14:26:19,531::momIF::84::MOM::(stop) Shutting down MOM MainThread::INFO::2014-04-01 14:26:19,531::vdsm::129::vds::(run) <MomThread(MOM, started 139945427580672)> MainThread::INFO::2014-04-01 14:26:19,531::vdsm::129::vds::(run) <HostMonitor(HostMonitor, started daemon 139945417090816)> MainThread::INFO::2014-04-01 14:26:19,531::vdsm::129::vds::(run) <WorkerThread(Thread-6, started daemon 139945918322432)> MainThread::INFO::2014-04-01 14:26:38,227::vdsm::119::vds::(run) (PID: 5104) I am the actual vdsm 4.14.6-0.el6 ovirt01.mydomain (2.6.32-431.11.2.el6.x86_64) MainThread::DEBUG::2014-04-01 14:26:38,228::resourceManager::421::ResourceManager::(registerNamespace) Registering namespace 'Storage' MainThread::DEBUG::2014-04-01 14:26:38,228::threadPool::35::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0 MainThread::WARNING::2014-04-01 14:26:38,233::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/mnt already exists MainThread::DEBUG::2014-04-01 14:26:38,781::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm MainThread::DEBUG::2014-04-01 14:26:38,817::multipath::142::Storage.Multipath::(isEnabled) Current revision of multipath.conf detected, preserving MainThread::DEBUG::2014-04-01 14:26:38,817::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None) MainThread::DEBUG::2014-04-01 14:26:38,999::hsm::423::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0 MainThread::DEBUG::2014-04-01 14:26:38,999::hsm::447::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center' MainThread::DEBUG::2014-04-01 14:26:39,003::hsm::479::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt'] MainThread::DEBUG::2014-04-01 14:26:39,003::hsm::480::Storage.HSM::(__cleanStorageRepository) Mount list: [] MainThread::DEBUG::2014-04-01 14:26:39,003::hsm::482::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers MainThread::DEBUG::2014-04-01 14:26:39,005::hsm::525::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center' MainThread::INFO::2014-04-01 14:26:39,007::dispatcher::97::Storage.Dispatcher::(__init__) Starting StorageDispatcher... storageRefresh::DEBUG::2014-04-01 14:26:39,007::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage) storageRefresh::DEBUG::2014-04-01 14:26:39,060::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 14:26:39,075::misc::762::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan) storageRefresh::DEBUG::2014-04-01 14:26:39,075::misc::764::SamplingMethod::(__call__) Got in to sampling method storageRefresh::DEBUG::2014-04-01 14:26:39,078::iscsi::393::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds storageRefresh::DEBUG::2014-04-01 14:26:39,078::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None) storageRefresh::DEBUG::2014-04-01 14:26:39,118::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 14:26:39,119::multipath::110::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath -r' (cwd None) MainThread::DEBUG::2014-04-01 14:26:39,159::task::595::TaskManager.Task::(_updateState) Task=`87e43e15-0963-43c4-be68-68466249fab0`::moving from state init -> state preparing MainThread::INFO::2014-04-01 14:26:39,161::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF instance at 0x18c53f8>>) MainThread::INFO::2014-04-01 14:26:39,161::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None MainThread::DEBUG::2014-04-01 14:26:39,161::task::1185::TaskManager.Task::(prepare) Task=`87e43e15-0963-43c4-be68-68466249fab0`::finished: None MainThread::DEBUG::2014-04-01 14:26:39,161::task::595::TaskManager.Task::(_updateState) Task=`87e43e15-0963-43c4-be68-68466249fab0`::moving from state preparing -> state finished MainThread::DEBUG::2014-04-01 14:26:39,161::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} MainThread::DEBUG::2014-04-01 14:26:39,162::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} MainThread::DEBUG::2014-04-01 14:26:39,162::task::990::TaskManager.Task::(_decref) Task=`87e43e15-0963-43c4-be68-68466249fab0`::ref 0 aborting False MainThread::INFO::2014-04-01 14:26:39,172::momIF::47::MOM::(__init__) Starting up MOM MainThread::INFO::2014-04-01 14:26:39,188::vmChannels::187::vds::(settimeout) Setting channels' timeout to 30 seconds. VM Channels Listener::INFO::2014-04-01 14:26:39,190::vmChannels::170::vds::(run) Starting VM channels listener thread. clientIFinit::DEBUG::2014-04-01 14:26:39,233::libvirtconnection::145::root::(get) trying to connect libvirt storageRefresh::DEBUG::2014-04-01 14:26:39,235::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 14:26:39,236::lvm::496::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,241::lvm::498::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,241::lvm::507::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,242::lvm::509::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,242::lvm::527::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,242::lvm::529::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,243::misc::772::SamplingMethod::(__call__) Returning last result storageRefresh::DEBUG::2014-04-01 14:26:39,243::lvm::324::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,329::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None) storageRefresh::DEBUG::2014-04-01 14:26:39,547::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0 storageRefresh::DEBUG::2014-04-01 14:26:39,550::lvm::349::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,579::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,588::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 14:26:39,751::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 14:26:39,751::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,752::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None) Thread-13::DEBUG::2014-04-01 14:26:39,863::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-13::DEBUG::2014-04-01 14:26:39,863::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Recovering from crash or Initializing', 'code': 99}} storageRefresh::DEBUG::2014-04-01 14:26:39,913::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 14:26:39,914::lvm::372::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex storageRefresh::DEBUG::2014-04-01 14:26:39,915::lvm::295::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [ \'r|.*|\' ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name' (cwd None) storageRefresh::DEBUG::2014-04-01 14:26:40,055::lvm::295::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ' No volume groups found\n'; <rc> = 0 storageRefresh::DEBUG::2014-04-01 14:26:40,056::lvm::414::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex storageRefresh::WARNING::2014-04-01 14:26:40,057::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/hsm-tasks already exists storageRefresh::DEBUG::2014-04-01 14:26:40,062::hsm::391::Storage.HSM::(storageRefresh) HSM is ready Thread-14::DEBUG::2014-04-01 14:26:40,924::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-14::DEBUG::2014-04-01 14:26:41,026::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-15::DEBUG::2014-04-01 14:48:06,601::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-15::DEBUG::2014-04-01 14:48:06,623::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-16::DEBUG::2014-04-01 14:51:28,992::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-16::DEBUG::2014-04-01 14:51:29,020::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-17::DEBUG::2014-04-01 15:04:55,803::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-17::DEBUG::2014-04-01 15:04:55,813::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-18::DEBUG::2014-04-01 15:07:59,091::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-18::DEBUG::2014-04-01 15:07:59,107::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-19::DEBUG::2014-04-01 15:10:26,674::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-19::DEBUG::2014-04-01 15:10:26,682::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-20::DEBUG::2014-04-01 15:22:09,047::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-20::DEBUG::2014-04-01 15:22:09,056::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-21::DEBUG::2014-04-01 15:22:32,925::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-21::DEBUG::2014-04-01 15:22:32,928::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} Thread-22::DEBUG::2014-04-01 15:30:32,100::BindingXMLRPC::1067::vds::(wrapper) client [127.0.0.1]::call getHardwareInfo with () {} Thread-22::DEBUG::2014-04-01 15:30:32,110::BindingXMLRPC::1074::vds::(wrapper) return getHardwareInfo with {'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'}} ------=_Part_594693_2003216810.1396362976018 Content-Type: text/x-log Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename=supervdsm.log MainThread::DEBUG::2014-04-01 12:50:10,809::supervdsmServer::424::SuperVdsm.Server::(main) Terminated normally MainThread::DEBUG::2014-04-01 12:50:12,851::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 12:50:12,851::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 12:50:12,873::supervdsmServer::384::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2014-04-01 12:50:12,873::supervdsmServer::393::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2014-04-01 12:50:12,873::supervdsmServer::396::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2014-04-01 12:50:12,874::supervdsmServer::400::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2014-04-01 12:50:12,874::supervdsmServer::406::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2014-04-01 12:50:12,880::supervdsmServer::417::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2014-04-01 12:50:12,880::sourceRouteThread::56::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started MainProcess::DEBUG::2014-04-01 12:50:16,019::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {} MainProcess::DEBUG::2014-04-01 12:50:16,020::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', ' polling_interval 5', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', ' no_path_retry fail', ' user_friendly_names no', ' flush_on_last_del yes', ' fast_io_fail_tmo 5', ' dev_loss_tmo 30', ' max_fds 4096', '}', '', 'devices {', 'device {', ' vendor "HITACHI"', ' product "DF.*"', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', ' vendor "COMPELNT"', ' product "Compellent Vol"', ' no_path_retry fail', '}', '}'] MainProcess|PolicyEngine::DEBUG::2014-04-01 12:50:26,756::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {} MainProcess|PolicyEngine::DEBUG::2014-04-01 12:50:26,761::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainThread::DEBUG::2014-04-01 12:50:40,269::supervdsmServer::424::SuperVdsm.Server::(main) Terminated normally MainThread::DEBUG::2014-04-01 12:52:17,924::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 12:52:17,933::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 12:52:18,005::supervdsmServer::384::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2014-04-01 12:52:18,005::supervdsmServer::393::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2014-04-01 12:52:18,006::supervdsmServer::396::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2014-04-01 12:52:18,006::supervdsmServer::400::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2014-04-01 12:52:18,006::supervdsmServer::406::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2014-04-01 12:52:18,008::supervdsmServer::417::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2014-04-01 12:52:18,008::sourceRouteThread::56::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started MainProcess::DEBUG::2014-04-01 12:52:42,649::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {} MainProcess::DEBUG::2014-04-01 12:52:42,650::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', ' polling_interval 5', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', ' no_path_retry fail', ' user_friendly_names no', ' flush_on_last_del yes', ' fast_io_fail_tmo 5', ' dev_loss_tmo 30', ' max_fds 4096', '}', '', 'devices {', 'device {', ' vendor "HITACHI"', ' product "DF.*"', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', ' vendor "COMPELNT"', ' product "Compellent Vol"', ' no_path_retry fail', '}', '}'] MainProcess|PolicyEngine::DEBUG::2014-04-01 12:52:53,805::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {} MainProcess|PolicyEngine::DEBUG::2014-04-01 12:52:53,806::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainProcess|Thread-13::DEBUG::2014-04-01 12:55:50,816::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-13::DEBUG::2014-04-01 12:55:50,928::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-14::DEBUG::2014-04-01 13:02:22,284::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-14::DEBUG::2014-04-01 13:02:22,285::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-15::DEBUG::2014-04-01 13:04:41,955::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-15::DEBUG::2014-04-01 13:04:41,955::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-16::DEBUG::2014-04-01 13:04:49,776::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-16::DEBUG::2014-04-01 13:04:49,777::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-17::DEBUG::2014-04-01 13:06:19,192::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-17::DEBUG::2014-04-01 13:06:19,192::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainThread::DEBUG::2014-04-01 13:11:01,435::supervdsmServer::424::SuperVdsm.Server::(main) Terminated normally MainThread::DEBUG::2014-04-01 13:11:07,331::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:11:07,332::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:11:07,352::supervdsmServer::384::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2014-04-01 13:11:07,353::supervdsmServer::393::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2014-04-01 13:11:07,353::supervdsmServer::396::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2014-04-01 13:11:07,353::supervdsmServer::400::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2014-04-01 13:11:07,353::supervdsmServer::406::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2014-04-01 13:11:07,357::supervdsmServer::417::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2014-04-01 13:11:07,358::sourceRouteThread::56::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started MainProcess::DEBUG::2014-04-01 13:11:10,614::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {} MainProcess::DEBUG::2014-04-01 13:11:10,615::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', ' polling_interval 5', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', ' no_path_retry fail', ' user_friendly_names no', ' flush_on_last_del yes', ' fast_io_fail_tmo 5', ' dev_loss_tmo 30', ' max_fds 4096', '}', '', 'devices {', 'device {', ' vendor "HITACHI"', ' product "DF.*"', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', ' vendor "COMPELNT"', ' product "Compellent Vol"', ' no_path_retry fail', '}', '}'] MainProcess|PolicyEngine::DEBUG::2014-04-01 13:11:21,387::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {} MainProcess|PolicyEngine::DEBUG::2014-04-01 13:11:21,388::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainProcess|Thread-13::DEBUG::2014-04-01 13:11:22,959::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-13::DEBUG::2014-04-01 13:11:23,031::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainThread::DEBUG::2014-04-01 13:13:31,965::supervdsmServer::424::SuperVdsm.Server::(main) Terminated normally MainThread::DEBUG::2014-04-01 13:16:40,783::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:16:40,783::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:16:40,806::supervdsmServer::384::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2014-04-01 13:16:40,807::supervdsmServer::393::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2014-04-01 13:16:40,807::supervdsmServer::396::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2014-04-01 13:16:40,807::supervdsmServer::400::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2014-04-01 13:16:40,807::supervdsmServer::406::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2014-04-01 13:16:40,809::supervdsmServer::417::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2014-04-01 13:16:40,809::sourceRouteThread::56::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started MainProcess::DEBUG::2014-04-01 13:16:44,053::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {} MainProcess::DEBUG::2014-04-01 13:16:44,053::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', ' polling_interval 5', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', ' no_path_retry fail', ' user_friendly_names no', ' flush_on_last_del yes', ' fast_io_fail_tmo 5', ' dev_loss_tmo 30', ' max_fds 4096', '}', '', 'devices {', 'device {', ' vendor "HITACHI"', ' product "DF.*"', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', ' vendor "COMPELNT"', ' product "Compellent Vol"', ' no_path_retry fail', '}', '}'] MainProcess|Thread-13::DEBUG::2014-04-01 13:16:45,279::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-13::DEBUG::2014-04-01 13:16:45,343::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|PolicyEngine::DEBUG::2014-04-01 13:16:54,860::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {} MainProcess|PolicyEngine::DEBUG::2014-04-01 13:16:54,860::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainProcess|Thread-14::DEBUG::2014-04-01 13:20:39,297::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-14::DEBUG::2014-04-01 13:20:39,297::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-15::DEBUG::2014-04-01 13:21:11,423::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-15::DEBUG::2014-04-01 13:21:11,423::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainThread::DEBUG::2014-04-01 13:22:22,074::supervdsmServer::424::SuperVdsm.Server::(main) Terminated normally MainThread::DEBUG::2014-04-01 13:22:25,111::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:22:25,111::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:22:25,132::supervdsmServer::384::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2014-04-01 13:22:25,132::supervdsmServer::393::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2014-04-01 13:22:25,132::supervdsmServer::396::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2014-04-01 13:22:25,133::supervdsmServer::400::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2014-04-01 13:22:25,133::supervdsmServer::406::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2014-04-01 13:22:25,200::supervdsmServer::417::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2014-04-01 13:22:25,201::sourceRouteThread::56::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started MainProcess::DEBUG::2014-04-01 13:22:28,808::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {} MainProcess::DEBUG::2014-04-01 13:22:28,808::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', ' polling_interval 5', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', ' no_path_retry fail', ' user_friendly_names no', ' flush_on_last_del yes', ' fast_io_fail_tmo 5', ' dev_loss_tmo 30', ' max_fds 4096', '}', '', 'devices {', 'device {', ' vendor "HITACHI"', ' product "DF.*"', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', ' vendor "COMPELNT"', ' product "Compellent Vol"', ' no_path_retry fail', '}', '}'] MainProcess|Thread-14::DEBUG::2014-04-01 13:22:31,095::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-14::DEBUG::2014-04-01 13:22:31,165::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|PolicyEngine::DEBUG::2014-04-01 13:22:39,576::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {} MainProcess|PolicyEngine::DEBUG::2014-04-01 13:22:39,577::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainThread::DEBUG::2014-04-01 13:44:59,743::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:44:59,759::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 13:44:59,839::supervdsmServer::384::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2014-04-01 13:44:59,839::supervdsmServer::393::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2014-04-01 13:44:59,839::supervdsmServer::396::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2014-04-01 13:44:59,840::supervdsmServer::400::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2014-04-01 13:44:59,840::supervdsmServer::406::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2014-04-01 13:44:59,841::supervdsmServer::417::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2014-04-01 13:44:59,842::sourceRouteThread::56::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started MainProcess::DEBUG::2014-04-01 13:45:24,565::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {} MainProcess::DEBUG::2014-04-01 13:45:24,566::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', ' polling_interval 5', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', ' no_path_retry fail', ' user_friendly_names no', ' flush_on_last_del yes', ' fast_io_fail_tmo 5', ' dev_loss_tmo 30', ' max_fds 4096', '}', '', 'devices {', 'device {', ' vendor "HITACHI"', ' product "DF.*"', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', ' vendor "COMPELNT"', ' product "Compellent Vol"', ' no_path_retry fail', '}', '}'] MainProcess|PolicyEngine::DEBUG::2014-04-01 13:45:35,830::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {} MainProcess|PolicyEngine::DEBUG::2014-04-01 13:45:35,837::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainProcess|Thread-13::DEBUG::2014-04-01 13:47:59,454::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-13::DEBUG::2014-04-01 13:47:59,568::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-14::DEBUG::2014-04-01 13:49:32,238::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-14::DEBUG::2014-04-01 13:49:32,240::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-15::DEBUG::2014-04-01 13:51:17,886::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-15::DEBUG::2014-04-01 13:51:17,888::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-16::DEBUG::2014-04-01 13:57:54,508::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-16::DEBUG::2014-04-01 13:57:54,510::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-17::DEBUG::2014-04-01 14:02:37,358::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-17::DEBUG::2014-04-01 14:02:37,359::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-18::DEBUG::2014-04-01 14:24:48,924::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-18::DEBUG::2014-04-01 14:24:48,931::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-19::DEBUG::2014-04-01 14:26:09,065::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-19::DEBUG::2014-04-01 14:26:09,066::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainThread::DEBUG::2014-04-01 14:26:29,970::supervdsmServer::424::SuperVdsm.Server::(main) Terminated normally MainThread::DEBUG::2014-04-01 14:26:34,549::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 14:26:34,550::netconfpersistence::134::root::(_getConfigs) Non-existing config set. MainThread::DEBUG::2014-04-01 14:26:34,633::supervdsmServer::384::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2014-04-01 14:26:34,635::supervdsmServer::393::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2014-04-01 14:26:34,635::supervdsmServer::396::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2014-04-01 14:26:34,636::supervdsmServer::400::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2014-04-01 14:26:34,636::supervdsmServer::406::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2014-04-01 14:26:34,637::supervdsmServer::417::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2014-04-01 14:26:34,648::sourceRouteThread::56::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started MainProcess::DEBUG::2014-04-01 14:26:38,816::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {} MainProcess::DEBUG::2014-04-01 14:26:38,816::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', ' polling_interval 5', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', ' no_path_retry fail', ' user_friendly_names no', ' flush_on_last_del yes', ' fast_io_fail_tmo 5', ' dev_loss_tmo 30', ' max_fds 4096', '}', '', 'devices {', 'device {', ' vendor "HITACHI"', ' product "DF.*"', ' getuid_callout "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', ' vendor "COMPELNT"', ' product "Compellent Vol"', ' no_path_retry fail', '}', '}'] MainProcess|Thread-14::DEBUG::2014-04-01 14:26:40,926::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-14::DEBUG::2014-04-01 14:26:41,025::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|PolicyEngine::DEBUG::2014-04-01 14:26:49,745::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {} MainProcess|PolicyEngine::DEBUG::2014-04-01 14:26:49,745::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainProcess|Thread-15::DEBUG::2014-04-01 14:48:06,622::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-15::DEBUG::2014-04-01 14:48:06,622::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-16::DEBUG::2014-04-01 14:51:29,018::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-16::DEBUG::2014-04-01 14:51:29,019::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-17::DEBUG::2014-04-01 15:04:55,811::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-17::DEBUG::2014-04-01 15:04:55,812::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-18::DEBUG::2014-04-01 15:07:59,106::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-18::DEBUG::2014-04-01 15:07:59,106::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-19::DEBUG::2014-04-01 15:10:26,681::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-19::DEBUG::2014-04-01 15:10:26,682::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-20::DEBUG::2014-04-01 15:22:09,055::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-20::DEBUG::2014-04-01 15:22:09,055::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-21::DEBUG::2014-04-01 15:22:32,928::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-21::DEBUG::2014-04-01 15:22:32,928::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} MainProcess|Thread-22::DEBUG::2014-04-01 15:30:32,109::supervdsmServer::96::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|Thread-22::DEBUG::2014-04-01 15:30:32,109::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL380 G5', 'systemSerialNumber': 'CZC6451JFR', 'systemFamily': None, 'systemVersion': 'Not Specified', 'systemUUID': '435a4336-3435-435a-4336-3435314a4652', 'systemManufacturer': 'HP'} ------=_Part_594693_2003216810.1396362976018 Content-Type: text/x-log Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename=mom.log 2014-04-01 12:50:16,314 - mom - INFO - MOM starting 2014-04-01 12:50:16,401 - mom - INFO - hypervisor interface vdsm 2014-04-01 12:50:16,401 - mom.HostMonitor - INFO - Host Monitor starting 2014-04-01 12:50:16,409 - mom.GuestManager - INFO - Guest Manager starting 2014-04-01 12:50:16,488 - mom.Policy - INFO - Loaded policy '00-defines' 2014-04-01 12:50:16,577 - mom.HostMonitor - INFO - HostMonitor is ready 2014-04-01 12:50:16,598 - mom.Policy - INFO - Loaded policy '02-balloon' 2014-04-01 12:50:16,691 - mom.Policy - INFO - Loaded policy '03-ksm' 2014-04-01 12:50:16,693 - mom.PolicyEngine - INFO - Policy Engine starting 2014-04-01 12:50:16,696 - mom.RPCServer - INFO - RPC Server is disabled 2014-04-01 12:50:26,748 - mom.Controllers.KSM - INFO - Updating KSM configuration: pages_to_scan:0 run:0 sleep_millisecs:0 2014-04-01 12:50:31,434 - mom.GuestManager - INFO - Guest Manager ending 2014-04-01 12:50:31,635 - mom.HostMonitor - INFO - Host Monitor ending 2014-04-01 12:50:31,712 - mom - ERROR - Thread 'HostMonitor' has exited 2014-04-01 12:52:43,250 - mom - INFO - MOM starting 2014-04-01 12:52:43,434 - mom - INFO - hypervisor interface vdsm 2014-04-01 12:52:43,444 - mom.HostMonitor - INFO - Host Monitor starting 2014-04-01 12:52:43,464 - mom.GuestManager - INFO - Guest Manager starting 2014-04-01 12:52:43,528 - mom.Policy - INFO - Loaded policy '00-defines' 2014-04-01 12:52:43,551 - mom.HostMonitor - INFO - HostMonitor is ready 2014-04-01 12:52:43,644 - mom.Policy - INFO - Loaded policy '02-balloon' 2014-04-01 12:52:43,742 - mom.Policy - INFO - Loaded policy '03-ksm' 2014-04-01 12:52:43,742 - mom.PolicyEngine - INFO - Policy Engine starting 2014-04-01 12:52:43,750 - mom.RPCServer - INFO - RPC Server is disabled 2014-04-01 12:52:53,802 - mom.Controllers.KSM - INFO - Updating KSM configuration: pages_to_scan:0 run:0 sleep_millisecs:0 2014-04-01 13:11:10,919 - mom - INFO - MOM starting 2014-04-01 13:11:11,019 - mom.HostMonitor - INFO - Host Monitor starting 2014-04-01 13:11:11,020 - mom - INFO - hypervisor interface vdsm 2014-04-01 13:11:11,028 - mom.GuestManager - INFO - Guest Manager starting 2014-04-01 13:11:11,080 - mom.HostMonitor - INFO - HostMonitor is ready 2014-04-01 13:11:11,112 - mom.Policy - INFO - Loaded policy '00-defines' 2014-04-01 13:11:11,186 - mom.Policy - INFO - Loaded policy '02-balloon' 2014-04-01 13:11:11,309 - mom.Policy - INFO - Loaded policy '03-ksm' 2014-04-01 13:11:11,310 - mom.PolicyEngine - INFO - Policy Engine starting 2014-04-01 13:11:11,314 - mom.RPCServer - INFO - RPC Server is disabled 2014-04-01 13:11:21,366 - mom.Controllers.KSM - INFO - Updating KSM configuration: pages_to_scan:0 run:0 sleep_millisecs:0 2014-04-01 13:13:31,180 - mom.GuestManager - INFO - Guest Manager ending 2014-04-01 13:13:31,458 - mom - ERROR - Thread 'GuestManager' has exited 2014-04-01 13:16:44,369 - mom - INFO - MOM starting 2014-04-01 13:16:44,431 - mom - INFO - hypervisor interface vdsm 2014-04-01 13:16:44,431 - mom.HostMonitor - INFO - Host Monitor starting 2014-04-01 13:16:44,435 - mom.GuestManager - INFO - Guest Manager starting 2014-04-01 13:16:44,621 - mom.HostMonitor - INFO - HostMonitor is ready 2014-04-01 13:16:44,629 - mom.Policy - INFO - Loaded policy '00-defines' 2014-04-01 13:16:44,692 - mom.Policy - INFO - Loaded policy '02-balloon' 2014-04-01 13:16:44,802 - mom.Policy - INFO - Loaded policy '03-ksm' 2014-04-01 13:16:44,803 - mom.PolicyEngine - INFO - Policy Engine starting 2014-04-01 13:16:44,806 - mom.RPCServer - INFO - RPC Server is disabled 2014-04-01 13:16:54,858 - mom.Controllers.KSM - INFO - Updating KSM configuration: pages_to_scan:0 run:0 sleep_millisecs:0 2014-04-01 13:22:11,271 - mom.HostMonitor - INFO - Host Monitor ending 2014-04-01 13:22:29,129 - mom - INFO - MOM starting 2014-04-01 13:22:29,211 - mom.HostMonitor - INFO - Host Monitor starting 2014-04-01 13:22:29,211 - mom - INFO - hypervisor interface vdsm 2014-04-01 13:22:29,323 - mom.GuestManager - INFO - Guest Manager starting 2014-04-01 13:22:29,340 - mom.Policy - INFO - Loaded policy '00-defines' 2014-04-01 13:22:29,370 - mom.HostMonitor - INFO - HostMonitor is ready 2014-04-01 13:22:29,401 - mom.Policy - INFO - Loaded policy '02-balloon' 2014-04-01 13:22:29,525 - mom.Policy - INFO - Loaded policy '03-ksm' 2014-04-01 13:22:29,525 - mom.PolicyEngine - INFO - Policy Engine starting 2014-04-01 13:22:29,527 - mom.RPCServer - INFO - RPC Server is disabled 2014-04-01 13:22:39,575 - mom.Controllers.KSM - INFO - Updating KSM configuration: pages_to_scan:0 run:0 sleep_millisecs:0 2014-04-01 13:43:13,362 - mom.PolicyEngine - INFO - Policy Engine ending 2014-04-01 13:45:25,188 - mom - INFO - MOM starting 2014-04-01 13:45:25,436 - mom.HostMonitor - INFO - Host Monitor starting 2014-04-01 13:45:25,437 - mom - INFO - hypervisor interface vdsm 2014-04-01 13:45:25,507 - mom.GuestManager - INFO - Guest Manager starting 2014-04-01 13:45:25,533 - mom.Policy - INFO - Loaded policy '00-defines' 2014-04-01 13:45:25,558 - mom.HostMonitor - INFO - HostMonitor is ready 2014-04-01 13:45:25,596 - mom.Policy - INFO - Loaded policy '02-balloon' 2014-04-01 13:45:25,752 - mom.Policy - INFO - Loaded policy '03-ksm' 2014-04-01 13:45:25,754 - mom.PolicyEngine - INFO - Policy Engine starting 2014-04-01 13:45:25,762 - mom.RPCServer - INFO - RPC Server is disabled 2014-04-01 13:45:35,814 - mom.Controllers.KSM - INFO - Updating KSM configuration: pages_to_scan:0 run:0 sleep_millisecs:0 2014-04-01 14:26:39,180 - mom - INFO - MOM starting 2014-04-01 14:26:39,307 - mom - INFO - hypervisor interface vdsm 2014-04-01 14:26:39,307 - mom.HostMonitor - INFO - Host Monitor starting 2014-04-01 14:26:39,395 - mom.HostMonitor - INFO - HostMonitor is ready 2014-04-01 14:26:39,463 - mom.GuestManager - INFO - Guest Manager starting 2014-04-01 14:26:39,482 - mom.Policy - INFO - Loaded policy '00-defines' 2014-04-01 14:26:39,527 - mom.Policy - INFO - Loaded policy '02-balloon' 2014-04-01 14:26:39,676 - mom.Policy - INFO - Loaded policy '03-ksm' 2014-04-01 14:26:39,677 - mom.PolicyEngine - INFO - Policy Engine starting 2014-04-01 14:26:39,690 - mom.RPCServer - INFO - RPC Server is disabled 2014-04-01 14:26:49,743 - mom.Controllers.KSM - INFO - Updating KSM configuration: pages_to_scan:0 run:0 sleep_millisecs:0 ------=_Part_594693_2003216810.1396362976018--

1 0

Jump to page: