[ovirt-users] VM can't start after interruption of power supply

张余歌 patrickstar12358 at hotmail.com
Sat Jul 23 04:20:33 UTC 2016


dear friends:
    recently i meet some problem:My company meeted interruption of power supply,which cause my server power off directly.when i start the server,it adviced to user fsck comnmand to repair FS.I did it and know my system had some bad block.And then i enter the ovirt's WEB administration portal normally,i try to start my VM,but i failed!it seems VM was locked .it tips"failed to get volume size:"XXX-XXXX-XXXXX".which confused me a long time!!Have someone meet this problem as me?please give me some advice.Thanks a lot.
    i try to process service vdsmd restart ,but i throw  exception:
#service vdsmd restart
----------------------------------------------------------------------
libvirt is already configured for vdsm
vdsm: Running validate_configuration
SUCCESS: ssl configured to false. No conflicts.
vdsm: Running prepare_transient_repository
vdsm: Running syslog_available
vdsm: Running nwfilter
libvirt: Network Filter Driver error : Requested operation is not valid: nwfilter is in use
vdsm: Running dummybr
vdsm: Running load_needed_modules
vdsm: Running tune_system
vdsm: Running test_space
vdsm: Running test_lo
Upgrading to unified persistence if needed
Traceback (most recent call last):
  File "/usr/share/vdsm/vdsm-restore-net-config", line 346, in <module>
    restore(args)
  File "/usr/share/vdsm/vdsm-restore-net-config", line 315, in restore
    unified_restoration()
  File "/usr/share/vdsm/vdsm-restore-net-config", line 93, in unified_restoration
    setupNetworks(nets, bonds, connectivityCheck=False, _inRollback=True)
  File "/usr/share/vdsm/network/api.py", line 666, in setupNetworks
    _handleBondings(bondings, configurator, in_rollback)
  File "/usr/share/vdsm/network/api.py", line 530, in _handleBondings
    destroyOnMasterRemoval='remove' in attrs)
  File "/usr/share/vdsm/network/models.py", line 242, in objectivize
    mtu, _netinfo)
  File "/usr/share/vdsm/network/models.py", line 232, in _objectivizeSlaves
    (nic, nicVlans or nicNet or nicBond))
network.errors.ConfigNetworkError: (24, u'nic em1 already used by ovirtmgmt')
Traceback (most recent call last):
  File "/usr/bin/vdsm-tool", line 219, in main
    return tool_command[cmd]["command"](*args)
  File "/usr/lib/python2.6/site-packages/vdsm/tool/restore_nets.py", line 40, in restore_command
    exec_restore(cmd)
  File "/usr/lib/python2.6/site-packages/vdsm/tool/restore_nets.py", line 53, in exec_restore
    raise EnvironmentError('Failed to restore the persisted networks')
EnvironmentError: Failed to restore the persisted networks
Upgrading to v3.x networking if needed
Starting up vdsm daemon:
vdsm start                                                 [  OK  ]
-----------------------------------------------------------------------------------

