[Users] Failed to initialize storage

Saturday, 19 May 2012

This is a multipart message in MIME format.

------=_NextPart_000_00A3_01CD362E.8DAFDA00
Content-Type: text/plain;
	charset="koi8-r"
Content-Transfer-Encoding: 7bit

Hi.

I installed  ovirt and vdsm version:

[root@noc-2 vds]# rpm -qa | grep ovirt-engine

ovirt-engine-image-uploader-3.1.0_0001-1.8.el6.x86_64

ovirt-engine-3.1.0_0001-1.8.el6.noarch

ovirt-engine-restapi-3.1.0_0001-1.8.el6.noarch

ovirt-engine-notification-service-3.1.0_0001-1.8.el6.noarch

ovirt-engine-jboss-deps-3.1.0_0001-1.8.el6.noarch

ovirt-engine-userportal-3.1.0_0001-1.8.el6.noarch

ovirt-engine-tools-common-3.1.0_0001-1.8.el6.noarch

ovirt-engine-setup-plugin-allinone-3.1.0_0001-1.8.el6.noarch

ovirt-engine-jbossas-1.2-2.fc16.x86_64

ovirt-engine-log-collector-3.1.0_0001-1.8.el6.x86_64

ovirt-engine-setup-3.1.0_0001-1.8.el6.noarch

ovirt-engine-iso-uploader-3.1.0_0001-1.8.el6.x86_64

ovirt-engine-dbscripts-3.1.0_0001-1.8.el6.noarch

ovirt-engine-sdk-1.3-1.el6.noarch

ovirt-engine-backend-3.1.0_0001-1.8.el6.noarch

ovirt-engine-config-3.1.0_0001-1.8.el6.noarch

ovirt-engine-genericapi-3.1.0_0001-1.8.el6.noarch

ovirt-engine-webadmin-portal-3.1.0_0001-1.8.el6.noarch

[root@noc-2 vds]# rpm -qa | grep vdsm

vdsm-python-4.9.6-0.223.gitb3c6b0c.el6.x86_64

vdsm-bootstrap-4.9.6-0.223.gitb3c6b0c.el6.noarch

vdsm-4.9.6-0.223.gitb3c6b0c.el6.x86_64

Installing a new host is successful. The host goes to reboot.

However, after rebooting the status of the host:

Host 10.1.20.7 is initializing. Message: Failed to initialize storage

In the logs:

Engine.log:

2012-05-19 17:36:45,183 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
(QuartzScheduler_Worker-88) Command
org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand return
value

org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForXmlRpc@6082848f

2012-05-19 17:36:45,183 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
(QuartzScheduler_Worker-88) Vds: 10.1.20.7

2012-05-19 17:36:45,183 ERROR
[org.ovirt.engine.core.vdsbroker.VDSCommandBase] (QuartzScheduler_Worker-88)
Command GetCapabilitiesVDS execution failed. Error: VDSRecoveringException:
Failed to initialize storage

2012-05-19 17:36:47,203 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
(QuartzScheduler_Worker-91) Command
org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand return
value

org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForXmlRpc@70db29ad

2012-05-19 17:36:47,203 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
(QuartzScheduler_Worker-91) Vds: 10.1.20.7

2012-05-19 17:36:47,203 ERROR
[org.ovirt.engine.core.vdsbroker.VDSCommandBase] (QuartzScheduler_Worker-91)
Command GetCapabilitiesVDS execution failed. Error: VDSRecoveringException:
Failed to initialize storage

Vdsm.log(host):

MainThread::INFO::2012-05-19 17:21:54,938::vdsm::78::vds::(run)
<_MainThread(MainThread, started 140055851738880)>

MainThread::INFO::2012-05-19 17:21:54,938::vdsm::78::vds::(run)
<Thread(libvirtEventLoop, started daemon 140055763654400)>

MainThread::INFO::2012-05-19 17:21:54,938::vdsm::78::vds::(run)
<WorkerThread(Thread-5, started daemon 140055620335360)>

