Attachment 922127 Details for Bug 1124372 – supervdsm log

supervdsm log

supervdsm.log (text/plain), 28.14 KB, created by Jiri Moskovcak on 2014-07-29 11:50:35 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jiri Moskovcak

Created: 2014-07-29 11:50:35 UTC

Size: 28.14 KB

patch

obsolete

>MainThread::DEBUG::2014-07-29 12:33:04,096::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:04,096::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:04,117::supervdsmServer::405::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm
>MainThread::DEBUG::2014-07-29 12:33:04,117::supervdsmServer::414::SuperVdsm.Server::(main) Parsing cmd args
>MainThread::DEBUG::2014-07-29 12:33:04,117::supervdsmServer::417::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock
>MainThread::DEBUG::2014-07-29 12:33:04,117::supervdsmServer::421::SuperVdsm.Server::(main) Setting up keep alive thread
>MainThread::DEBUG::2014-07-29 12:33:04,117::supervdsmServer::427::SuperVdsm.Server::(main) Creating remote object manager
>MainThread::DEBUG::2014-07-29 12:33:04,118::fileUtils::192::Storage.fileUtils::(chown) Changing owner for /var/run/vdsm/svdsm.sock, to (36:36)
>MainThread::DEBUG::2014-07-29 12:33:04,118::supervdsmServer::438::SuperVdsm.Server::(main) Started serving super vdsm object
>sourceRoute::DEBUG::2014-07-29 12:33:04,119::sourceroutethread::75::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started
>sourceRoute::DEBUG::2014-07-29 12:33:04,119::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406564730
>sourceRoute::DEBUG::2014-07-29 12:33:04,119::libvirtconnection::150::root::(get) trying to connect libvirt
>sourceRoute::INFO::2014-07-29 12:33:04,139::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 12:33:04,139::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-07-29 12:33:04,139::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406572967
>sourceRoute::INFO::2014-07-29 12:33:04,140::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 12:33:04,140::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-07-29 12:33:04,140::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406582910
>sourceRoute::INFO::2014-07-29 12:33:04,140::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 12:33:04,140::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-07-29 12:33:04,141::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406592110
>sourceRoute::INFO::2014-07-29 12:33:04,141::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 12:33:04,141::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-07-29 12:33:04,141::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406601797
>sourceRoute::INFO::2014-07-29 12:33:04,142::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 12:33:04,142::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-07-29 12:33:04,142::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406611264
>sourceRoute::INFO::2014-07-29 12:33:04,143::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 12:33:04,143::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-07-29 12:33:04,143::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406621503
>sourceRoute::INFO::2014-07-29 12:33:04,144::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 12:33:04,144::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>MainThread::DEBUG::2014-07-29 12:33:05,814::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,814::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,814::vdsm-restore-net-config::55::root::(unified_restoration) Removing all networks ({}) and bonds ({}) in running config.
>MainThread::DEBUG::2014-07-29 12:33:05,815::libvirtconnection::150::root::(get) trying to connect libvirt
>MainThread::DEBUG::2014-07-29 12:33:05,833::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,835::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,836::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,838::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,844::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/bonding_masters (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,846::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,846::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/lQLkrhdM3ag5GYz/bonding/mode (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,848::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,850::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/lQLkrhdM3ag5GYz/bonding/mode (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,851::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,853::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/lQLkrhdM3ag5GYz/bonding/mode (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,855::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,856::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/lQLkrhdM3ag5GYz/bonding/mode (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,858::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,859::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/lQLkrhdM3ag5GYz/bonding/mode (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,861::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,862::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/lQLkrhdM3ag5GYz/bonding/mode (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,864::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,865::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/lQLkrhdM3ag5GYz/bonding/mode (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,867::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,868::utils::738::root::(execCmd) /usr/bin/tee /sys/class/net/bonding_masters (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,889::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,889::api::614::setupNetworks::(setupNetworks) Setting up network according to configuration: networks:{}, bondings:{}, options:{'_inRollback': True, 'connectivityCheck': False}
>MainThread::DEBUG::2014-07-29 12:33:05,889::api::618::root::(setupNetworks) Validating configuration
>MainThread::DEBUG::2014-07-29 12:33:05,891::api::630::setupNetworks::(setupNetworks) Applying...
>MainThread::DEBUG::2014-07-29 12:33:05,892::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,892::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,892::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,894::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,900::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,902::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::INFO::2014-07-29 12:33:05,907::netconfpersistence::158::root::(_clearDisk) Clearing /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/
>MainThread::DEBUG::2014-07-29 12:33:05,907::netconfpersistence::166::root::(_clearDisk) No existent config to clear.
>MainThread::INFO::2014-07-29 12:33:05,907::netconfpersistence::182::root::(save) Saved new config RunningConfig({}, {}) to /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/
>MainThread::DEBUG::2014-07-29 12:33:05,909::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,909::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,913::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,913::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,914::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,914::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,915::vdsm-restore-net-config::68::root::(unified_restoration) Calling setupNetworks with networks ({}) and bond ({}).
>MainThread::DEBUG::2014-07-29 12:33:05,917::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,919::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,920::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,922::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,926::api::614::setupNetworks::(setupNetworks) Setting up network according to configuration: networks:{}, bondings:{}, options:{'_inRollback': True, 'connectivityCheck': False}
>MainThread::DEBUG::2014-07-29 12:33:05,926::api::618::root::(setupNetworks) Validating configuration
>MainThread::DEBUG::2014-07-29 12:33:05,928::api::630::setupNetworks::(setupNetworks) Applying...
>MainThread::DEBUG::2014-07-29 12:33:05,929::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,929::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 12:33:05,929::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,931::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-07-29 12:33:05,937::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainThread::DEBUG::2014-07-29 12:33:05,939::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::INFO::2014-07-29 12:33:05,944::netconfpersistence::158::root::(_clearDisk) Clearing /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/
>MainThread::DEBUG::2014-07-29 12:33:05,944::netconfpersistence::166::root::(_clearDisk) No existent config to clear.
>MainThread::INFO::2014-07-29 12:33:05,944::netconfpersistence::182::root::(save) Saved new config RunningConfig({}, {}) to /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/
>MainProcess|Thread-14::DEBUG::2014-07-29 12:33:08,304::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-14::DEBUG::2014-07-29 12:33:08,334::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL320e Gen8', 'systemUUID': '30303734-3536-5A43-3133-343330305746', 'systemSerialNumber': 'CZ134300WF', 'systemFamily': 'ProLiant', 'systemManufacturer': 'HP'}
>MainProcess|PolicyEngine::DEBUG::2014-07-29 12:33:17,176::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {}
>MainProcess|PolicyEngine::DEBUG::2014-07-29 12:33:17,177::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None
>MainProcess|Thread-18::DEBUG::2014-07-29 12:33:40,079::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getPathsStatus with () {}
>MainProcess|Thread-18::DEBUG::2014-07-29 12:33:40,080::devicemapper::153::Storage.Misc.excCmd::(_getPathsStatus) /sbin/dmsetup status (cwd None)
>MainProcess|Thread-18::DEBUG::2014-07-29 12:33:40,094::devicemapper::153::Storage.Misc.excCmd::(_getPathsStatus) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-18::DEBUG::2014-07-29 12:33:40,095::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getPathsStatus with {}
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,100::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getPathsStatus with () {}
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,101::devicemapper::153::Storage.Misc.excCmd::(_getPathsStatus) /sbin/dmsetup status (cwd None)
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,111::devicemapper::153::Storage.Misc.excCmd::(_getPathsStatus) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,112::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getPathsStatus with {'sdc': 'active'}
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,113::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getScsiSerial with ('dm-3',) {}
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,114::multipath::213::Storage.Misc.excCmd::(getScsiSerial) /sbin/scsi_id --page=0x80 --whitelisted --export --replace-whitespace --device=/dev/dm-3 (cwd None)
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,121::multipath::213::Storage.Misc.excCmd::(getScsiSerial) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,122::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getScsiSerial with SIET_VIRTUAL-DISK
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,124::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call readSessionInfo with (1,) {}
>MainProcess|Thread-21::DEBUG::2014-07-29 12:33:43,125::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return readSessionInfo with IscsiSession(id=1, iface=<IscsiInterface name='default' transport='tcp'>, target=IscsiTarget(portal=IscsiPortal(hostname='10.34.131.193', port=3260), tpgt=1, iqn='iqn.2009-02.com.example:for.all'), credentials=<storage.iscsi.ChapCredentials object at 0x1f93610>)
>MainProcess::DEBUG::2014-07-29 12:36:10,562::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {}
>MainProcess::DEBUG::2014-07-29 12:36:10,563::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', '    polling_interval        5', '    getuid_callout          "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '    no_path_retry           fail', '    user_friendly_names     no', '    flush_on_last_del       yes', '    fast_io_fail_tmo        5', '    dev_loss_tmo            30', '    max_fds                 4096', '}', '', 'devices {', 'device {', '    vendor                  "HITACHI"', '    product                 "DF.*"', '    getuid_callout          "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', '    vendor                  "COMPELNT"', '    product                 "Compellent Vol"', '    no_path_retry           fail', '}', '}']
>MainProcess|Thread-14::DEBUG::2014-07-29 12:36:12,470::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-14::DEBUG::2014-07-29 12:36:12,471::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL320e Gen8', 'systemUUID': '30303734-3536-5A43-3133-343330305746', 'systemSerialNumber': 'CZ134300WF', 'systemFamily': 'ProLiant', 'systemManufacturer': 'HP'}
>MainProcess|Thread-16::DEBUG::2014-07-29 12:36:12,987::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call addNetwork with ('ovirtmgmt', {'force': 'False', 'nics': ['eth0'], 'bootproto': 'dhcp', 'bridged': 'True', 'blockingdhcp': 'true', 'ONBOOT': 'yes'}) {}
>MainProcess|Thread-16::DEBUG::2014-07-29 12:36:12,988::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainProcess|Thread-16::DEBUG::2014-07-29 12:36:12,988::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainProcess|Thread-16::DEBUG::2014-07-29 12:36:12,988::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainProcess|Thread-16::DEBUG::2014-07-29 12:36:12,994::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-16::WARNING::2014-07-29 12:36:13,005::libvirtconnection::135::root::(wrapper) connection to libvirt broken. ecode: 1 edom: 7
>MainProcess|Thread-16::CRITICAL::2014-07-29 12:36:13,005::libvirtconnection::137::root::(wrapper) taking calling process down.
>MainProcess|Thread-16::DEBUG::2014-07-29 12:36:13,007::libvirtconnection::143::root::(wrapper) Unknown libvirterror: ecode: 1 edom: 7 level: 2 message: internal error client socket is closed
>MainThread::DEBUG::2014-07-29 12:36:13,007::supervdsmServer::445::SuperVdsm.Server::(main) Terminated normally
>MainProcess|Thread-16::ERROR::2014-07-29 12:36:13,007::supervdsmServer::103::SuperVdsm.ServerCallback::(wrapper) Error in addNetwork
>Traceback (most recent call last):
>  File "/usr/share/vdsm/supervdsmServer", line 101, in wrapper
>    res = func(*args, **kwargs)
>  File "/usr/share/vdsm/supervdsmServer", line 209, in addNetwork
>    return addNetwork(bridge, **options)
>  File "/usr/share/vdsm/network/api.py", line 225, in wrapped
>    ret = func(**attrs)
>  File "/usr/share/vdsm/network/api.py", line 253, in addNetwork
>    _netinfo = netinfo.NetInfo()
>  File "/usr/lib64/python2.6/site-packages/vdsm/netinfo.py", line 904, in __init__
>    _netinfo = get()
>  File "/usr/lib64/python2.6/site-packages/vdsm/netinfo.py", line 813, in get
>    nets = networks()
>  File "/usr/lib64/python2.6/site-packages/vdsm/netinfo.py", line 119, in networks
>    allNets = ((net, net.name()) for net in conn.listAllNetworks(0))
>  File "/usr/lib64/python2.6/site-packages/vdsm/libvirtconnection.py", line 129, in wrapper
>    __connections.get(id(target)).pingLibvirt()
>  File "/usr/lib64/python2.6/site-packages/libvirt.py", line 3389, in getLibVersion
>    if ret == -1: raise libvirtError ('virConnectGetLibVersion() failed', conn=self)
>libvirtError: internal error client socket is closed
>MainThread::DEBUG::2014-07-29 13:01:03,374::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 13:01:03,374::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 13:01:03,395::supervdsmServer::405::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm
>MainThread::DEBUG::2014-07-29 13:01:03,395::supervdsmServer::414::SuperVdsm.Server::(main) Parsing cmd args
>MainThread::DEBUG::2014-07-29 13:01:03,396::supervdsmServer::417::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock
>MainThread::DEBUG::2014-07-29 13:01:03,396::supervdsmServer::421::SuperVdsm.Server::(main) Setting up keep alive thread
>MainThread::DEBUG::2014-07-29 13:01:03,396::supervdsmServer::427::SuperVdsm.Server::(main) Creating remote object manager
>MainThread::DEBUG::2014-07-29 13:01:03,397::fileUtils::192::Storage.fileUtils::(chown) Changing owner for /var/run/vdsm/svdsm.sock, to (36:36)
>MainThread::DEBUG::2014-07-29 13:01:03,397::supervdsmServer::438::SuperVdsm.Server::(main) Started serving super vdsm object
>sourceRoute::DEBUG::2014-07-29 13:01:03,397::sourceroutethread::75::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started
>sourceRoute::DEBUG::2014-07-29 13:01:03,397::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1406631354
>sourceRoute::DEBUG::2014-07-29 13:01:03,398::libvirtconnection::150::root::(get) trying to connect libvirt
>sourceRoute::INFO::2014-07-29 13:01:03,434::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-07-29 13:01:03,434::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>MainProcess::DEBUG::2014-07-29 13:01:04,916::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {}
>MainProcess::DEBUG::2014-07-29 13:01:04,917::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', '    polling_interval        5', '    getuid_callout          "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '    no_path_retry           fail', '    user_friendly_names     no', '    flush_on_last_del       yes', '    fast_io_fail_tmo        5', '    dev_loss_tmo            30', '    max_fds                 4096', '}', '', 'devices {', 'device {', '    vendor                  "HITACHI"', '    product                 "DF.*"', '    getuid_callout          "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', '    vendor                  "COMPELNT"', '    product                 "Compellent Vol"', '    no_path_retry           fail', '}', '}']
>MainProcess|PolicyEngine::DEBUG::2014-07-29 13:01:15,384::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0},) {}
>MainProcess|PolicyEngine::DEBUG::2014-07-29 13:01:15,385::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None
>MainProcess|Thread-13::DEBUG::2014-07-29 13:01:21,306::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-13::DEBUG::2014-07-29 13:01:21,334::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL320e Gen8', 'systemUUID': '30303734-3536-5A43-3133-343330305746', 'systemSerialNumber': 'CZ134300WF', 'systemFamily': 'ProLiant', 'systemManufacturer': 'HP'}
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,934::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getPathsStatus with () {}
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,934::devicemapper::153::Storage.Misc.excCmd::(_getPathsStatus) /sbin/dmsetup status (cwd None)
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,958::devicemapper::153::Storage.Misc.excCmd::(_getPathsStatus) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,958::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getPathsStatus with {'sdc': 'active'}
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,960::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getScsiSerial with ('dm-3',) {}
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,960::multipath::213::Storage.Misc.excCmd::(getScsiSerial) /sbin/scsi_id --page=0x80 --whitelisted --export --replace-whitespace --device=/dev/dm-3 (cwd None)
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,964::multipath::213::Storage.Misc.excCmd::(getScsiSerial) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,965::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getScsiSerial with SIET_VIRTUAL-DISK
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,967::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call readSessionInfo with (1,) {}
>MainProcess|Thread-17::DEBUG::2014-07-29 13:01:35,968::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return readSessionInfo with IscsiSession(id=1, iface=<IscsiInterface name='default' transport='tcp'>, target=IscsiTarget(portal=IscsiPortal(hostname='10.34.131.193', port=3260), tpgt=1, iqn='iqn.2009-02.com.example:for.all'), credentials=<storage.iscsi.ChapCredentials object at 0x14cca10>)
>MainProcess::DEBUG::2014-07-29 13:03:58,411::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call readMultipathConf with () {}
>MainProcess::DEBUG::2014-07-29 13:03:58,412::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return readMultipathConf with ['# RHEV REVISION 1.0', '', 'defaults {', '    polling_interval        5', '    getuid_callout          "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '    no_path_retry           fail', '    user_friendly_names     no', '    flush_on_last_del       yes', '    fast_io_fail_tmo        5', '    dev_loss_tmo            30', '    max_fds                 4096', '}', '', 'devices {', 'device {', '    vendor                  "HITACHI"', '    product                 "DF.*"', '    getuid_callout          "/sbin/scsi_id --whitelisted --replace-whitespace --device=/dev/%n"', '}', 'device {', '    vendor                  "COMPELNT"', '    product                 "Compellent Vol"', '    no_path_retry           fail', '}', '}']
>MainProcess|Thread-13::DEBUG::2014-07-29 13:03:59,267::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {}
>MainProcess|Thread-13::DEBUG::2014-07-29 13:03:59,267::supervdsmServer::106::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'ProLiant DL320e Gen8', 'systemUUID': '30303734-3536-5A43-3133-343330305746', 'systemSerialNumber': 'CZ134300WF', 'systemFamily': 'ProLiant', 'systemManufacturer': 'HP'}
>MainProcess|Thread-15::DEBUG::2014-07-29 13:03:59,783::supervdsmServer::99::SuperVdsm.ServerCallback::(wrapper) call addNetwork with ('ovirtmgmt', {'force': 'False', 'nics': ['eth0'], 'bootproto': 'dhcp', 'bridged': 'True', 'blockingdhcp': 'true', 'ONBOOT': 'yes'}) {}
>MainProcess|Thread-15::DEBUG::2014-07-29 13:03:59,784::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainProcess|Thread-15::DEBUG::2014-07-29 13:03:59,784::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainProcess|Thread-15::DEBUG::2014-07-29 13:03:59,784::utils::738::root::(execCmd) /sbin/ip route show to 0.0.0.0/0 table all (cwd None)
>MainProcess|Thread-15::DEBUG::2014-07-29 13:03:59,791::utils::758::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainProcess|Thread-15::WARNING::2014-07-29 13:03:59,799::libvirtconnection::135::root::(wrapper) connection to libvirt broken. ecode: 1 edom: 7
>MainProcess|Thread-15::CRITICAL::2014-07-29 13:03:59,799::libvirtconnection::137::root::(wrapper) taking calling process down.
>MainThread::DEBUG::2014-07-29 13:03:59,799::supervdsmServer::445::SuperVdsm.Server::(main) Terminated normally
>MainProcess|Thread-15::DEBUG::2014-07-29 13:03:59,799::libvirtconnection::143::root::(wrapper) Unknown libvirterror: ecode: 1 edom: 7 level: 2 message: internal error client socket is closed
>MainThread::DEBUG::2014-07-29 13:04:00,004::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 13:04:00,004::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-07-29 13:04:00,024::supervdsmServer::405::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm
>MainThread::DEBUG::2014-07-29 13:04:00,025::supervdsmServer::414::SuperVdsm.Server::(main) Parsing cmd args
>MainThread::DEBUG::2014-07-29 13:04:00,025::supervdsmServer::417::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock
>MainThread::DEBUG::2014-07-29 13:04:00,025::supervdsmServer::421::SuperVdsm.Server::(main) Setting up keep alive thread
>MainThread::DEBUG::2014-07-29 13:04:00,025::supervdsmServer::427::SuperVdsm.Server::(main) Creating remote object manager
>MainThread::DEBUG::2014-07-29 13:04:00,026::fileUtils::192::Storage.fileUtils::(chown) Changing owner for /var/run/vdsm/svdsm.sock, to (36:36)
>MainThread::DEBUG::2014-07-29 13:04:00,026::supervdsmServer::438::SuperVdsm.Server::(main) Started serving super vdsm object
>sourceRoute::DEBUG::2014-07-29 13:04:00,026::sourceroutethread::75::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started

Actions: View

Attachments on bug 1124372: 922110 | 922127 | 922187 | 922863