Attachment 938520 Details for Bug 1142909 – vdms.log

vdms.log

vdsm.log (text/plain), 30.34 KB, created by Piotr Kliczewski on 2014-09-17 15:20:36 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Piotr Kliczewski

Created: 2014-09-17 15:20:36 UTC

Size: 30.34 KB

patch

obsolete

>MainThread::INFO::2014-09-17 16:02:10,960::vdsm::131::vds::(run) (PID: 5072) I am the actual vdsm 4.16.4-0.el6 localhost.localdomain (2.6.32-431.el6.x86_64)
>MainThread::DEBUG::2014-09-17 16:02:10,961::resourceManager::421::Storage.ResourceManager::(registerNamespace) Registering namespace 'Storage'
>MainThread::DEBUG::2014-09-17 16:02:10,961::threadPool::35::Storage.ThreadPool::(__init__) Enter - numThreads: 10, waitTimeout: 3, maxTasks: 500
>MainThread::DEBUG::2014-09-17 16:02:10,964::fileUtils::142::Storage.fileUtils::(createdir) Creating directory: /rhev/data-center/mnt
>MainThread::DEBUG::2014-09-17 16:02:11,043::multipath::151::Storage.Multipath::(isEnabled) multipath Defaulting to False
>MainThread::DEBUG::2014-09-17 16:02:11,044::multipath::169::Storage.Misc.excCmd::(setupMultipath) /usr/bin/sudo -n /bin/cp /tmp/tmp_ki3Ei /etc/multipath.conf (cwd None)
>MainThread::DEBUG::2014-09-17 16:02:11,053::multipath::169::Storage.Misc.excCmd::(setupMultipath) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-09-17 16:02:11,054::multipath::175::Storage.Misc.excCmd::(setupMultipath) /usr/bin/sudo -n /sbin/multipath -F (cwd None)
>MainThread::DEBUG::2014-09-17 16:02:11,116::multipath::175::Storage.Misc.excCmd::(setupMultipath) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-09-17 16:02:11,116::multipath::178::Storage.Misc.excCmd::(setupMultipath) /usr/bin/sudo -n /usr/bin/vdsm-tool service-reload multipathd (cwd None)
>MainThread::DEBUG::2014-09-17 16:02:11,309::multipath::178::Storage.Misc.excCmd::(setupMultipath) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-09-17 16:02:11,309::hsm::418::Storage.Misc.excCmd::(__validateLvmLockingType) /usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type (cwd None)
>MainThread::DEBUG::2014-09-17 16:02:11,418::hsm::418::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-09-17 16:02:11,418::hsm::442::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center'
>MainThread::DEBUG::2014-09-17 16:02:11,420::hsm::474::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt']
>MainThread::DEBUG::2014-09-17 16:02:11,420::hsm::475::Storage.HSM::(__cleanStorageRepository) Mount list: []
>MainThread::DEBUG::2014-09-17 16:02:11,420::hsm::477::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers
>MainThread::DEBUG::2014-09-17 16:02:11,421::hsm::520::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center'
>MainThread::INFO::2014-09-17 16:02:11,425::dispatcher::46::Storage.Dispatcher::(__init__) Starting StorageDispatcher...
>MainThread::DEBUG::2014-09-17 16:02:11,572::task::595::Storage.TaskManager.Task::(_updateState) Task=`8eda3346-4c82-4a3d-b731-ab9e639e3941`::moving from state init -> state preparing
>MainThread::INFO::2014-09-17 16:02:11,572::logUtils::44::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<bound method clientIF.contEIOVms of <clientIF.clientIF object at 0x299c550>>)
>MainThread::INFO::2014-09-17 16:02:11,575::logUtils::47::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None
>MainThread::DEBUG::2014-09-17 16:02:11,575::task::1191::Storage.TaskManager.Task::(prepare) Task=`8eda3346-4c82-4a3d-b731-ab9e639e3941`::finished: None
>MainThread::DEBUG::2014-09-17 16:02:11,575::task::595::Storage.TaskManager.Task::(_updateState) Task=`8eda3346-4c82-4a3d-b731-ab9e639e3941`::moving from state preparing -> state finished
>MainThread::DEBUG::2014-09-17 16:02:11,575::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>MainThread::DEBUG::2014-09-17 16:02:11,575::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>MainThread::DEBUG::2014-09-17 16:02:11,575::task::993::Storage.TaskManager.Task::(_decref) Task=`8eda3346-4c82-4a3d-b731-ab9e639e3941`::ref 0 aborting False
>storageRefresh::DEBUG::2014-09-17 16:02:11,576::misc::741::Storage.SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
>storageRefresh::DEBUG::2014-09-17 16:02:11,576::misc::743::Storage.SamplingMethod::(__call__) Got in to sampling method
>storageRefresh::DEBUG::2014-09-17 16:02:11,576::misc::741::Storage.SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
>storageRefresh::DEBUG::2014-09-17 16:02:11,576::misc::743::Storage.SamplingMethod::(__call__) Got in to sampling method
>storageRefresh::DEBUG::2014-09-17 16:02:11,576::iscsi::403::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds
>storageRefresh::DEBUG::2014-09-17 16:02:11,576::iscsiadm::92::Storage.Misc.excCmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m session -R (cwd None)
>MainThread::INFO::2014-09-17 16:02:11,590::momIF::49::MOM::(__init__) Starting up MOM
>MainThread::INFO::2014-09-17 16:02:11,641::vmchannels::192::vds::(settimeout) Setting channels' timeout to 30 seconds.
>MainThread::DEBUG::2014-09-17 16:02:11,675::protocoldetector::129::vds.MultiProtocolAcceptor::(add_detector) adding detector: <rpc.BindingXMLRPC.XmlDetector instance at 0x2add3f8>
>storageRefresh::DEBUG::2014-09-17 16:02:11,725::misc::751::Storage.SamplingMethod::(__call__) Returning last result
>storageRefresh::DEBUG::2014-09-17 16:02:11,725::supervdsm::77::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm
>VM Channels Listener::INFO::2014-09-17 16:02:11,748::vmchannels::175::vds::(run) Starting VM channels listener thread.
>clientIFinit::DEBUG::2014-09-17 16:02:11,749::libvirtconnection::150::root::(get) trying to connect libvirt
>MainThread::DEBUG::2014-09-17 16:02:11,772::protocoldetector::129::vds.MultiProtocolAcceptor::(add_detector) adding detector: <yajsonrpc.stompReactor.StompDetector instance at 0x2c58950>
>JsonRpcServer::DEBUG::2014-09-17 16:02:11,798::__init__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request
>Detector thread::DEBUG::2014-09-17 16:02:11,813::protocoldetector::68::vds.MultiProtocolAcceptor::(serve_forever) Acceptor running
>Detector thread::DEBUG::2014-09-17 16:02:11,813::protocoldetector::70::vds.MultiProtocolAcceptor::(serve_forever) Using required_size=7
>storageRefresh::DEBUG::2014-09-17 16:02:12,138::multipath::110::Storage.Misc.excCmd::(rescan) /usr/bin/sudo -n /sbin/multipath (cwd None)
>storageRefresh::DEBUG::2014-09-17 16:02:12,328::multipath::110::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0
>storageRefresh::DEBUG::2014-09-17 16:02:12,329::lvm::489::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,329::lvm::491::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,329::lvm::500::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,329::lvm::502::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,329::lvm::520::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,329::lvm::522::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,329::misc::751::Storage.SamplingMethod::(__call__) Returning last result
>storageRefresh::DEBUG::2014-09-17 16:02:12,330::lvm::317::Storage.OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,331::lvm::288::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  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size (cwd None)
>storageRefresh::DEBUG::2014-09-17 16:02:12,437::lvm::288::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = ''; <rc> = 0
>storageRefresh::DEBUG::2014-09-17 16:02:12,438::lvm::342::Storage.OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,438::lvm::365::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,438::lvm::288::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  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -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-09-17 16:02:12,518::lvm::288::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '  No volume groups found\n'; <rc> = 0
>storageRefresh::DEBUG::2014-09-17 16:02:12,518::lvm::407::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,518::lvm::288::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  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags (cwd None)
>storageRefresh::DEBUG::2014-09-17 16:02:12,595::lvm::288::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '  No volume groups found\n'; <rc> = 0
>storageRefresh::DEBUG::2014-09-17 16:02:12,595::lvm::365::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,596::lvm::288::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  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -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-09-17 16:02:12,669::lvm::288::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '  No volume groups found\n'; <rc> = 0
>storageRefresh::DEBUG::2014-09-17 16:02:12,670::lvm::407::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>storageRefresh::DEBUG::2014-09-17 16:02:12,670::hsm::387::Storage.HSM::(storageRefresh) HSM is ready
>Detector thread::DEBUG::2014-09-17 16:02:12,741::protocoldetector::160::vds.MultiProtocolAcceptor::(_add_connection) Adding connection from 192.168.1.10:37032
>Detector thread::DEBUG::2014-09-17 16:02:12,742::protocoldetector::171::vds.MultiProtocolAcceptor::(_remove_connection) Connection removed from 192.168.1.10:37032
>Detector thread::DEBUG::2014-09-17 16:02:12,742::protocoldetector::197::vds.MultiProtocolAcceptor::(_handle_connection_read) Detected protocol stomp from 192.168.1.10:37032
>Detector thread::DEBUG::2014-09-17 16:02:12,742::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='CONNECT'>
>Detector thread::INFO::2014-09-17 16:02:12,742::stompReactor::66::Broker.StompAdapter::(_cmd_connect) Processing CONNECT request
>Detector thread::DEBUG::2014-09-17 16:02:12,742::stompReactor::338::protocoldetector.StompDetector::(handleSocket) Stomp detected from ('192.168.1.10', 37032)
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:12,743::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SUBSCRIBE'>
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:12,743::stompReactor::89::Broker.StompAdapter::(_cmd_subscribe) Subscribe command ignored
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:12,782::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SEND'>
>JsonRpcServer::DEBUG::2014-09-17 16:02:12,783::__init__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request
>Thread-13::DEBUG::2014-09-17 16:02:12,783::__init__::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.ping' in bridge with {}
>Thread-13::DEBUG::2014-09-17 16:02:12,784::__init__::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.ping' in bridge with True
>Thread-13::DEBUG::2014-09-17 16:02:12,784::stompReactor::163::yajsonrpc.StompServer::(send) Sending response
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:12,785::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SEND'>
>JsonRpcServer::DEBUG::2014-09-17 16:02:12,786::__init__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request
>Thread-14::DEBUG::2014-09-17 16:02:12,786::__init__::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getCapabilities' in bridge with {}
>Thread-14::DEBUG::2014-09-17 16:02:12,841::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,846::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,864::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/bonding_masters (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,879::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,880::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/AnZB8Q8zZIah6iz/bonding/mode (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,889::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,890::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/AnZB8Q8zZIah6iz/bonding/mode (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,900::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,901::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/AnZB8Q8zZIah6iz/bonding/mode (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,921::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,922::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/AnZB8Q8zZIah6iz/bonding/mode (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,929::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,930::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/AnZB8Q8zZIah6iz/bonding/mode (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,936::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,937::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/AnZB8Q8zZIah6iz/bonding/mode (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,943::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,944::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/AnZB8Q8zZIah6iz/bonding/mode (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,951::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:12,952::utils::738::root::(execCmd) /usr/bin/sudo -n /usr/bin/tee /sys/class/net/bonding_masters (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:12,969::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:13,023::caps::646::root::(get) VirtioRNG DISABLED: libvirt version 0.10.2-29.el6_5.12 required >= 0.10.2-31
>Thread-14::DEBUG::2014-09-17 16:02:13,024::utils::738::root::(execCmd) numactl --hardware (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:13,028::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:13,029::utils::738::root::(execCmd) sysctl -n -e kernel.numa_balancing (cwd None)
>Thread-14::DEBUG::2014-09-17 16:02:13,031::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2014-09-17 16:02:13,034::caps::269::root::(getLiveMergeSupport) libvirt is missing 'VIR_DOMAIN_BLOCK_COMMIT_RELATIVE': live merge disabled
>Thread-14::DEBUG::2014-09-17 16:02:13,034::__init__::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getCapabilities' in bridge with {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:33af6a7e8294'}], 'FC': []}, 'packages2': {'kernel': {'release': '431.el6.x86_64', 'buildtime': 1385086509.0, 'version': '2.6.32'}, 'spice-server': {'release': '6.el6_5.2', 'buildtime': 1402324637L, 'version': '0.12.4'}, 'vdsm': {'release': '0.el6', 'buildtime': 1410384705L, 'version': '4.16.4'}, 'qemu-kvm': {'release': '2.415.el6_5.14', 'buildtime': 1408575896L, 'version': '0.12.1.2'}, 'libvirt': {'release': '29.el6_5.12', 'buildtime': 1409579341L, 'version': '0.10.2'}, 'qemu-img': {'release': '2.415.el6_5.14', 'buildtime': 1408575896L, 'version': '0.12.1.2'}, 'mom': {'release': '2.el6', 'buildtime': 1403794344L, 'version': '0.4.1'}}, 'numaNodeDistance': {'0': [10]}, 'cpuModel': 'Intel Xeon E312xx (Sandy Bridge)', 'liveMerge': 'false', 'hooks': {}, 'cpuSockets': '1', 'vmTypes': ['kvm'], 'selinux': {'mode': '1'}, 'kdumpStatus': 0, 'supportedProtocols': ['2.2', '2.3'], 'networks': {}, 'bridges': {'virbr0': {'addr': '192.168.122.1', 'cfg': {}, 'ipv6addrs': [], 'mtu': '1500', 'netmask': '255.255.255.0', 'stp': 'on', 'ipv4addrs': ['192.168.122.1/24'], 'ipv6gateway': '::', 'gateway': '', 'opts': {'topology_change_detected': '0', 'multicast_last_member_count': '2', 'hash_elasticity': '4', 'multicast_query_response_interval': '999', 'multicast_snooping': '1', 'multicast_startup_query_interval': '3124', 'hello_timer': '194', 'multicast_querier_interval': '25496', 'max_age': '1999', 'hash_max': '512', 'stp_state': '1', 'root_id': '8000.52540037209a', 'priority': '32768', 'multicast_membership_interval': '25996', 'root_path_cost': '0', 'root_port': '0', 'multicast_querier': '0', 'multicast_startup_query_count': '2', 'hello_time': '199', 'topology_change': '0', 'bridge_id': '8000.52540037209a', 'topology_change_timer': '0', 'ageing_time': '29995', 'gc_timer': '94', 'group_addr': '1:80:c2:0:0:0', 'tcn_timer': '0', 'multicast_query_interval': '12498', 'multicast_last_member_interval': '99', 'multicast_router': '1', 'forward_delay': '0'}, 'ports': ['virbr0-nic']}}, 'uuid': 'C305F70B-36EF-A64B-EE8B-CF3922306CF4', 'lastClientIface': 'eth0', 'nics': {'eth0': {'addr': '192.168.1.20', 'cfg': {}, 'ipv6addrs': ['fe80::5054:ff:fe38:b1c0/64'], 'mtu': '1500', 'netmask': '255.255.255.0', 'ipv4addrs': ['192.168.1.20/24'], 'hwaddr': '52:54:00:38:b1:c0', 'speed': 100}}, 'software_revision': '0', 'clusterLevels': ['3.0', '3.1', '3.2', '3.3', '3.4', '3.5'], 'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,mmx,fxsr,sse,sse2,ss,syscall,nx,rdtscp,lm,constant_tsc,up,rep_good,unfair_spinlock,pni,pclmulqdq,vmx,ssse3,cx16,pcid,sse4_1,sse4_2,x2apic,popcnt,tsc_deadline_timer,aes,xsave,avx,f16c,rdrand,hypervisor,lahf_lm,xsaveopt,vnmi,ept,fsgsbase,smep,erms,model_Nehalem,model_Conroe,model_Penryn,model_Westmere,model_SandyBridge', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:33af6a7e8294', 'netConfigDirty': 'False', 'supportedENGINEs': ['3.0', '3.1', '3.2', '3.3', '3.4', '3.5'], 'autoNumaBalancing': 2, 'reservedMem': '321', 'bondings': {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'ipv6addrs': [], 'mtu': '1500', 'netmask': '', 'ipv4addrs': [], 'hwaddr': '00:00:00:00:00:00', 'slaves': [], 'opts': {}}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond2': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}}, 'software_version': '4.16', 'memSize': '996', 'cpuSpeed': '3392.328', 'numaNodes': {u'0': {'totalMemory': '996', 'cpus': [0]}}, 'version_name': 'Snow Man', 'vlans': {}, 'cpuCores': '1', 'kvmEnabled': 'true', 'guestOverhead': '65', 'cpuThreads': '1', 'emulatedMachines': [u'rhel6.5.0', u'pc', u'rhel6.4.0', u'rhel6.3.0', u'rhel6.2.0', u'rhel6.1.0', u'rhel6.0.0', u'rhel5.5.0', u'rhel5.4.4', u'rhel5.4.0'], 'operatingSystem': {'release': '5.el6.centos.11.1', 'version': '6', 'name': 'RHEL'}}
>Thread-14::DEBUG::2014-09-17 16:02:13,036::stompReactor::163::yajsonrpc.StompServer::(send) Sending response
>Detector thread::DEBUG::2014-09-17 16:02:13,132::protocoldetector::160::vds.MultiProtocolAcceptor::(_add_connection) Adding connection from 192.168.1.10:37033
>Detector thread::DEBUG::2014-09-17 16:02:13,133::protocoldetector::171::vds.MultiProtocolAcceptor::(_remove_connection) Connection removed from 192.168.1.10:37033
>Detector thread::DEBUG::2014-09-17 16:02:13,133::protocoldetector::197::vds.MultiProtocolAcceptor::(_handle_connection_read) Detected protocol stomp from 192.168.1.10:37033
>Detector thread::DEBUG::2014-09-17 16:02:13,133::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='CONNECT'>
>Detector thread::INFO::2014-09-17 16:02:13,133::stompReactor::66::Broker.StompAdapter::(_cmd_connect) Processing CONNECT request
>Detector thread::DEBUG::2014-09-17 16:02:13,133::stompReactor::338::protocoldetector.StompDetector::(handleSocket) Stomp detected from ('192.168.1.10', 37033)
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:13,134::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SUBSCRIBE'>
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:13,134::stompReactor::89::Broker.StompAdapter::(_cmd_subscribe) Subscribe command ignored
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:13,173::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SEND'>
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:02:13,173::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SEND'>
>JsonRpcServer::DEBUG::2014-09-17 16:02:13,174::__init__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request
>Thread-15::DEBUG::2014-09-17 16:02:13,174::__init__::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.ping' in bridge with {}
>Thread-15::DEBUG::2014-09-17 16:02:13,175::__init__::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.ping' in bridge with True
>Thread-15::DEBUG::2014-09-17 16:02:13,175::stompReactor::163::yajsonrpc.StompServer::(send) Sending response
>JsonRpcServer::DEBUG::2014-09-17 16:02:13,175::__init__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request
>Thread-16::DEBUG::2014-09-17 16:02:13,175::__init__::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.setupNetworks' in bridge with {u'bondings': {}, u'networks': {u'ovirtmgmt': {u'nic': u'eth0', u'STP': u'no', u'bridged': u'true', u'mtu': u'1500'}}, u'options': {u'connectivityCheck': u'true', u'connectivityTimeout': 120}}
>Thread-16::ERROR::2014-09-17 16:04:15,323::API::1487::vds::(_rollback) connectivity check failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/API.py", line 1485, in _rollback
>    yield rollbackCtx
>  File "/usr/share/vdsm/API.py", line 1373, in setupNetworks
>    supervdsm.getProxy().setupNetworks(networks, bondings, options)
>  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
>    return callMethod()
>  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
>    **kwargs)
>  File "<string>", line 2, in setupNetworks
>  File "/usr/lib64/python2.6/multiprocessing/managers.py", line 740, in _callmethod
>    raise convert_to_error(kind, result)
>ConfigNetworkError: (10, 'connectivity check failed')
>Thread-16::DEBUG::2014-09-17 16:04:15,331::stompReactor::163::yajsonrpc.StompServer::(send) Sending response
>Detector thread::ERROR::2014-09-17 16:23:16,440::protocoldetector::79::vds.MultiProtocolAcceptor::(serve_forever) Unhandled exception
>Traceback (most recent call last):
>  File "/usr/share/vdsm/protocoldetector.py", line 75, in serve_forever
>    self._process_events()
>  File "/usr/share/vdsm/protocoldetector.py", line 92, in _process_events
>    self._accept_connection()
>  File "/usr/share/vdsm/protocoldetector.py", line 155, in _accept_connection
>    client_socket, _ = self._socket.accept()
>  File "/usr/lib64/python2.6/site-packages/vdsm/sslutils.py", line 114, in accept
>    raise SSL.SSLError("%s, client %s" % (e, address[0]))
>SSLError: unexpected eof, client 192.168.1.10
>Detector thread::ERROR::2014-09-17 16:23:16,453::protocoldetector::79::vds.MultiProtocolAcceptor::(serve_forever) Unhandled exception
>Traceback (most recent call last):
>  File "/usr/share/vdsm/protocoldetector.py", line 75, in serve_forever
>    self._process_events()
>  File "/usr/share/vdsm/protocoldetector.py", line 92, in _process_events
>    self._accept_connection()
>  File "/usr/share/vdsm/protocoldetector.py", line 155, in _accept_connection
>    client_socket, _ = self._socket.accept()
>  File "/usr/lib64/python2.6/site-packages/vdsm/sslutils.py", line 114, in accept
>    raise SSL.SSLError("%s, client %s" % (e, address[0]))
>SSLError: unexpected eof, client 192.168.1.10
>Detector thread::ERROR::2014-09-17 16:23:16,454::protocoldetector::79::vds.MultiProtocolAcceptor::(serve_forever) Unhandled exception
>Traceback (most recent call last):
>  File "/usr/share/vdsm/protocoldetector.py", line 75, in serve_forever
>    self._process_events()
>  File "/usr/share/vdsm/protocoldetector.py", line 92, in _process_events
>    self._accept_connection()
>  File "/usr/share/vdsm/protocoldetector.py", line 155, in _accept_connection
>    client_socket, _ = self._socket.accept()
>  File "/usr/lib64/python2.6/site-packages/vdsm/sslutils.py", line 114, in accept
>    raise SSL.SSLError("%s, client %s" % (e, address[0]))
>SSLError: unexpected eof, client 192.168.1.10
>Detector thread::ERROR::2014-09-17 16:23:16,454::protocoldetector::79::vds.MultiProtocolAcceptor::(serve_forever) Unhandled exception
>Traceback (most recent call last):
>  File "/usr/share/vdsm/protocoldetector.py", line 75, in serve_forever
>    self._process_events()
>  File "/usr/share/vdsm/protocoldetector.py", line 92, in _process_events
>    self._accept_connection()
>  File "/usr/share/vdsm/protocoldetector.py", line 155, in _accept_connection
>    client_socket, _ = self._socket.accept()
>  File "/usr/lib64/python2.6/site-packages/vdsm/sslutils.py", line 114, in accept
>    raise SSL.SSLError("%s, client %s" % (e, address[0]))
>SSLError: unexpected eof, client 192.168.1.10
>Detector thread::DEBUG::2014-09-17 16:25:47,692::protocoldetector::160::vds.MultiProtocolAcceptor::(_add_connection) Adding connection from 192.168.1.10:37483
>Detector thread::DEBUG::2014-09-17 16:25:47,693::protocoldetector::171::vds.MultiProtocolAcceptor::(_remove_connection) Connection removed from 192.168.1.10:37483
>Detector thread::DEBUG::2014-09-17 16:25:47,693::protocoldetector::197::vds.MultiProtocolAcceptor::(_handle_connection_read) Detected protocol stomp from 192.168.1.10:37483
>Detector thread::DEBUG::2014-09-17 16:25:47,694::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='CONNECT'>
>Detector thread::INFO::2014-09-17 16:25:47,694::stompReactor::66::Broker.StompAdapter::(_cmd_connect) Processing CONNECT request
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:25:47,694::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SUBSCRIBE'>
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:25:47,694::stompReactor::89::Broker.StompAdapter::(_cmd_subscribe) Subscribe command ignored
>Detector thread::DEBUG::2014-09-17 16:25:47,694::stompReactor::338::protocoldetector.StompDetector::(handleSocket) Stomp detected from ('192.168.1.10', 37483)
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:25:47,734::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SEND'>
>JsonRpc (StompReactor)::DEBUG::2014-09-17 16:25:47,734::stompReactor::98::Broker.StompAdapter::(handle_frame) Handling message <StompFrame command='SEND'>
>JsonRpcServer::DEBUG::2014-09-17 16:25:47,736::__init__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request
>Thread-17::DEBUG::2014-09-17 16:25:47,736::__init__::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.ping' in bridge with {}
>Thread-17::DEBUG::2014-09-17 16:25:47,737::__init__::498::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.ping' in bridge with True
>Thread-17::DEBUG::2014-09-17 16:25:47,737::stompReactor::163::yajsonrpc.StompServer::(send) Sending response
>JsonRpcServer::DEBUG::2014-09-17 16:25:47,737::__init__::504::jsonrpc.JsonRpcServer::(serve_requests) Waiting for request
>Thread-18::DEBUG::2014-09-17 16:25:47,737::__init__::467::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.setupNetworks' in bridge with {u'bondings': {}, u'networks': {u'ovirtmgmt': {u'nic': u'eth0', u'STP': u'no', u'bridged': u'true', u'mtu': u'1500'}}, u'options': {u'connectivityCheck': u'true', u'connectivityTimeout': 120}}
>Thread-18::ERROR::2014-09-17 16:27:53,428::API::1487::vds::(_rollback) connectivity check failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/API.py", line 1485, in _rollback
>    yield rollbackCtx
>  File "/usr/share/vdsm/API.py", line 1373, in setupNetworks
>    supervdsm.getProxy().setupNetworks(networks, bondings, options)
>  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
>    return callMethod()
>  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
>    **kwargs)
>  File "<string>", line 2, in setupNetworks
>  File "/usr/lib64/python2.6/multiprocessing/managers.py", line 740, in _callmethod
>    raise convert_to_error(kind, result)
>ConfigNetworkError: (10, 'connectivity check failed')
>Thread-18::DEBUG::2014-09-17 16:27:53,435::stompReactor::163::yajsonrpc.StompServer::(send) Sending response

Actions: View

Attachments on bug 1142909: 938519 | 938520