MainThread::INFO::2012-05-19 17:21:54,939::vdsm::78::vds::(run)
<WorkerThread(Thread-8, started daemon 140055249151744)>

MainThread::INFO::2012-05-19 17:21:54,939::vdsm::78::vds::(run)
<WorkerThread(Thread-10, started daemon 140055228172032)>

MainThread::INFO::2012-05-19 17:21:54,939::vdsm::78::vds::(run)
<KsmMonitorThread(KsmMonitor, started daemon 140054789879552)>

MainThread::INFO::2012-05-19 17:21:54,939::vdsm::78::vds::(run)
<WorkerThread(Thread-3, started daemon 140055641315072)>

MainThread::INFO::2012-05-19 17:21:54,939::vdsm::78::vds::(run)
<WorkerThread(Thread-6, started daemon 140055609845504)>

MainThread::INFO::2012-05-19 17:21:54,940::vdsm::78::vds::(run)
<WorkerThread(Thread-2, started daemon 140055651804928)>

MainThread::INFO::2012-05-19 17:21:54,940::vdsm::78::vds::(run)
<WorkerThread(Thread-1, started daemon 140055662294784)>

MainThread::INFO::2012-05-19 17:21:54,940::vdsm::78::vds::(run)
<WorkerThread(Thread-7, started daemon 140055259641600)>

MainThread::INFO::2012-05-19 17:21:54,940::vmChannels::135::vds::(stop) VM
channels listener was stopped.

MainThread::INFO::2012-05-19 17:21:54,940::vdsm::78::vds::(run) <Listener(VM
Channels Listener, started daemon 140054768899840)>

MainThread::INFO::2012-05-19 17:21:54,940::vdsm::78::vds::(run)
<WorkerThread(Thread-9, started daemon 140055238661888)>

MainThread::INFO::2012-05-19 17:21:54,941::vdsm::78::vds::(run)
<WorkerThread(Thread-4, started daemon 140055630825216)>

MainThread::INFO::2012-05-19 17:21:54,941::vdsm::78::vds::(run)
<Thread(Thread-11, started daemon 140055217682176)>

MainThread::INFO::2012-05-19 17:30:55,476::vdsm::70::vds::(run) I am the
actual vdsm 4.9.6-0.223.gitb3c6b0c

MainThread::DEBUG::2012-05-19
17:30:56,219::resourceManager::379::ResourceManager::(registerNamespace)
Registering namespace 'Storage'

MainThread::DEBUG::2012-05-19
17:30:56,220::threadPool::45::Misc.ThreadPool::(__init__) Enter -
numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0

MainThread::DEBUG::2012-05-19
17:30:56,234::sp::359::Storage.StoragePool::(cleanupMasterMount) master
`/rhev/data-center/mnt/blockSD/e5a63624-716e-4bb4-ae60-cd4d7aae9ed2/master`
is not mounted, skipping

MainThread::DEBUG::2012-05-19
17:30:56,302::supervdsm::103::SuperVdsmProxy::(_killSupervdsm) Could not
kill old Super Vdsm [Errno 2] No such file or directory:
'/var/run/vdsm/svdsm.pid'

MainThread::DEBUG::2012-05-19
17:30:56,302::supervdsm::91::SuperVdsmProxy::(_launchSupervdsm) Launching
Super Vdsm

MainThread::DEBUG::2012-05-19
17:30:56,302::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n
/usr/bin/python /usr/share/vdsm/supervdsmServer.py
1f16883e-d8b7-45ab-b527-bcee38e5fc87 2994' (cwd None)

MainThread::DEBUG::2012-05-19
17:30:56,529::supervdsmServer::279::SuperVdsm.Server::(main) Making sure I'm
root

MainThread::DEBUG::2012-05-19
17:30:56,529::supervdsmServer::283::SuperVdsm.Server::(main) Parsing cmd
args

