Attachment 844562 Details for Bug 1047356 – supervdsm

supervdsm

cinit_supervdsm.log (text/x-log), 40.83 KB, created by Meni Yakove on 2014-01-02 13:41:25 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Meni Yakove

Created: 2014-01-02 13:41:25 UTC

Size: 40.83 KB

patch

obsolete

>MainThread::DEBUG::2013-12-31 14:04:21,282::supervdsmServer::375::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm
>MainThread::DEBUG::2013-12-31 14:04:21,282::supervdsmServer::384::SuperVdsm.Server::(main) Parsing cmd args
>MainThread::DEBUG::2013-12-31 14:04:21,282::supervdsmServer::387::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock
>MainThread::DEBUG::2013-12-31 14:04:21,283::supervdsmServer::391::SuperVdsm.Server::(main) Setting up keep alive thread
>MainThread::DEBUG::2013-12-31 14:04:21,283::supervdsmServer::394::SuperVdsm.Server::(main) Creating remote object manager
>MainThread::DEBUG::2013-12-31 14:04:21,290::supervdsmServer::405::SuperVdsm.Server::(main) Started serving super vdsm object
>MainThread::DEBUG::2013-12-31 14:04:21,290::sourceRouteThread::46::root::(subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,153::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call setupNetworks with ({'rhevm': {'nic': 'eth0', 'bootproto': 'dhcp', 'STP': 'no', 'bridged': 'true'}}, {}, {'connectivityCheck': 'true', 'connectivityTimeout': 120}) {}
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,156::utils::489::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,203::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,206::libvirtconnection::131::libvirtconnection::(get) trying to connect libvirt
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:29,259::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:29,260::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:29,260::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:29,261::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,298::configNetwork::491::setupNetworks::(setupNetworks) Setting up network according to configuration: networks:{'rhevm': {'nic': 'eth0', 'bootproto': 'dhcp', 'STP': 'no', 'bridged': 'true'}}, bondings:{}, options:{'connectivityCheck': 'true', 'connectivityTimeout': 120}
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,298::configNetwork::495::root::(setupNetworks) Validating configuration
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,298::utils::489::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,323::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,337::configNetwork::498::setupNetworks::(setupNetworks) Applying...
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,339::utils::489::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,344::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,358::utils::489::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,365::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,381::configNetwork::539::setupNetworks::(setupNetworks) Adding network 'rhevm'
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,381::utils::489::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,386::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,401::configNetwork::176::root::(addNetwork) validating network...
>MainProcess|Thread-15::INFO::2013-12-31 14:04:29,401::configNetwork::191::root::(addNetwork) Adding network rhevm with vlan=None, bonding=None, nics=['eth0'], bondingOptions=None, mtu=None, bridged=True, options={'bootproto': 'dhcp', 'STP': 'no', 'implicitBonding': True}
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,402::ifcfg::385::root::(_persistentBackup) backing up ifcfg-rhevm: # original file did not exist
>
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,404::ifcfg::254::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/ifcfg-rhevm (until next 'set safe config')
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,405::ifcfg::460::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-rhevm configuration:
># Generated by VDSM version 4.13.2-0.4.el6ev
>DEVICE=rhevm
>ONBOOT=yes
>TYPE=Bridge
>DELAY=0
>BOOTPROTO=dhcp
>DEFROUTE=yes
>NM_CONTROLLED=no
>STP=no
>
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,545::utils::489::root::(execCmd) '/sbin/ifdown rhevm' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,888::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,889::utils::489::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,897::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,912::ifcfg::310::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eth0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,912::ifcfg::385::root::(_persistentBackup) backing up ifcfg-eth0: DEVICE="eth0"
>BOOTPROTO="dhcp"
>DHCP_HOSTNAME="navy-vds1.qa.lab.tlv.redhat.com"
>HWADDR=00:14:5e:dd:09:24
>NM_CONTROLLED="yes"
>ONBOOT=yes
>TYPE="Ethernet"
>UUID="96d6dd4f-9d65-4197-bf8a-395824775898"
>
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,913::ifcfg::254::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/ifcfg-eth0 (until next 'set safe config')
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,914::ifcfg::460::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-eth0 configuration:
># Generated by VDSM version 4.13.2-0.4.el6ev
>DEVICE=eth0
>ONBOOT=yes
>HWADDR=00:14:5e:dd:09:24
>BRIDGE=rhevm
>NM_CONTROLLED=no
>STP=no
>
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:29,916::utils::489::root::(execCmd) '/sbin/ifdown eth0' (cwd None)
>MainThread::DEBUG::2013-12-31 14:04:30,132::sourceRouteThread::18::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1388491470
>MainThread::INFO::2013-12-31 14:04:30,142::sourceRouteThread::35::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>MainThread::WARNING::2013-12-31 14:04:30,143::utils::113::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:30,267::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:30,276::utils::509::root::(execCmd) SUCCESS: <err> = 'bridge rhevm does not exist!\n'; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:30,277::utils::489::root::(execCmd) '/sbin/ifup eth0' (cwd None)
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:30,561::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:04:30,562::utils::489::root::(execCmd) '/sbin/ifup rhevm' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:31,289::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:31,300::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:31,304::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:31,311::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:31,315::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:31,319::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:31,322::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:33,326::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:04:33,326::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None
>MainProcess|PolicyEngine::DEBUG::2013-12-31 14:04:38,630::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {}
>MainProcess|PolicyEngine::DEBUG::2013-12-31 14:04:38,631::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None
>MainThread::DEBUG::2013-12-31 14:05:10,686::sourceRouteThread::18::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1388491510
>MainThread::INFO::2013-12-31 14:05:10,687::sourceRoute::81::root::(configure) Configuring gateway - ip: 10.35.128.31, network: 10.35.128.0/24, subnet: 255.255.255.0, gateway: 10.35.128.254, table: 170098719, device: rhevm
>MainThread::DEBUG::2013-12-31 14:05:10,688::utils::489::root::(execCmd) '/sbin/ip route add 0.0.0.0/0 via 10.35.128.254 dev rhevm table 170098719' (cwd None)
>MainThread::DEBUG::2013-12-31 14:05:10,707::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2013-12-31 14:05:10,708::utils::489::root::(execCmd) '/sbin/ip route add 10.35.128.0/24 via 10.35.128.31 dev rhevm table 170098719' (cwd None)
>MainThread::DEBUG::2013-12-31 14:05:10,722::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2013-12-31 14:05:10,722::utils::489::root::(execCmd) '/sbin/ip rule add from 10.35.128.0/24 table 170098719' (cwd None)
>MainThread::DEBUG::2013-12-31 14:05:10,737::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2013-12-31 14:05:10,738::utils::489::root::(execCmd) '/sbin/ip rule add from all to 10.35.128.0/24 dev rhevm table 170098719' (cwd None)
>MainThread::DEBUG::2013-12-31 14:05:10,742::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:10,750::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:10,760::libvirtconnection::108::libvirtconnection::(wrapper) Unknown libvirterror: ecode: 43 edom: 19 level: 2 message: Network not found: no network with matching name 'vdsm-rhevm'
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:10,760::ifcfg::264::root::(_atomicNetworkBackup) Backed up rhevm
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:10,761::libvirtconnection::108::libvirtconnection::(wrapper) Unknown libvirterror: ecode: 43 edom: 19 level: 2 message: Network not found: no network with matching name 'vdsm-rhevm'
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:10,762::ifcfg::273::root::(_persistentNetworkBackup) backing up network rhevm: # original file did not exist
>
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:10,763::ifcfg::254::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/logicalnetworks/rhevm (until next 'set safe config')
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:10,874::configNetwork::544::setupNetworks::(setupNetworks) Checking connectivity...
>MainProcess|Thread-15::DEBUG::2013-12-31 14:05:11,875::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return setupNetworks with None
>MainProcess|Thread-23::DEBUG::2013-12-31 14:05:12,432::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call setSafeNetworkConfig with () {}
>MainProcess|Thread-23::DEBUG::2013-12-31 14:05:12,432::configNetwork::570::Storage.Misc.excCmd::(setSafeNetworkConfig) '/usr/share/vdsm/vdsm-store-net-config' (cwd None)
>MainProcess|Thread-23::DEBUG::2013-12-31 14:05:12,441::configNetwork::570::Storage.Misc.excCmd::(setSafeNetworkConfig) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-23::DEBUG::2013-12-31 14:05:12,441::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return setSafeNetworkConfig with None
>MainProcess|Thread-25::DEBUG::2013-12-31 14:05:12,852::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-25::DEBUG::2013-12-31 14:05:12,971::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'IBM System x3550 -[797842G]-', 'systemSerialNumber': '0000000', 'systemFamily': 'Not Specified', 'systemVersion': 'Not Specified', 'systemUUID': 'c031ad93-3b43-b601-0a5e-00145edd0924', 'systemManufacturer': 'IBM'}
>MainProcess|Thread-27::DEBUG::2013-12-31 14:05:13,049::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-27::DEBUG::2013-12-31 14:05:13,049::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'IBM System x3550 -[797842G]-', 'systemSerialNumber': '0000000', 'systemFamily': 'Not Specified', 'systemVersion': 'Not Specified', 'systemUUID': 'c031ad93-3b43-b601-0a5e-00145edd0924', 'systemManufacturer': 'IBM'}
>MainProcess|Thread-29::DEBUG::2013-12-31 14:05:13,622::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', ('qemu', 'kvm'), '/rhev/data-center/mnt/10.35.160.108:_RHEV_myakove-rhevm33-nfs', 5) {}
>MainProcess|Thread-29::DEBUG::2013-12-31 14:05:13,631::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,698::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,698::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,698::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,699::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,705::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,711::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,716::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,721::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,727::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,732::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,737::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:13,742::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:15,746::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-30::DEBUG::2013-12-31 14:05:15,746::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None
>MainProcess|Thread-79::DEBUG::2013-12-31 14:06:16,900::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-79::ERROR::2013-12-31 14:06:16,901::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|Thread-84::DEBUG::2013-12-31 14:06:19,967::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-84::ERROR::2013-12-31 14:06:19,968::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|Thread-87::DEBUG::2013-12-31 14:06:23,016::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-87::ERROR::2013-12-31 14:06:23,017::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|Thread-90::DEBUG::2013-12-31 14:06:26,055::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-90::ERROR::2013-12-31 14:06:26,055::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|Thread-95::DEBUG::2013-12-31 14:06:29,114::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-95::ERROR::2013-12-31 14:06:29,114::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|Thread-98::DEBUG::2013-12-31 14:06:32,153::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-98::ERROR::2013-12-31 14:06:32,153::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|Thread-101::DEBUG::2013-12-31 14:06:35,213::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-101::ERROR::2013-12-31 14:06:35,213::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess::DEBUG::2013-12-31 14:06:41,076::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {}
>MainProcess::DEBUG::2013-12-31 14:06:41,077::supervdsmServer::102::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|storageRefresh::DEBUG::2013-12-31 14:06:42,077::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:42,077::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:42,078::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:42,078::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:42,082::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:42,086::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:43,091::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:43,094::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:43,098::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:43,101::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:43,104::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:43,108::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|Thread-14::DEBUG::2013-12-31 14:06:44,465::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-14::DEBUG::2013-12-31 14:06:44,465::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'IBM System x3550 -[797842G]-', 'systemSerialNumber': '0000000', 'systemFamily': 'Not Specified', 'systemVersion': 'Not Specified', 'systemUUID': 'c031ad93-3b43-b601-0a5e-00145edd0924', 'systemManufacturer': 'IBM'}
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:45,111::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|storageRefresh::DEBUG::2013-12-31 14:06:45,111::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,286::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,287::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,287::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,288::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,295::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,298::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,301::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,305::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,308::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,311::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,315::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:45,318::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:47,321::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-17::DEBUG::2013-12-31 14:06:47,322::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None
>MainProcess|PolicyEngine::DEBUG::2013-12-31 14:06:51,696::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {}
>MainProcess|PolicyEngine::DEBUG::2013-12-31 14:06:51,697::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None
>MainProcess|Thread-6105::DEBUG::2013-12-31 16:55:17,723::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call prepareVmChannel with ('/var/lib/libvirt/qemu/channels/f53b10c4-b8c4-4d1e-8b41-b32cbc536d2e.com.redhat.rhevm.vdsm',) {}
>MainProcess|Thread-6105::DEBUG::2013-12-31 16:55:17,733::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return prepareVmChannel with None
>MainProcess|Thread-6105::DEBUG::2013-12-31 16:55:17,746::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getVmPid with ('omer1',) {}
>MainProcess|Thread-6105::DEBUG::2013-12-31 16:55:17,748::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getVmPid with 13752
>MainProcess|Thread-6311::DEBUG::2013-12-31 17:00:25,219::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-6311::ERROR::2013-12-31 17:00:25,219::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|Thread-6325::DEBUG::2013-12-31 17:00:45,292::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|Thread-6325::ERROR::2013-12-31 17:00:45,292::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess|libvirtEventLoop::DEBUG::2013-12-31 17:01:19,079::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call removeFs with ('',) {}
>MainProcess|libvirtEventLoop::ERROR::2013-12-31 17:01:19,080::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) Error in removeFs
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 97, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 329, in removeFs
>    return mkimage.removeFs(path)
>  File "/usr/share/vdsm/mkimage.py", line 133, in removeFs
>    _P_PAYLOAD_IMAGES)
>Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload
>MainProcess::DEBUG::2013-12-31 17:04:58,322::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {}
>MainProcess::DEBUG::2013-12-31 17:04:58,322::supervdsmServer::102::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|storageRefresh::DEBUG::2013-12-31 17:04:59,356::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:04:59,357::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:04:59,357::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:04:59,358::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:04:59,364::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:04:59,370::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:00,376::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:00,390::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:00,397::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:01,404::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:01,416::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:01,428::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|Thread-14::DEBUG::2013-12-31 17:05:01,904::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-14::DEBUG::2013-12-31 17:05:01,904::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'IBM System x3550 -[797842G]-', 'systemSerialNumber': '0000000', 'systemFamily': 'Not Specified', 'systemVersion': 'Not Specified', 'systemUUID': 'c031ad93-3b43-b601-0a5e-00145edd0924', 'systemManufacturer': 'IBM'}
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:03,431::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|storageRefresh::DEBUG::2013-12-31 17:05:03,432::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:03,604::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:03,604::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:03,605::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:03,605::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:04,622::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:04,626::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:05,631::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:05,634::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:05,638::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:05,641::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:05,645::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:05,648::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:07,651::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-17::DEBUG::2013-12-31 17:05:07,652::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None
>MainProcess|PolicyEngine::DEBUG::2013-12-31 17:05:08,844::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {}
>MainProcess|PolicyEngine::DEBUG::2013-12-31 17:05:08,845::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:37,506::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:37,507::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:37,508::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:37,509::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:37,533::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:38,542::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:38,548::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:38,553::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:38,559::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:38,564::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:38,569::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:38,574::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:40,578::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-37640::DEBUG::2014-01-01 10:29:40,579::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None
>MainProcess|Thread-37643::DEBUG::2014-01-01 10:31:02,744::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-37643::DEBUG::2014-01-01 10:31:02,744::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'IBM System x3550 -[797842G]-', 'systemSerialNumber': '0000000', 'systemFamily': 'Not Specified', 'systemVersion': 'Not Specified', 'systemUUID': 'c031ad93-3b43-b601-0a5e-00145edd0924', 'systemManufacturer': 'IBM'}
>MainProcess|Thread-37645::DEBUG::2014-01-01 10:31:46,788::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-37645::DEBUG::2014-01-01 10:31:46,788::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'IBM System x3550 -[797842G]-', 'systemSerialNumber': '0000000', 'systemFamily': 'Not Specified', 'systemVersion': 'Not Specified', 'systemUUID': 'c031ad93-3b43-b601-0a5e-00145edd0924', 'systemManufacturer': 'IBM'}
>MainProcess|Thread-37647::DEBUG::2014-01-01 10:31:46,979::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-37647::DEBUG::2014-01-01 10:31:46,979::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'IBM System x3550 -[797842G]-', 'systemSerialNumber': '0000000', 'systemFamily': 'Not Specified', 'systemVersion': 'Not Specified', 'systemUUID': 'c031ad93-3b43-b601-0a5e-00145edd0924', 'systemManufacturer': 'IBM'}
>MainProcess|Thread-37649::DEBUG::2014-01-01 10:31:47,111::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call validateAccess with ('qemu', ('qemu', 'kvm'), '/rhev/data-center/mnt/10.35.160.108:_RHEV_myakove-rhevm33-nfs', 5) {}
>MainProcess|Thread-37649::DEBUG::2014-01-01 10:31:47,120::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return validateAccess with None
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:47,171::supervdsmServer::95::SuperVdsm.ServerCallback::(wrapper) call forceScsiScan with () {}
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:47,171::misc::809::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.forceScsiScan)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:47,171::misc::811::SamplingMethod::(__call__) Got in to sampling method
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:47,172::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:47,179::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:48,187::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:48,192::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:48,198::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:48,203::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:48,208::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host6/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:49,215::iscsi::390::Storage.Misc.excCmd::(forceScsiScan) '/bin/dd of=/sys/class/scsi_host/host7/scan' (cwd None)
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:49,220::iscsi::416::Storage.ISCSI::(forceScsiScan) Performing SCSI scan, this will take up to 30 seconds
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:51,224::misc::819::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-37650::DEBUG::2014-01-01 10:31:51,225::supervdsmServer::102::SuperVdsm.ServerCallback::(wrapper) return forceScsiScan with None

Actions: View

Attachments on bug 1047356: 843472 | 844562