i check engine.log
#less engine.log
INFO  [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-4) [31d932f0] Lock Acquired to object EngineLock [exclusiveLocks= key: d6575495-5cb3-41df-9d6e-6b88186dbbb3 value: VM
, sharedLocks= ]
2016-07-23 11:30:00,931 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-4) [31d932f0] START, IsVmDuringInitiatingVDSCommand( vmId = d6575495-5cb3-41df-9d6e-6b88186dbbb3), log id: 2fbe5c73
2016-07-23 11:30:00,933 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-4) [31d932f0] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 2fbe5c73
2016-07-23 11:30:00,966 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] Running command: RunVmCommand internal: false. Entities affected :  ID: d6575495-5cb3-41df-9d6e-6b88186dbbb3 Type: VMAction group VM_BASIC_OPERATIONS with role type USER
2016-07-23 11:30:00,996 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] START, UpdateVmDynamicDataVDSCommand(HostName = local_host, HostId = 953cfb28-41a6-48f1-bcf5-58a741b1213b, vmDynamic=org.ovirt.engine.core.common.businessentities.VmDynamic at 417b138a), log id: 1bca8285
2016-07-23 11:30:01,000 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] FINISH, UpdateVmDynamicDataVDSCommand, log id: 1bca8285
2016-07-23 11:30:01,005 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] START, CreateVmVDSCommand(HostName = local_host, HostId = 953cfb28-41a6-48f1-bcf5-58a741b1213b, vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3, vm=VM [晓峰]), log id: 196e548a
2016-07-23 11:30:01,010 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] START, CreateVDSCommand(HostName = local_host, HostId = 953cfb28-41a6-48f1-bcf5-58a741b1213b, vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3, vm=VM [晓峰]), log id: 566631a7
2016-07-23 11:30:01,031 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand kvmEnable=true,keyboardLayout=en-us,nice=0,displayNetwork=ovirtmgmt,copyPasteEnable=true,timeOffset=28800,transparentHugePages=true,vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3,acpiEnable=true,custom={device_82fbfee2-6ff6-4b65-8d4f-92aa100f0e40device_897aa439-44f3-47b7-bdde-b719ed0d524f=VmDevice {vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3, deviceId=897aa439-44f3-47b7-bdde-b719ed0d524f, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={bus=0, controller=0, type=virtio-serial, port=2}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null, logicalName=null}, device_82fbfee2-6ff6-4b65-8d4f-92aa100f0e40device_897aa439-44f3-47b7-bdde-b719ed0d524fdevice_13730383-d2a9-4475-8465-721aaa3ca9f0device_6e251d6d-c788-4378-8a50-ba6807229c4c=VmDevice {vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3, deviceId=6e251d6d-c788-4378-8a50-ba6807229c4c, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={bus=0, controller=0, type=virtio-serial, port=1}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null, logicalName=null}, device_82fbfee2-6ff6-4b65-8d4f-92aa100f0e40device_897aa439-44f3-47b7-bdde-b719ed0d524fdevice_13730383-d2a9-4475-8465-721aaa3ca9f0=VmDevice {vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3, deviceId=13730383-d2a9-4475-8465-721aaa3ca9f0, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={bus=0, controller=0, type=virtio-serial, port=3}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null, logicalName=null}, device_82fbfee2-6ff6-4b65-8d4f-92aa100f0e40=VmDevice {vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3, deviceId=82fbfee2-6ff6-4b65-8d4f-92aa100f0e40, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={slot=0x01, bus=0x00, domain=0x0000, type=pci, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null, logicalName=null}},memSize=4096,smp=4,emulatedMachine=rhel6.5.0,vmType=kvm,memGuaranteedSize=1024,display=qxl,smartcardEnable=false,bootMenuEnable=false,smpCoresPerSocket=1,maxVCpus=16,hypervEnable=true,devices=[{model=ich9-ehci1, index=0, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x7}, specParams={}, device=usb, type=controller, deviceId=f28a4ab1-1f8b-450c-945f-63c555e35493}, {address={bus=0x00, domain=0x0000, type=pci, slot=0x02, function=0x0}, specParams={vram=32768, heads=1}, device=qxl, type=video, deviceId=ebbb86af-594e-4507-b620-dbdfaea1dbed}, {shared=false, iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0, type=drive}, specParams={path=}, path=, device=cdrom, type=disk, readonly=true, deviceId=c23c1941-3df5-4fac-be1d-98135b8cd41e}, {shared=false, index=0, volumeID=3e2bbe2b-39e2-4575-aac5-8f2c6fe31a88, propagateErrors=off, format=cow, type=disk, iface=virtio, bootOrder=1, domainID=e42469fc-13c8-4323-800f-9602150fb4e1, imageID=64d53873-af54-4c71-ae5e-f7675033c920, specParams={}, optional=false, device=disk, poolID=9049d6c1-00e3-46b3-ac3d-745f5c99f8b5, readonly=false, deviceId=64d53873-af54-4c71-ae5e-f7675033c920}, {shared=false, volumeID=06e72ea0-e4fb-4213-b5bc-d2b53acf36d9, iface=virtio, imageID=863fe11e-6a53-4981-b09a-ba2bb3f02e6a, domainID=7b74d992-823f-460c-8f84-47190b515d49, specParams={}, optional=false, propagateErrors=off, device=disk, poolID=9049d6c1-00e3-46b3-ac3d-745f5c99f8b5, format=cow, type=disk, readonly=false, deviceId=863fe11e-6a53-4981-b09a-ba2bb3f02e6a}, {nicModel=pv, address={bus=0x00, domain=0x0000, type=pci, slot=0x09, function=0x0}, specParams={outbound={}, inbound={}}, macAddr=00:1a:4a:1b:25:1c, device=bridge, linkActive=true, type=interface, filter=vdsm-no-mac-spoofing, network=ovirtmgmt, deviceId=8ae649b9-6dd0-4236-aecd-8e68d7dbe287}, {address={bus=0x00, domain=0x0000, type=pci, slot=0x03, function=0x0}, specParams={}, device=ich6, type=sound, deviceId=e04a692e-d0af-474a-99d8-93b487362131}, {model=ich9-uhci2, index=0, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x1}, specParams={}, device=usb, type=controller, deviceId=110d4e02-fe82-4a3f-ab9b-57cf1e70360f}, {model=ich9-uhci1, index=0, address={bus=0x00, domain=0x0000, type=pci, multifunction=on, slot=0x04, function=0x0}, specParams={}, device=usb, type=controller, deviceId=925b918e-6e7c-4ce1-962e-ecceac5a3056}, {model=ich9-uhci3, index=0, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x2}, specParams={}, device=usb, type=controller, deviceId=e469ef72-1f57-422b-b6ab-29176cf88439}, {bus=usb, specParams={}, device=spicevmc, type=redir, deviceId=2338974e-8753-42cb-98ca-41281bedae48}, {bus=usb, specParams={}, device=spicevmc, type=redir, deviceId=39badef1-50c5-4549-93a3-91d91ef98554}, {bus=usb, specParams={}, device=spicevmc, type=redir, deviceId=7a40dba2-5f6c-4e8a-bbf0-8559fe9aa553}, {bus=usb, specParams={}, device=spicevmc, type=redir, deviceId=d9930b40-d233-453a-b7fb-ddaa740978ec}, {index=0, model=virtio-scsi, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, specParams={}, device=scsi, type=controller, deviceId=7a0ed22b-a3c0-4a44-9874-58a40f1a01d4}, {address={bus=0x00, domain=0x0000, type=pci, slot=0x06, function=0x0}, specParams={}, device=virtio-serial, type=controller, deviceId=1d23f521-a6c0-4913-b239-8c47bf258fe7}],vmName=晓峰,cpuType=SandyBridge,fileTransferEnable=true
2016-07-23 11:30:01,100 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] FINISH, CreateVDSCommand, log id: 566631a7
2016-07-23 11:30:01,105 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 196e548a
2016-07-23 11:30:01,107 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-25) [31d932f0] Lock freed to object EngineLock [exclusiveLocks= key: d6575495-5cb3-41df-9d6e-6b88186dbbb3 value: VM
, sharedLocks= ]
2016-07-23 11:30:01,111 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-25) [31d932f0] Correlation ID: 31d932f0, Job ID: cdac0f67-f098-485e-8232-ab2dc7de24c7, Call Stack: null, Custom Event ID: -1, Message: VM 晓峰 was started by admin at internal (Host: local_host).
2016-07-23 11:30:02,611 INFO  [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (ajp--127.0.0.1-8702-8) Running command: LoginUserCommand internal: false.
2016-07-23 11:30:02,613 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-8) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin at internal logged in.
2016-07-23 11:30:03,896 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-56) START, DestroyVDSCommand(HostName = local_host, HostId = 953cfb28-41a6-48f1-bcf5-58a741b1213b, vmId=d6575495-5cb3-41df-9d6e-6b88186dbbb3, force=false, secondsToWait=0, gracefully=false, reason=), log id: 15692c5f
2016-07-23 11:30:03,936 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (DefaultQuartzScheduler_Worker-56) FINISH, DestroyVDSCommand, log id: 15692c5f
2016-07-23 11:30:03,948 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-56) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VM 晓峰 is down with error. Exit message: ('Failed to get size for volume %s', '3e2bbe2b-39e2-4575-aac5-8f2c6fe31a88').
2016-07-23 11:30:03,949 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-56) Running on vds during rerun failed vm: null
2016-07-23 11:30:03,967 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-56) VM 晓峰 (d6575495-5cb3-41df-9d6e-6b88186dbbb3) is running in db and not running in VDS local_host
2016-07-23 11:30:03,995 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-56) Rerun vm d6575495-5cb3-41df-9d6e-6b88186dbbb3. Called from vds local_host
2016-07-23 11:30:04,002 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-45) Correlation ID: 31d932f0, Job ID: cdac0f67-f098-485e-8232-ab2dc7de24c7, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM 晓峰 on Host local_host.
2016-07-23 11:30:04,006 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-45) Lock Acquired to object EngineLock [exclusiveLocks= key: d6575495-5cb3-41df-9d6e-6b88186dbbb3 value: VM
, sharedLocks= ]
2016-07-23 11:30:04,027 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-45) START, IsVmDuringInitiatingVDSCommand( vmId = d6575495-5cb3-41df-9d6e-6b88186dbbb3), log id: 2ea731ec
2016-07-23 11:30:04,028 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (org.ovirt.thread.pool-8-thread-45) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 2ea731ec
2016-07-23 11:30:04,039 WARN  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-45) CanDoAction of action RunVm failed for user admin at internal. Reasons: VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,SCHEDULING_NO_HOSTS
2016-07-23 11:30:04,041 INFO  [org.ovirt.engine.core.bll.RunVmCommand] (org.ovirt.thread.pool-8-thread-45) Lock freed to object EngineLock [exclusiveLocks= key: d6575495-5cb3-41df-9d6e-6b88186dbbb3 value: VM
, sharedLocks= ]
2016-07-23 11:30:04,045 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-45) Correlation ID: 31d932f0, Job ID: cdac0f67-f098-485e-8232-ab2dc7de24c7, Call Stack: null, Custom Event ID: -1, Message: Failed to run VM 晓峰 (User: admin at internal).
2016-07-23 11:30:04,051 INFO  [org.ovirt.engine.core.bll.ProcessDownVmCommand] (org.ovirt.thread.pool-8-thread-20) [6de68e30] Running command: ProcessDownVmCommand internal: true.
--------------------------------------------------------------------------------------------

 		 	   		  
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20160723/bd31f7ad/attachment-0001.html>


More information about the Users mailing list