MainThread::DEBUG::2012-05-19
17:30:56,529::supervdsmServer::286::SuperVdsm.Server::(main) Creating PID
file

MainThread::DEBUG::2012-05-19
17:30:56,529::supervdsmServer::290::SuperVdsm.Server::(main) Cleaning old
socket

MainThread::DEBUG::2012-05-19
17:30:56,530::supervdsmServer::294::SuperVdsm.Server::(main) Setting up keep
alive thread

MainThread::DEBUG::2012-05-19
17:30:56,530::supervdsmServer::300::SuperVdsm.Server::(main) Creating remote
object manager

MainThread::DEBUG::2012-05-19
17:30:56,531::supervdsmServer::311::SuperVdsm.Server::(main) Started serving
super vdsm object

MainThread::DEBUG::2012-05-19
17:30:58,309::supervdsm::113::SuperVdsmProxy::(_connect) Trying to connect
to Super Vdsm

MainThread::DEBUG::2012-05-19
17:30:58,314::supervdsm::84::SuperVdsmProxy::(__init__) Connected to Super
Vdsm

MainThread::DEBUG::2012-05-19
17:30:58,315::multipath::102::Storage.Multipath::(isEnabled) Current
revision of multipath.conf detected, preserving

MainThread::DEBUG::2012-05-19
17:30:58,315::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n
/sbin/lvm dumpconfig global/locking_type' (cwd None)

MainThread::DEBUG::2012-05-19
17:30:58,464::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0

MainThread::DEBUG::2012-05-19
17:30:58,465::lvm::316::OperationMutex::(_reloadpvs) Operation 'lvm reload
operation' got the operation mutex

MainThread::DEBUG::2012-05-19
17:30:58,467::__init__::1164::Storage.Misc.excCmd::(_log) '/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
filter = [ \\"a%35000c50001770ea3%\\", \\"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,de
v_size' (cwd None)

MainThread::DEBUG::2012-05-19
17:30:58,613::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = "
Couldn't find device with uuid jbH4vV-SWm9-NI0q-Apmd-12qW-KBPX-Rgg2lK.\n";
<rc> = 0

MainThread::DEBUG::2012-05-19
17:30:58,615::lvm::339::OperationMutex::(_reloadpvs) Operation 'lvm reload
operation' released the operation mutex

MainThread::DEBUG::2012-05-19
17:30:58,615::lvm::349::OperationMutex::(_reloadvgs) Operation 'lvm reload
operation' got the operation mutex

MainThread::DEBUG::2012-05-19
17:30:58,616::__init__::1164::Storage.Misc.excCmd::(_log) '/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
filter = [ \\"a%35000c50001770ea3%\\", \\"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_siz
e,vg_mda_free' (cwd None)

MainThread::DEBUG::2012-05-19
17:30:58,764::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = "
Couldn't find device with uuid jbH4vV-SWm9-NI0q-Apmd-12qW-KBPX-Rgg2lK.\n";
<rc> = 0

MainThread::DEBUG::2012-05-19
17:30:58,766::lvm::376::OperationMutex::(_reloadvgs) Operation 'lvm reload
operation' released the operation mutex

MainThread::DEBUG::2012-05-19
17:30:58,766::__init__::1164::Storage.Misc.excCmd::(_log) '/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
filter = [ \\"a%35000c50001770ea3%\\", \\"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)

MainThread::DEBUG::2012-05-19
17:30:58,907::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = "
Couldn't find device with uuid jbH4vV-SWm9-NI0q-Apmd-12qW-KBPX-Rgg2lK.\n";
<rc> = 0

MainThread::ERROR::2012-05-19 17:30:58,909::clientIF::201::vds::(_initIRS)
Error initializing IRS

Traceback (most recent call last):

  File "/usr/share/vdsm/clientIF.py", line 199, in _initIRS

    self.irs = Dispatcher(HSM())

  File "/usr/share/vdsm/storage/hsm.py", line 300, in __init__

    lvm._lvminfo.bootstrap()

  File "/usr/share/vdsm/storage/lvm.py", line 309, in bootstrap

    self._reloadAllLvs()

  File "/usr/share/vdsm/storage/lvm.py", line 435, in _reloadAllLvs

    lv = makeLV(*fields)

  File "/usr/share/vdsm/storage/lvm.py", line 218, in makeLV

    attrs = _attr2NamedTuple(args[LV._fields.index("attr")], LV_ATTR_BITS,
"LV_ATTR")

  File "/usr/share/vdsm/storage/lvm.py", line 188, in _attr2NamedTuple

    attrs = Attrs(*values)

TypeError: __new__() takes exactly 9 arguments (7 given)

MainThread::DEBUG::2012-05-19
17:30:58,918::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/pgrep
-xf ksmd' (cwd None)

MainThread::DEBUG::2012-05-19
17:30:58,945::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0

MainThread::INFO::2012-05-19
17:30:58,946::vmChannels::139::vds::(settimeout) Setting channels' timeout
to 30 seconds.

MainThread::ERROR::2012-05-19
17:30:58,951::clientIF::142::vds::(_prepareBindings) Unable to load the rest
server module. Please make sure it is installed.

VM Channels Listener::INFO::2012-05-19
17:30:58,952::vmChannels::127::vds::(run) Starting VM channels listener
thread.

------=_NextPart_000_00A3_01CD362E.8DAFDA00
Content-Type: text/html;
	charset="koi8-r"
Content-Transfer-Encoding: quoted-printable

<html xmlns:v=3D"urn:schemas-microsoft-com:vml" =
xmlns:o=3D"urn:schemas-microsoft-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=3DContent-Type content=3D"text/html; charset=3Dkoi8-r"><meta =
name=3DGenerator content=3D"Microsoft Word 14 (filtered =
medium)"><style><!--
/* Font Definitions */
@font-face
	{font-family:"Cambria Math";
	panose-1:2 4 5 3 5 4 6 3 2 4;}
@font-face
	{font-family:"Arial Unicode MS";
	panose-1:2 11 6 4 2 2 2 2 2 4;}
@font-face
	{font-family:Calibri;
	panose-1:2 15 5 2 2 2 4 3 2 4;}
@font-face
	{font-family:"\@Arial Unicode MS";
	panose-1:2 11 6 4 2 2 2 2 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
	{margin:0cm;
	margin-bottom:.0001pt;
	font-size:11.0pt;
	font-family:"Calibri","sans-serif";
	mso-fareast-language:EN-US;}
a:link, span.MsoHyperlink
	{mso-style-priority:99;
	color:blue;
	text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
	{mso-style-priority:99;
	color:purple;
	text-decoration:underline;}
span.EmailStyle17
	{mso-style-type:personal-compose;
	font-family:"Calibri","sans-serif";
	color:windowtext;}
.MsoChpDefault
	{mso-style-type:export-only;
	font-family:"Calibri","sans-serif";
	mso-fareast-language:EN-US;}
@page WordSection1
	{size:612.0pt 792.0pt;
	margin:2.0cm 42.5pt 2.0cm 3.0cm;}
div.WordSection1
	{page:WordSection1;}
--></style><!--[if gte mso 9]><xml>
<o:shapedefaults v:ext=3D"edit" spidmax=3D"1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext=3D"edit">
<o:idmap v:ext=3D"edit" data=3D"1" />
</o:shapelayout></xml><![endif]--></head><body lang=3DRU
link=3Dblue =
vlink=3Dpurple><div class=3DWordSection1><p class=3DMsoNormal><span =
lang=3DEN-US>Hi.<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>I installed =9Aovirt and vdsm =
version:<o:p></o:p></span></p><p class=3DMsoNormal><span
=
lang=3DEN-US>[root@noc-2 vds]# rpm -qa | grep =
ovirt-engine<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-image-uploader-3.1.0_0001-1.8.el6.x86_64<o:p></=
o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-3.1.0_0001-1.8.el6.noarch<o:p></o:p></span></p>=
<p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-restapi-3.1.0_0001-1.8.el6.noarch<o:p></o:p></s=
pan></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-notification-service-3.1.0_0001-1.8.el6.noarch<=
o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-jboss-deps-3.1.0_0001-1.8.el6.noarch<o:p></o:p>=
</span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-userportal-3.1.0_0001-1.8.el6.noarch<o:p></o:p>=
</span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-tools-common-3.1.0_0001-1.8.el6.noarch<o:p></o:=
p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-setup-plugin-allinone-3.1.0_0001-1.8.el6.noarch=
<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-jbossas-1.2-2.fc16.x86_64<o:p></o:p></span></p>=
<p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-log-collector-3.1.0_0001-1.8.el6.x86_64<o:p></o=
:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-setup-3.1.0_0001-1.8.el6.noarch<o:p></o:p></spa=
n></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-iso-uploader-3.1.0_0001-1.8.el6.x86_64<o:p></o:=
p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-dbscripts-3.1.0_0001-1.8.el6.noarch<o:p></o:p><=
/span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-sdk-1.3-1.el6.noarch<o:p></o:p></span></p><p
=
class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-backend-3.1.0_0001-1.8.el6.noarch<o:p></o:p></s=
pan></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-config-3.1.0_0001-1.8.el6.noarch<o:p></o:p></sp=
an></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-genericapi-3.1.0_0001-1.8.el6.noarch<o:p></o:p>=
</span></p><p class=3DMsoNormal><span =
lang=3DEN-US>ovirt-engine-webadmin-portal-3.1.0_0001-1.8.el6.noarch<o:p><=
/o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US><o:p>&nbsp;</o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>[root@noc-2 vds]# rpm -qa | grep =
vdsm<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>vdsm-python-4.9.6-0.223.gitb3c6b0c.el6.x86_64<o:p></o:p></sp=
an></p><p class=3DMsoNormal><span =
lang=3DEN-US>vdsm-bootstrap-4.9.6-0.223.gitb3c6b0c.el6.noarch<o:p></o:p><=
/span></p><p class=3DMsoNormal><span =
lang=3DEN-US>vdsm-4.9.6-0.223.gitb3c6b0c.el6.x86_64<o:p></o:p></span></p>=
<p class=3DMsoNormal><span
lang=3DEN-US><o:p>&nbsp;</o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>Installing a new host is =
successful. The host goes to reboot.<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>However, after rebooting the status =
of the host:<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US style=3D'font-size:12.0pt;font-family:"Arial Unicode =
MS","sans-serif";color:black;mso-fareast-language:RU'>Host 10.1.20.7
is =
initializing. Message: Failed to initialize =
storage<o:p></o:p></span></p><p class=3DMsoNormal><span
=
lang=3DEN-US><o:p>&nbsp;</o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>In the logs:<o:p></o:p></span></p><p =
class=3DMsoNormal><span
lang=3DEN-US>Engine.log:<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>2012-05-19 17:36:45,183 INFO=9A =
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] =
(QuartzScheduler_Worker-88) Command =
org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand =
return value<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US> =
org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForXmlRpc@6082848f=
<o:p></o:p></span></p><p class=3DMsoNormal><span
lang=3DEN-US>2012-05-19 =
17:36:45,183 INFO=9A =
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] =
(QuartzScheduler_Worker-88) Vds:
10.1.20.7<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>2012-05-19 17:36:45,183 ERROR =
[org.ovirt.engine.core.vdsbroker.VDSCommandBase] =
(QuartzScheduler_Worker-88) Command GetCapabilitiesVDS execution failed. =
Error: VDSRecoveringException: Failed to initialize =
storage<o:p></o:p></span></p><p class=3DMsoNormal><span
=
lang=3DEN-US>2012-05-19 17:36:47,203 INFO=9A =
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] =
(QuartzScheduler_Worker-91) Command =
org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand =
return value<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US> =
org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForXmlRpc@70db29ad=
<o:p></o:p></span></p><p class=3DMsoNormal><span
lang=3DEN-US>2012-05-19 =
17:36:47,203 INFO=9A =
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] =
(QuartzScheduler_Worker-91) Vds:
10.1.20.7<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>2012-05-19 17:36:47,203 ERROR =
[org.ovirt.engine.core.vdsbroker.VDSCommandBase] =
(QuartzScheduler_Worker-91) Command GetCapabilitiesVDS execution failed. =
</span>Error: VDSRecoveringException: Failed to initialize =
storage<o:p></o:p></p><p
class=3DMsoNormal><o:p>&nbsp;</o:p></p><p =
class=3DMsoNormal><span =
lang=3DEN-US>Vdsm.log(host):<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,938::vdsm::78::vds::(run) &lt;_MainThread(MainThread, started =
140055851738880)&gt;<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,938::vdsm::78::vds::(run) &lt;Thread(libvirtEventLoop, started =
daemon 140055763654400)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,938::vdsm::78::vds::(run) &lt;WorkerThread(Thread-5, started =
daemon 140055620335360)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,939::vdsm::78::vds::(run) &lt;WorkerThread(Thread-8, started =
daemon 140055249151744)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,939::vdsm::78::vds::(run) &lt;WorkerThread(Thread-10, started =
daemon 140055228172032)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,939::vdsm::78::vds::(run) &lt;KsmMonitorThread(KsmMonitor, =
started daemon 140054789879552)&gt;<o:p></o:p></span></p><p
=
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,939::vdsm::78::vds::(run) &lt;WorkerThread(Thread-3, started =
daemon 140055641315072)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,939::vdsm::78::vds::(run) &lt;WorkerThread(Thread-6, started =
daemon 140055609845504)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,940::vdsm::78::vds::(run) &lt;WorkerThread(Thread-2, started =
daemon 140055651804928)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,940::vdsm::78::vds::(run) &lt;WorkerThread(Thread-1, started =
daemon 140055662294784)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,940::vdsm::78::vds::(run) &lt;WorkerThread(Thread-7, started =
daemon 140055259641600)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,940::vmChannels::135::vds::(stop) VM channels listener was =
stopped.<o:p></o:p></span></p><p class=3DMsoNormal><span
=
lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,940::vdsm::78::vds::(run) &lt;Listener(VM Channels Listener, =
started daemon 140054768899840)&gt;<o:p></o:p></span></p><p
=
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,940::vdsm::78::vds::(run) &lt;WorkerThread(Thread-9, started =
daemon 140055238661888)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,941::vdsm::78::vds::(run) &lt;WorkerThread(Thread-4, started =
daemon 140055630825216)&gt;<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:21:54,941::vdsm::78::vds::(run) &lt;Thread(Thread-11, started daemon =
140055217682176)&gt;<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:30:55,476::vdsm::70::vds::(run) I am the actual vdsm =
4.9.6-0.223.gitb3c6b0c<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,219::resourceManager::379::ResourceManager::(registerNamespace) =
Registering namespace 'Storage'<o:p></o:p></span></p><p
=
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,220::threadPool::45::Misc.ThreadPool::(__init__) Enter - =
numThreads: 10.0, waitTimeout: 3, maxTasks: =
500.0<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,234::sp::359::Storage.StoragePool::(cleanupMasterMount) master =
`/rhev/data-center/mnt/blockSD/e5a63624-716e-4bb4-ae60-cd4d7aae9ed2/maste=
r` is not mounted, skipping<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,302::supervdsm::103::SuperVdsmProxy::(_killSupervdsm) Could not =
kill old Super Vdsm [Errno 2] No such file or directory: =
'/var/run/vdsm/svdsm.pid'<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,302::supervdsm::91::SuperVdsmProxy::(_launchSupervdsm) =
Launching Super Vdsm<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,302::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo =
-n /usr/bin/python /usr/share/vdsm/supervdsmServer.py =
1f16883e-d8b7-45ab-b527-bcee38e5fc87 2994' (cwd =
None)<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,529::supervdsmServer::279::SuperVdsm.Server::(main) Making sure =
I'm root<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,529::supervdsmServer::283::SuperVdsm.Server::(main) Parsing cmd =
args<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,529::supervdsmServer::286::SuperVdsm.Server::(main) Creating =
PID file<o:p></o:p></span></p><p class=3DMsoNormal><span
=
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,529::supervdsmServer::290::SuperVdsm.Server::(main) Cleaning =
old socket<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,530::supervdsmServer::294::SuperVdsm.Server::(main) Setting up =
keep alive thread<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,530::supervdsmServer::300::SuperVdsm.Server::(main) Creating =
remote object manager<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:56,531::supervdsmServer::311::SuperVdsm.Server::(main) Started =
serving super vdsm object<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,309::supervdsm::113::SuperVdsmProxy::(_connect) Trying to =
connect to Super Vdsm<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,314::supervdsm::84::SuperVdsmProxy::(__init__) Connected to =
Super Vdsm<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,315::multipath::102::Storage.Multipath::(isEnabled) Current =
revision of multipath.conf detected,
preserving<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,315::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo =
-n /sbin/lvm dumpconfig global/locking_type' (cwd =
None)<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,464::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: =
&lt;err&gt; =3D ''; &lt;rc&gt; =3D
0<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,465::lvm::316::OperationMutex::(_reloadpvs) Operation 'lvm =
reload operation' got the operation
mutex<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,467::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo =
-n /sbin/lvm pvs --config &quot; devices { preferred_names =3D =
[\\&quot;^/dev/mapper/\\&quot;] ignore_suspended_devices=3D1 =
write_cache_state=3D0 disable_after_error_count=3D3 filter =3D [ =
\\&quot;a%35000c50001770ea3%\\&quot;, \\&quot;r%.*%\\&quot; ] }=9A =
global {=9A locking_type=3D1=9A prioritise_write_locks=3D1=9A =
wait_for_locks=3D1 }=9A backup {=9A retain_min =3D 50=9A retain_days =3D =
0 } &quot; --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)<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,613::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: =
&lt;err&gt; =3D &quot;=9A Couldn't find device with uuid =
jbH4vV-SWm9-NI0q-Apmd-12qW-KBPX-Rgg2lK.\n&quot;; &lt;rc&gt; =3D =
0<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,615::lvm::339::OperationMutex::(_reloadpvs) Operation 'lvm =
reload operation' released the operation
mutex<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,615::lvm::349::OperationMutex::(_reloadvgs) Operation 'lvm =
reload operation' got the operation
mutex<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,616::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo =
-n /sbin/lvm vgs --config &quot; devices { preferred_names =3D =
[\\&quot;^/dev/mapper/\\&quot;] ignore_suspended_devices=3D1 =
write_cache_state=3D0 disable_after_error_count=3D3 filter =3D [ =
\\&quot;a%35000c50001770ea3%\\&quot;, \\&quot;r%.*%\\&quot; ] }=9A =
global {=9A locking_type=3D1=9A prioritise_write_locks=3D1=9A =
wait_for_locks=3D1 }=9A backup {=9A retain_min =3D 50=9A retain_days =3D =
0 } &quot; --noheadings --units b --nosuffix --separator | -o =
uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_=
size,vg_mda_free' (cwd None)<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,764::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: =
&lt;err&gt; =3D &quot;=9A Couldn't find device with uuid =
jbH4vV-SWm9-NI0q-Apmd-12qW-KBPX-Rgg2lK.\n&quot;; &lt;rc&gt; =3D =
0<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,766::lvm::376::OperationMutex::(_reloadvgs) Operation 'lvm =
reload operation' released the operation
mutex<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,766::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo =
-n /sbin/lvm lvs --config &quot; devices { preferred_names =3D =
[\\&quot;^/dev/mapper/\\&quot;] ignore_suspended_devices=3D1 =
write_cache_state=3D0 disable_after_error_count=3D3 filter =3D [ =
\\&quot;a%35000c50001770ea3%\\&quot;, \\&quot;r%.*%\\&quot; ] }=9A =
global {=9A locking_type=3D1=9A prioritise_write_locks=3D1=9A =
wait_for_locks=3D1 }=9A backup {=9A retain_min =3D 50=9A retain_days =3D =
0 } &quot; --noheadings --units b --nosuffix --separator | -o =
uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd =
None)<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,907::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: =
&lt;err&gt; =3D &quot;=9A Couldn't find device with uuid =
jbH4vV-SWm9-NI0q-Apmd-12qW-KBPX-Rgg2lK.\n&quot;; &lt;rc&gt; =3D =
0<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>MainThread::ERROR::2012-05-19 =
17:30:58,909::clientIF::201::vds::(_initIRS) Error initializing =
IRS<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>Traceback (most recent call
last):<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>=9A File =
&quot;/usr/share/vdsm/clientIF.py&quot;, line 199, in =
_initIRS<o:p></o:p></span></p><p class=3DMsoNormal><span
=
lang=3DEN-US>=9A=9A=9A self.irs =3D =
Dispatcher(HSM())<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>=9A File &quot;/usr/share/vdsm/storage/hsm.py&quot;, line =
300, in __init__<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>=9A=9A=9A
lvm._lvminfo.bootstrap()<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>=9A File =
&quot;/usr/share/vdsm/storage/lvm.py&quot;, line 309, in =
bootstrap<o:p></o:p></span></p><p class=3DMsoNormal><span
=
lang=3DEN-US>=9A=9A=9A
self._reloadAllLvs()<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>=9A File =
&quot;/usr/share/vdsm/storage/lvm.py&quot;, line 435, in =
_reloadAllLvs<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>=9A=9A=9A lv =3D
makeLV(*fields)<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>=9A File =
&quot;/usr/share/vdsm/storage/lvm.py&quot;, line 218, in =
makeLV<o:p></o:p></span></p><p class=3DMsoNormal><span =
lang=3DEN-US>=9A=9A=9A attrs =3D =
_attr2NamedTuple(args[LV._fields.index(&quot;attr&quot;)], LV_ATTR_BITS, =
&quot;LV_ATTR&quot;)<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>=9A File &quot;/usr/share/vdsm/storage/lvm.py&quot;, line =
188, in _attr2NamedTuple<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>=9A=9A=9A attrs =3D
Attrs(*values)<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>TypeError: __new__() takes exactly =
9 arguments (7 given)<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,918::__init__::1164::Storage.Misc.excCmd::(_log) =
'/usr/bin/pgrep -xf ksmd' (cwd
None)<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::DEBUG::2012-05-19 =
17:30:58,945::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: =
&lt;err&gt; =3D ''; &lt;rc&gt; =3D
0<o:p></o:p></span></p><p =
class=3DMsoNormal><span lang=3DEN-US>MainThread::INFO::2012-05-19 =
17:30:58,946::vmChannels::139::vds::(settimeout) Setting channels' =
timeout to 30 seconds.<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>MainThread::ERROR::2012-05-19 =
17:30:58,951::clientIF::142::vds::(_prepareBindings) Unable to load the =
rest server module. Please make sure it is =
installed.<o:p></o:p></span></p><p
class=3DMsoNormal><span =
lang=3DEN-US>VM Channels Listener::INFO::2012-05-19 =
17:30:58,952::vmChannels::127::vds::(run) Starting VM channels listener =
thread.<o:p></o:p></span></p></div></body></html>
------=_NextPart_000_00A3_01CD362E.8DAFDA00--

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

[Users] Failed to initialize storage