Re: [ovirt-users] Losing IP config on host

On 24 Mar 2016, at 05:03, David LeVene <David.LeVene@blackboard.com> = wrote: =20 Hi Rein, =20 Not sure if you are using IPv6, but I ran into this issue it wouldn't = configure things correctly. If I fixed it manually after a reboot it = would fail again. There is a patch that is being released in ovirt 3.6.4 = which resolves the issue I was having. =20 Your issue might be related - = http://lists.ovirt.org/pipermail/users/2016-March/038201.html which is = fixed in https://gerrit.ovirt.org/#/c/54237 =20 The RH guys will generally ask for your vdsm logs and maybe supervdsm = (see the above post for more information on where they are located). = Looking in these logs might guide you as well. =20 Regards David =20 -----Original Message----- From: users-bounces@ovirt.org [mailto:users-bounces@ovirt.org] On = Behalf Of Rein van Weerden Sent: Thursday, March 24, 2016 07:16 To: users@ovirt.org Subject: [ovirt-users] Losing IP config on host =20 Hi All, =20 When creating a new host i lose the ip configuration on the host while = creating the interfaces. Please advice. =20 Using: oVirt engine 3.6.3.4-1 on CentOS 7 oVirt engine SDK python 3.6.3.0 =20 ->Rein. =20 =20 =20 =20 _______________________________________________ Users mailing list Users@ovirt.org http://lists.ovirt.org/mailman/listinfo/users This email and any attachments may contain confidential and =

--Apple-Mail=_B565CEDD-E856-4748-912B-7B9679AB82CE Content-Transfer-Encoding: quoted-printable Content-Type: text/plain; charset=utf-8 Hi David, Thanks for your reaction. I=E2=80=99m using IPv4 addressing. I upgraded to oVirt 3.6.4.1-1.el7.centos, but that did not solve the = problem. When i use the setupnetworks from the Python SDK it looks like the that = the XML that send to the oVirt engine has the ip address settings but = they are not send to the host (hypervisor). But the VDSM removes the = network configuration. After that the communication between the engine = and the host is gone, obviously. I include some logs. --Apple-Mail=_B565CEDD-E856-4748-912B-7B9679AB82CE Content-Disposition: attachment; filename=nets.xml Content-Type: application/xml; name="nets.xml" Content-Transfer-Encoding: 7bit <action> <host_nics> <host_nic> <name>ens1f0</name> <network> <name>ovirtmgmt</name> </network> <ip netmask="255.255.255.0" gateway="10.149.133.1" address="10.149.133.27"/> <boot_protocol>none</boot_protocol> <check_connectivity>1</check_connectivity> <status> <state>up</state> </status> <mtu>1500</mtu> <bridged>false</bridged> <custom_configuration>false</custom_configuration> <override_configuration>1</override_configuration> </host_nic> <host_nic> <name>bond0</name> <ip/> <bonding> <options> <option name="miimon" value="100"/> <option name="mode" value="4"/> </options> <slaves> <host_nic> <name>eno1</name> </host_nic> <host_nic> <name>eno2</name> </host_nic> </slaves> </bonding> <boot_protocol>none</boot_protocol> <status> <state>up</state> </status> <mtu>1504</mtu> <bridged>false</bridged> <override_configuration>1</override_configuration> </host_nic> <host_nic> <name>bond0.602</name> <network> <name>VLAN602</name> </network> <ip netmask="255.255.255.0" address="10.149.2.27"/> <boot_protocol>none</boot_protocol> <status> <state>up</state> </status> <mtu>1500</mtu> <bridged>true</bridged> <custom_configuration>false</custom_configuration> <override_configuration>1</override_configuration> </host_nic> </host_nics> <check_connectivity>0</check_connectivity> <force>1</force> </action> --Apple-Mail=_B565CEDD-E856-4748-912B-7B9679AB82CE Content-Disposition: attachment; filename=supervdsm.log Content-Type: application/octet-stream; name="supervdsm.log" Content-Transfer-Encoding: 7bit MainThread::DEBUG::2016-04-04 15:07:33,248::supervdsmServer::582::SuperVdsm.Server::(main) Terminated normally MainThread::DEBUG::2016-04-04 15:07:42,785::supervdsmServer::542::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm MainThread::DEBUG::2016-04-04 15:07:42,785::supervdsmServer::551::SuperVdsm.Server::(main) Parsing cmd args MainThread::DEBUG::2016-04-04 15:07:42,785::supervdsmServer::554::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock MainThread::DEBUG::2016-04-04 15:07:42,786::supervdsmServer::558::SuperVdsm.Server::(main) Setting up keep alive thread MainThread::DEBUG::2016-04-04 15:07:42,786::supervdsmServer::564::SuperVdsm.Server::(main) Creating remote object manager MainThread::DEBUG::2016-04-04 15:07:42,786::fileUtils::192::Storage.fileUtils::(chown) Changing owner for /var/run/vdsm/svdsm.sock, to (36:36) MainThread::DEBUG::2016-04-04 15:07:42,786::supervdsmServer::575::SuperVdsm.Server::(main) Started serving super vdsm object sourceRoute::DEBUG::2016-04-04 15:07:42,786::sourceroutethread::79::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started restore-net::INFO::2016-04-04 15:07:43,435::vdsm-restore-net-config::384::root::(restore) networks already restored. doing nothing. MainProcess|storageRefresh::DEBUG::2016-04-04 15:07:44,718::supervdsmServer::116::SuperVdsm.ServerCallback::(wrapper) call hbaRescan with () {} MainProcess|storageRefresh::DEBUG::2016-04-04 15:07:44,718::utils::671::Storage.HBA::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/libexec/vdsm/fc-scan (cwd None) MainProcess|storageRefresh::DEBUG::2016-04-04 15:07:44,766::supervdsmServer::123::SuperVdsm.ServerCallback::(wrapper) return hbaRescan with None MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:07:47,399::supervdsmServer::116::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:07:47,436::supervdsmServer::123::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'SYS-2028TP-HC1TR', 'systemSerialNumber': 'S16549025214470', 'systemFamily': 'To be filled by O.E.M.', 'systemVersion': '0123456789', 'systemUUID': '00000000-0000-0000-0000-002590FA5BF8', 'systemManufacturer': 'Supermicro'} MainProcess|jsonrpc.Executor/6::DEBUG::2016-04-04 15:07:47,938::supervdsmServer::116::SuperVdsm.ServerCallback::(wrapper) call getHardwareInfo with () {} MainProcess|jsonrpc.Executor/6::DEBUG::2016-04-04 15:07:47,938::supervdsmServer::123::SuperVdsm.ServerCallback::(wrapper) return getHardwareInfo with {'systemProductName': 'SYS-2028TP-HC1TR', 'systemSerialNumber': 'S16549025214470', 'systemFamily': 'To be filled by O.E.M.', 'systemVersion': '0123456789', 'systemUUID': '00000000-0000-0000-0000-002590FA5BF8', 'systemManufacturer': 'Supermicro'} MainProcess|jsonrpc.Executor/1::DEBUG::2016-04-04 15:07:55,501::supervdsmServer::116::SuperVdsm.ServerCallback::(wrapper) call hbaRescan with () {} MainProcess|jsonrpc.Executor/1::DEBUG::2016-04-04 15:07:55,502::utils::671::Storage.HBA::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/libexec/vdsm/fc-scan (cwd None) MainProcess|jsonrpc.Executor/1::DEBUG::2016-04-04 15:07:55,554::supervdsmServer::123::SuperVdsm.ServerCallback::(wrapper) return hbaRescan with None MainProcess|jsonrpc.Executor/2::DEBUG::2016-04-04 15:07:55,704::supervdsmServer::116::SuperVdsm.ServerCallback::(wrapper) call hbaRescan with () {} MainProcess|jsonrpc.Executor/2::DEBUG::2016-04-04 15:07:55,705::utils::671::Storage.HBA::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/libexec/vdsm/fc-scan (cwd None) MainProcess|jsonrpc.Executor/2::DEBUG::2016-04-04 15:07:55,751::supervdsmServer::123::SuperVdsm.ServerCallback::(wrapper) return hbaRescan with None MainProcess|Thread-14::DEBUG::2016-04-04 15:07:59,614::supervdsmServer::116::SuperVdsm.ServerCallback::(wrapper) call ksmTune with ({'run': 0, 'merge_across_nodes': 1},) {} MainProcess|Thread-14::DEBUG::2016-04-04 15:07:59,615::supervdsmServer::123::SuperVdsm.ServerCallback::(wrapper) return ksmTune with None MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,087::supervdsmServer::116::SuperVdsm.ServerCallback::(wrapper) call setupNetworks with ({u'ovirtmgmt': {u'nic': u'ens1f0', u'mtu': u'1500', u'bridged': u'false', u'custom': {}}, u'VLAN602': {u'mtu': u'1500', u'bonding': u'bond0', u'vlan': u'602', u'bridged': u'false', u'custom': {}}}, {u'bond0': {u'nics': [u'eno1', u'eno2'], u'options': u'miimon=100 mode=4'}}, {u'connectivityCheck': u'false'}) {} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,087::api::886::setupNetworks::(setupNetworks) Setting up network according to configuration: networks:{u'ovirtmgmt': {u'nic': u'ens1f0', u'mtu': u'1500', u'bridged': u'false', u'custom': {}}, u'VLAN602': {u'mtu': u'1500', u'bonding': u'bond0', u'vlan': u'602', u'bridged': u'false', u'custom': {}}}, bondings:{u'bond0': {u'nics': [u'eno1', u'eno2'], u'options': u'miimon=100 mode=4'}}, options:{u'connectivityCheck': u'false'} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,087::api::890::root::(setupNetworks) Validating configuration MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,090::libvirtconnection::161::root::(get) trying to connect libvirt MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,096::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on ens1f0. MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,097::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on ens1f0. MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,097::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on bond0.602. MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,097::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on bond0.602. MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:02,097::netinfo::495::root::(_getNetInfo) Obtaining info for net bond0.602. Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/netinfo.py", line 492, in _getNetInfo 'mtu': str(getMtu(iface))}) File "/usr/lib/python2.7/site-packages/vdsm/netinfo.py", line 191, in getMtu with open('/sys/class/net/%s/mtu' % iface) as f: IOError: [Errno 2] No such file or directory: '/sys/class/net/bond0.602/mtu' MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,103::api::900::setupNetworks::(setupNetworks) Applying... MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:02,103::netconfpersistence::62::root::(setNetwork) Adding network ovirtmgmt({'nic': 'ens1f0', 'dhcpv6': False, 'ipaddr': '10.149.133.27', 'mtu': '1500', 'netmask': '255.255.255.0', 'bootproto': 'none', 'bridged': False, 'gateway': '10.149.133.1', 'defaultRoute': False}) MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:02,103::netconfpersistence::73::root::(setBonding) Adding bond0({'nics': [], 'options': 'mode=0'}) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,104::api::909::setupNetworks::(setupNetworks) Removing network u'ovirtmgmt' MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:02,104::api::462::root::(_delNetwork) Removing network ovirtmgmt with vlan=None, bonding=None, nics=['ens1f0'],keep_bridge=True options={} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,104::ifcfg::332::root::(_atomicNetworkBackup) Backed up ovirtmgmt MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:02,105::api::495::root::(_delNetwork) Removing network entity ens1f0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,106::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/ifdown ens1f0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,392::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,393::__init__::144::root::(_removeSourceRoute) Removing source route for device ens1f0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,393::ifcfg::416::root::(_persistentBackup) backing up rule-ens1f0: # original file did not exist MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,393::ifcfg::321::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/rule-ens1f0 (until next 'set safe config') MainProcess|jsonrpc.Executor/4::WARNING::2016-04-04 15:08:02,393::utils::140::root::(rmFile) File: /etc/sysconfig/network-scripts/rule-ens1f0 already removed MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,393::ifcfg::289::root::(_removeFile) Removed file /etc/sysconfig/network-scripts/rule-ens1f0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,393::ifcfg::416::root::(_persistentBackup) backing up route-ens1f0: # original file did not exist MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,394::ifcfg::321::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/route-ens1f0 (until next 'set safe config') MainProcess|jsonrpc.Executor/4::WARNING::2016-04-04 15:08:02,394::utils::140::root::(rmFile) File: /etc/sysconfig/network-scripts/route-ens1f0 already removed MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,394::ifcfg::289::root::(_removeFile) Removed file /etc/sysconfig/network-scripts/route-ens1f0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,394::ifcfg::379::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-ens1f0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,394::ifcfg::416::root::(_persistentBackup) backing up ifcfg-ens1f0: # Generated by dracut initrd DEVICE="ens1f0" ONBOOT=yes NETBOOT=yes UUID="a6e93be4-5fa5-4567-b205-adfd3fa96c45" IPV6INIT=yes BOOTPROTO=none TYPE=Ethernet NAME="ens1f0" IPADDR0=10.149.133.27 PREFIX0=24 GATEWAY0=10.149.133.1 DNS1=10.148.125.237 DEFROUTE=yes IPV4_FAILURE_FATAL=no IPV6_AUTOCONF=yes IPV6_DEFROUTE=yes IPV6_FAILURE_FATAL=no HWADDR=0C:C4:7A:52:DE:06 IPV6_PEERDNS=yes IPV6_PEERROUTES=yes MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,394::ifcfg::321::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/ifcfg-ens1f0 (until next 'set safe config') MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,395::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/bin/systemd-run --scope --unit=30a5b69a-1687-4b2b-8cf3-3f7d93e85a43 --slice=vdsm-dhclient /usr/sbin/ifup ens1f0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,833::utils::689::root::(execCmd) SUCCESS: <err> = 'Running scope as unit 30a5b69a-1687-4b2b-8cf3-3f7d93e85a43.scope.\n'; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,834::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/tc filter del dev ens1f0 pref 5000 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,837::utils::689::root::(execCmd) FAILED: <err> = 'RTNETLINK answers: Operation not supported\nWe have an error talking to the kernel\n'; <rc> = 2 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,837::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/tc qdisc show dev ens1f0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,839::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,840::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/tc class del dev ens1f0 classid 0:1388 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,842::utils::689::root::(execCmd) FAILED: <err> = 'RTNETLINK answers: No such file or directory\n'; <rc> = 2 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,843::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/tc class show dev ens1f0 parent 0: (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,845::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,845::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/tc qdisc del dev ens1f0 root (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,848::utils::689::root::(execCmd) FAILED: <err> = 'RTNETLINK answers: No such file or directory\n'; <rc> = 2 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,848::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/tc qdisc del dev ens1f0 ingress (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,851::utils::689::root::(execCmd) FAILED: <err> = 'RTNETLINK answers: No such file or directory\n'; <rc> = 2 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,851::netconfpersistence::69::root::(removeNetwork) Network ovirtmgmt not found for removal MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,855::api::925::setupNetworks::(setupNetworks) Removing broken network u'VLAN602' MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,857::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on bond0.602. MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,857::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on bond0.602. MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:02,858::netinfo::495::root::(_getNetInfo) Obtaining info for net bond0.602. Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/netinfo.py", line 492, in _getNetInfo 'mtu': str(getMtu(iface))}) File "/usr/lib/python2.7/site-packages/vdsm/netinfo.py", line 191, in getMtu with open('/sys/class/net/%s/mtu' % iface) as f: IOError: [Errno 2] No such file or directory: '/sys/class/net/bond0.602/mtu' MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,861::netconfpersistence::69::root::(removeNetwork) Network VLAN602 not found for removal MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,870::api::678::_handleBondings::(_handleBondings) Editing bond Bond(bond0: [Nic(eno1), Nic(eno2)]) with options mode=4 miimon=100 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:02,871::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = u"BONDING_OPTS='mode=4 miimon=100'\nONBOOT=yes\n", name = bond0, ipv4 = IPv4(None, None, None, None, None), ipv6 = IPv6(None, None, None, None, None), mtu = None, kwargs = {'hotplug': 'no'} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,871::ifcfg::416::root::(_persistentBackup) backing up ifcfg-bond0: # original file did not exist MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,872::ifcfg::321::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/ifcfg-bond0 (until next 'set safe config') MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,872::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-bond0 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=bond0 BONDING_OPTS='mode=4 miimon=100' ONBOOT=yes NM_CONTROLLED=no IPV6INIT=no HOTPLUG=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,877::ifcfg::499::root::(writeConfFile) ignoring restorecon error in case SElinux is disabled Traceback (most recent call last): File "/usr/share/vdsm/network/configurators/ifcfg.py", line 496, in writeConfFile selinux.restorecon(fileName.encode('utf-8')) File "/usr/lib64/python2.7/site-packages/selinux/__init__.py", line 88, in restorecon status, oldcontext = lgetfilecon(path) OSError: [Errno 61] No data available MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:02,878::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/ifdown eno1 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,072::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:03,079::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = u'HWADDR=00:25:90:fa:5b:f8\nMASTER=bond0\nSLAVE=yes\nONBOOT=yes\n', name = eno1, ipv4 = IPv4(None, None, None, None, None), ipv6 = IPv6(None, None, None, None, None), mtu = None, kwargs = {} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,079::ifcfg::379::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eno1 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,079::ifcfg::416::root::(_persistentBackup) backing up ifcfg-eno1: HWADDR=00:25:90:FA:5B:F8 TYPE=Ethernet BOOTPROTO=dhcp DEFROUTE=yes PEERDNS=yes PEERROUTES=yes IPV4_FAILURE_FATAL=no IPV6INIT=yes IPV6_AUTOCONF=yes IPV6_DEFROUTE=yes IPV6_PEERDNS=yes IPV6_PEERROUTES=yes IPV6_FAILURE_FATAL=no NAME=eno1 UUID=ad1e4309-419c-4b94-a75c-ebeacf7c811b ONBOOT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,079::ifcfg::321::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/ifcfg-eno1 (until next 'set safe config') MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,079::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-eno1 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=eno1 HWADDR=00:25:90:fa:5b:f8 MASTER=bond0 SLAVE=yes ONBOOT=yes NM_CONTROLLED=no IPV6INIT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,080::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/bin/systemd-run --scope --unit=83dbadd9-5ebf-4e1e-9997-aed694db18b2 --slice=vdsm-dhclient /usr/sbin/ifup eno1 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,548::utils::689::root::(execCmd) SUCCESS: <err> = 'Running scope as unit 83dbadd9-5ebf-4e1e-9997-aed694db18b2.scope.\n'; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,548::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/ifdown eno2 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,745::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:03,752::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = u'HWADDR=00:25:90:fa:5b:f9\nMASTER=bond0\nSLAVE=yes\nONBOOT=yes\n', name = eno2, ipv4 = IPv4(None, None, None, None, None), ipv6 = IPv6(None, None, None, None, None), mtu = None, kwargs = {} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,752::ifcfg::379::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eno2 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,752::ifcfg::416::root::(_persistentBackup) backing up ifcfg-eno2: HWADDR=00:25:90:FA:5B:F9 TYPE=Ethernet BOOTPROTO=dhcp DEFROUTE=yes PEERDNS=yes PEERROUTES=yes IPV4_FAILURE_FATAL=no IPV6INIT=yes IPV6_AUTOCONF=yes IPV6_DEFROUTE=yes IPV6_PEERDNS=yes IPV6_PEERROUTES=yes IPV6_FAILURE_FATAL=no NAME=eno2 UUID=9d958f30-d3c9-42a5-96f9-6362268ce048 ONBOOT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,752::ifcfg::321::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/ifcfg-eno2 (until next 'set safe config') MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,752::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-eno2 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=eno2 HWADDR=00:25:90:fa:5b:f9 MASTER=bond0 SLAVE=yes ONBOOT=yes NM_CONTROLLED=no IPV6INIT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:03,753::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/bin/systemd-run --scope --unit=8043d910-ee54-4af2-81e7-9a0a9e4fc6e0 --slice=vdsm-dhclient /usr/sbin/ifup eno2 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:04,220::utils::689::root::(execCmd) SUCCESS: <err> = 'Running scope as unit 8043d910-ee54-4af2-81e7-9a0a9e4fc6e0.scope.\n'; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:04,221::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/ifdown bond0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:05,741::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:05,742::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/bin/systemd-run --scope --unit=3d37b14a-8341-4188-8015-1ed1cfd4a29a --slice=vdsm-dhclient /usr/sbin/ifup bond0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:06,978::utils::689::root::(execCmd) SUCCESS: <err> = 'Running scope as unit 3d37b14a-8341-4188-8015-1ed1cfd4a29a.scope.\n'; <rc> = 0 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:06,979::netconfpersistence::73::root::(setBonding) Adding bond0({'nics': [u'eno1', u'eno2'], 'options': u'mode=4 miimon=100'}) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:06,984::api::765::setupNetworks::(_add_missing_networks) Adding network u'ovirtmgmt' MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:06,984::api::281::root::(_addNetwork) validating network... MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:06,984::api::301::root::(_addNetwork) Adding network ovirtmgmt with vlan=None, bonding=None, nics=[u'ens1f0'], bondingOptions=None, mtu=1500, bridged=False, defaultRoute=True,options={'implicitBonding': True, u'custom': {}} MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:06,984::api::331::root::(_addNetwork) Configuring device ens1f0 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:06,990::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = 'HWADDR=0c:c4:7a:52:de:06\nONBOOT=yes\n', name = ens1f0, ipv4 = IPv4(None, None, None, True, None), ipv6 = IPv6(None, None, True, None, None), mtu = 1500, kwargs = {'implicitBonding': True, u'custom': {}} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:06,991::ifcfg::416::root::(_persistentBackup) backing up ifcfg-ens1f0: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=ens1f0 ONBOOT=yes MTU=1500 HWADDR=0C:C4:7A:52:DE:06 NM_CONTROLLED=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:06,991::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-ens1f0 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=ens1f0 HWADDR=0c:c4:7a:52:de:06 ONBOOT=yes MTU=1500 DEFROUTE=yes NM_CONTROLLED=no IPV6INIT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:06,991::__init__::130::root::(_addSourceRoute) Adding source route: name=ens1f0, addr=None, netmask=None, gateway=None MainProcess|jsonrpc.Executor/4::WARNING::2016-04-04 15:08:06,991::__init__::137::root::(_addSourceRoute) invalid input for source routing: name=ens1f0, addr=None, netmask=None, gateway=None MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:06,992::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/ifdown ens1f0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,233::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,233::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/bin/systemd-run --scope --unit=1463dcfa-fd91-40ff-a5a6-76f4759a824f --slice=vdsm-dhclient /usr/sbin/ifup ens1f0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,666::utils::689::root::(execCmd) SUCCESS: <err> = 'Running scope as unit 1463dcfa-fd91-40ff-a5a6-76f4759a824f.scope.\n'; <rc> = 0 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:07,674::netconfpersistence::62::root::(setNetwork) Adding network ovirtmgmt({'nic': u'ens1f0', u'custom': {}, u'bridged': u'false', u'mtu': u'1500'}) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,679::api::765::setupNetworks::(_add_missing_networks) Adding network u'VLAN602' MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,679::api::281::root::(_addNetwork) validating network... MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:07,680::api::301::root::(_addNetwork) Adding network VLAN602 with vlan=602, bonding=bond0, nics=[u'eno1', u'eno2'], bondingOptions=miimon=100 mode=4, mtu=1500, bridged=False, defaultRoute=False,options={'implicitBonding': True, u'custom': {}} MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:07,680::api::331::root::(_addNetwork) Configuring device bond0.602 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:07,680::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = 'VLAN=yes\nONBOOT=yes\n', name = bond0.602, ipv4 = IPv4(None, None, None, False, None), ipv6 = IPv6(None, None, False, None, None), mtu = 1500, kwargs = {'implicitBonding': True, 'hotplug': 'no', u'custom': {}} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,680::ifcfg::416::root::(_persistentBackup) backing up ifcfg-bond0.602: # original file did not exist MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,681::ifcfg::321::root::(writeBackupFile) Persistently backed up /var/lib/vdsm/netconfback/ifcfg-bond0.602 (until next 'set safe config') MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,681::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-bond0.602 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=bond0.602 VLAN=yes ONBOOT=yes MTU=1500 DEFROUTE=no NM_CONTROLLED=no IPV6INIT=no HOTPLUG=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,681::ifcfg::499::root::(writeConfFile) ignoring restorecon error in case SElinux is disabled Traceback (most recent call last): File "/usr/share/vdsm/network/configurators/ifcfg.py", line 496, in writeConfFile selinux.restorecon(fileName.encode('utf-8')) File "/usr/lib64/python2.7/site-packages/selinux/__init__.py", line 88, in restorecon status, oldcontext = lgetfilecon(path) OSError: [Errno 61] No data available MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:07,683::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = u"BONDING_OPTS='mode=4 miimon=100'\nONBOOT=yes\n", name = bond0, ipv4 = IPv4(None, None, None, None, None), ipv6 = IPv6(None, None, None, None, None), mtu = 1500, kwargs = {'implicitBonding': True, 'hotplug': 'no', u'custom': {}} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,683::ifcfg::416::root::(_persistentBackup) backing up ifcfg-bond0: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=bond0 BONDING_OPTS='mode=4 miimon=100' ONBOOT=yes NM_CONTROLLED=no IPV6INIT=no HOTPLUG=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,684::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-bond0 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=bond0 BONDING_OPTS='mode=4 miimon=100' ONBOOT=yes MTU=1500 NM_CONTROLLED=no IPV6INIT=no HOTPLUG=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,684::ifcfg::499::root::(writeConfFile) ignoring restorecon error in case SElinux is disabled Traceback (most recent call last): File "/usr/share/vdsm/network/configurators/ifcfg.py", line 496, in writeConfFile selinux.restorecon(fileName.encode('utf-8')) File "/usr/lib64/python2.7/site-packages/selinux/__init__.py", line 88, in restorecon status, oldcontext = lgetfilecon(path) OSError: [Errno 61] No data available MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:07,685::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/ifdown eno1 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:08,362::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:08,362::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/sbin/ifdown eno2 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,036::utils::689::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,039::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on ens1f0. MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,039::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on ens1f0. MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:09,044::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = u'HWADDR=00:25:90:fa:5b:f8\nMASTER=bond0\nSLAVE=yes\nONBOOT=yes\n', name = eno1, ipv4 = IPv4(None, None, None, None, None), ipv6 = IPv6(None, None, None, None, None), mtu = 1500, kwargs = {'implicitBonding': True, u'custom': {}} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,044::ifcfg::416::root::(_persistentBackup) backing up ifcfg-eno1: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=eno1 HWADDR=00:25:90:fa:5b:f8 MASTER=bond0 SLAVE=yes ONBOOT=yes NM_CONTROLLED=no IPV6INIT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,044::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-eno1 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=eno1 HWADDR=00:25:90:fa:5b:f8 MASTER=bond0 SLAVE=yes ONBOOT=yes MTU=1500 NM_CONTROLLED=no IPV6INIT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,047::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on ens1f0. MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,047::netinfo::453::root::(_dhcp_used) There is no VDSM network configured on ens1f0. MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:09,052::ifcfg::509::root::(_createConfFile) ifcfg _createConfFile with: conf = u'HWADDR=00:25:90:fa:5b:f9\nMASTER=bond0\nSLAVE=yes\nONBOOT=yes\n', name = eno2, ipv4 = IPv4(None, None, None, None, None), ipv6 = IPv6(None, None, None, None, None), mtu = 1500, kwargs = {'implicitBonding': True, u'custom': {}} MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,053::ifcfg::416::root::(_persistentBackup) backing up ifcfg-eno2: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=eno2 HWADDR=00:25:90:fa:5b:f9 MASTER=bond0 SLAVE=yes ONBOOT=yes NM_CONTROLLED=no IPV6INIT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,053::ifcfg::487::root::(writeConfFile) Writing to file /etc/sysconfig/network-scripts/ifcfg-eno2 configuration: # Generated by VDSM version 4.17.23.2-0.el7.centos DEVICE=eno2 HWADDR=00:25:90:fa:5b:f9 MASTER=bond0 SLAVE=yes ONBOOT=yes MTU=1500 NM_CONTROLLED=no IPV6INIT=no MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:09,053::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/bin/systemd-run --scope --unit=b45706d8-fe5f-4320-bc99-9bd99991d878 --slice=vdsm-dhclient /usr/sbin/ifup bond0 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:10,291::utils::689::root::(execCmd) SUCCESS: <err> = 'Running scope as unit b45706d8-fe5f-4320-bc99-9bd99991d878.scope.\n'; <rc> = 0 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:10,291::netconfpersistence::73::root::(setBonding) Adding bond0({'nics': [u'eno1', u'eno2'], 'options': u'mode=4 miimon=100'}) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:10,291::__init__::130::root::(_addSourceRoute) Adding source route: name=bond0.602, addr=None, netmask=None, gateway=None MainProcess|jsonrpc.Executor/4::WARNING::2016-04-04 15:08:10,291::__init__::137::root::(_addSourceRoute) invalid input for source routing: name=bond0.602, addr=None, netmask=None, gateway=None MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:10,292::utils::671::root::(execCmd) /usr/bin/taskset --cpu-list 0-39 /usr/bin/systemd-run --scope --unit=cff02ee0-ee96-4b7e-bc49-706e230bc59a --slice=vdsm-dhclient /usr/sbin/ifup bond0.602 (cwd None) MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:10,609::utils::689::root::(execCmd) SUCCESS: <err> = 'Running scope as unit cff02ee0-ee96-4b7e-bc49-706e230bc59a.scope.\nRTNETLINK answers: Network is unreachable\nRTNETLINK answers: Network is unreachable\n'; <rc> = 0 MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:10,610::ifcfg::332::root::(_atomicNetworkBackup) Backed up VLAN602 MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:10,616::netconfpersistence::62::root::(setNetwork) Adding network VLAN602({u'custom': {}, u'bonding': u'bond0', u'vlan': u'602', u'bridged': u'false', u'mtu': u'1500'}) MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:10,621::netconfpersistence::187::root::(_clearDisk) Clearing /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/ MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:10,621::netconfpersistence::195::root::(_clearDisk) No existent config to clear. MainProcess|jsonrpc.Executor/4::INFO::2016-04-04 15:08:10,621::netconfpersistence::131::root::(save) Saved new config RunningConfig({u'ovirtmgmt': {'nic': u'ens1f0', u'custom': {}, u'bridged': u'false', u'mtu': u'1500'}, u'VLAN602': {u'custom': {}, u'bonding': u'bond0', u'vlan': u'602', u'bridged': u'false', u'mtu': u'1500'}}, {u'bond0': {'nics': [u'eno1', u'eno2'], 'options': u'mode=4 miimon=100'}}) to /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/ MainProcess|jsonrpc.Executor/4::DEBUG::2016-04-04 15:08:10,622::supervdsmServer::123::SuperVdsm.ServerCallback::(wrapper) return setupNetworks with None --Apple-Mail=_B565CEDD-E856-4748-912B-7B9679AB82CE Content-Disposition: attachment; filename=vdsm.log Content-Type: application/octet-stream; name="vdsm.log" Content-Transfer-Encoding: quoted-printable Reactor=20thread::INFO::2016-04-04=20= 15:07:17,671::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37253=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:07:17,675::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:07:17,675::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37253=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:07:17,676::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37253=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:07:17,676::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037253)=0A= Thread-37::INFO::2016-04-04=20= 15:07:17,676::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37253=20started=0A= Thread-37::INFO::2016-04-04=20= 15:07:17,677::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37253=20stopped=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,124::vdsm::72::vds::(sigtermHandler)=20Received=20signal=2015=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,124::periodic::162::virt.periodic.Operation::(stop)=20stopping=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.UpdateVolumes'>=20at=200x7f292c00e910>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,124::periodic::162::virt.periodic.Operation::(stop)=20stopping=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.NumaInfoMonitor'>=20at=200x7f292c00eb50>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,124::periodic::162::virt.periodic.Operation::(stop)=20stopping=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.BlockjobMonitor'>=20at=200x7f292c00ebd0>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,124::periodic::162::virt.periodic.Operation::(stop)=20stopping=20= operation=20<VMBulkSampler=20at=200x7f292c00ec50>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,125::periodic::162::virt.periodic.Operation::(stop)=20stopping=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.DriveWatermarkMonitor'>=20at=200x7f292c00ef90>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,125::executor::78::Executor::(stop)=20Stopping=20executor=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,125::protocoldetector::203::vds.MultiProtocolAcceptor::(stop)=20= Stopping=20Acceptor=0AMainThread::INFO::2016-04-04=20= 15:07:33,125::__init__::612::jsonrpc.JsonRpcServer::(stop)=20Stopping=20= JsonRPC=20Server=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,125::executor::78::Executor::(stop)=20Stopping=20executor=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,125::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/2=0Ajsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:33,126::executor::176::Executor::(_run)=20Worker=20stopped=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:33,126::executor::176::Executor::(_run)=20Worker=20stopped=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:33,126::executor::176::Executor::(_run)=20Worker=20stopped=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,126::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/7=0Ajsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:33,126::executor::176::Executor::(_run)=20Worker=20stopped=0A= jsonrpc.Executor/4::DEBUG::2016-04-04=20= 15:07:33,126::executor::176::Executor::(_run)=20Worker=20stopped=0A= jsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:33,127::executor::176::Executor::(_run)=20Worker=20stopped=0A= jsonrpc.Executor/6::DEBUG::2016-04-04=20= 15:07:33,127::executor::176::Executor::(_run)=20Worker=20stopped=0A= jsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:33,127::executor::176::Executor::(_run)=20Worker=20stopped=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,127::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/6=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,127::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/4=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,127::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/1=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,128::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/3=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,128::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/0=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,128::executor::165::Executor::(join)=20Waiting=20for=20worker=20= jsonrpc.Executor/5=0AMainThread::INFO::2016-04-04=20= 15:07:33,128::bindingxmlrpc::85::vds::(stop)=20Stopping=20XMLRPC=20= server=0ABindingXMLRPC::INFO::2016-04-04=20= 15:07:33,128::bindingxmlrpc::74::vds::(threaded_start)=20XMLRPC=20server=20= stopped=0AMainThread::INFO::2016-04-04=20= 15:07:33,128::secret::90::root::(clear)=20Unregistering=20all=20secrests=0A= periodic/0::DEBUG::2016-04-04=20= 15:07:33,128::executor::176::Executor::(_run)=20Worker=20stopped=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,129::vmchannels::207::vds::(stop)=20VM=20channels=20listener=20= was=20stopped.=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,129::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`0a3e21a2-1f17-42b6-a741-8c44ee28b500`::moving=20from=20state=20= init=20->=20state=20preparing=0AMainThread::INFO::2016-04-04=20= 15:07:33,129::logUtils::48::dispatcher::(wrapper)=20Run=20and=20protect:=20= prepareForShutdown(options=3DNone)=0AThread-11::DEBUG::2016-04-04=20= 15:07:33,130::storageServer::864::Storage.ConnectionMonitor::(_monitorConn= ections)=20Monitoring=20stopped=0AMainThread::INFO::2016-04-04=20= 15:07:33,157::monitor::156::Storage.Monitor::(shutdown)=20Shutting=20= down=20domain=20monitors=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,158::taskManager::90::Storage.TaskManager::(prepareForShutdown)=20= Request=20to=20stop=20all=20tasks=0AMainThread::INFO::2016-04-04=20= 15:07:33,158::logUtils::51::dispatcher::(wrapper)=20Run=20and=20protect:=20= prepareForShutdown,=20Return=20response:=20None=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,158::task::1191::Storage.TaskManager.Task::(prepare)=20= Task=3D`0a3e21a2-1f17-42b6-a741-8c44ee28b500`::finished:=20None=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,158::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`0a3e21a2-1f17-42b6-a741-8c44ee28b500`::moving=20from=20state=20= preparing=20->=20state=20finished=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,158::resourceManager::943::Storage.ResourceManager.Owner::(releas= eAll)=20Owner.releaseAll=20requests=20{}=20resources=20{}=0A= MainThread::DEBUG::2016-04-04=20= 15:07:33,158::resourceManager::980::Storage.ResourceManager.Owner::(cancel= All)=20Owner.cancelAll=20requests=20{}=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,158::task::993::Storage.TaskManager.Task::(_decref)=20= Task=3D`0a3e21a2-1f17-42b6-a741-8c44ee28b500`::ref=200=20aborting=20= False=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,158::schedule::110::Scheduler::(stop)=20Stopping=20scheduler=20= vdsm.Scheduler=0AMainThread::INFO::2016-04-04=20= 15:07:33,158::vdsm::174::vds::(run)=20VDSM=20main=20thread=20ended.=20= Waiting=20for=2018=20other=20threads...=0AMainThread::INFO::2016-04-04=20= 15:07:33,158::vdsm::178::vds::(run)=20<Thread(libvirtEventLoop,=20= started=20daemon=20139816958940928)>=0AMainThread::INFO::2016-04-04=20= 15:07:33,159::vdsm::178::vds::(run)=20<Thread(periodic/2,=20started=20= daemon=20139814791796480)>=0AMainThread::DEBUG::2016-04-04=20= 15:07:33,159::vmchannels::207::vds::(stop)=20VM=20channels=20listener=20= was=20stopped.=0AMainThread::INFO::2016-04-04=20= 15:07:33,159::vdsm::178::vds::(run)=20<Listener(VM=20Channels=20= Listener,=20started=20daemon=20139815796168448)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <WorkerThread(Thread-6,=20started=20daemon=20139816908584704)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <WorkerThread(Thread-5,=20started=20daemon=20139816916977408)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <WorkerThread(Thread-4,=20started=20daemon=20139816925370112)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <Thread(periodic/1,=20started=20daemon=20139814800189184)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <Thread(vdsm.Scheduler,=20started=20daemon=20139816377231104)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <WorkerThread(Thread-7,=20started=20daemon=20139816900192000)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <WorkerThread(Thread-1,=20started=20daemon=20139816950548224)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <WorkerThread(Thread-10,=20started=20daemon=20139816402409216)>=0A= MainThread::INFO::2016-04-04=2015:07:33,159::vdsm::178::vds::(run)=20= <WorkerThread(Thread-9,=20started=20daemon=20139816410801920)>=0A= MainThread::INFO::2016-04-04=2015:07:33,160::vdsm::178::vds::(run)=20= <WorkerThread(Thread-8,=20started=20daemon=20139816419194624)>=0A= MainThread::INFO::2016-04-04=2015:07:33,160::vdsm::178::vds::(run)=20= <WorkerThread(Thread-2,=20started=20daemon=20139816942155520)>=0A= MainThread::INFO::2016-04-04=2015:07:33,160::vdsm::178::vds::(run)=20= <_MainThread(MainThread,=20started=20139817249052480)>=0A= MainThread::INFO::2016-04-04=2015:07:33,160::vdsm::178::vds::(run)=20= <Thread(Reactor=20thread,=20started=20daemon=20139815362205440)>=0A= MainThread::INFO::2016-04-04=2015:07:33,160::vdsm::178::vds::(run)=20= <Thread(periodic/3,=20started=20daemon=20139814783403776)>=0A= vdsm.Scheduler::DEBUG::2016-04-04=20= 15:07:33,160::schedule::145::Scheduler::(_run)=20stopped=0A= MainThread::INFO::2016-04-04=2015:07:33,160::vdsm::178::vds::(run)=20= <HostStatsThread(Thread-12,=20started=20daemon=20139816360789760)>=0A= MainThread::INFO::2016-04-04=2015:07:33,160::vdsm::178::vds::(run)=20= <WorkerThread(Thread-3,=20started=20daemon=20139816933762816)>=0A= periodic/1::DEBUG::2016-04-04=20= 15:07:33,161::executor::176::Executor::(_run)=20Worker=20stopped=0A= periodic/2::DEBUG::2016-04-04=20= 15:07:33,161::executor::176::Executor::(_run)=20Worker=20stopped=0A= periodic/3::DEBUG::2016-04-04=20= 15:07:33,161::executor::176::Executor::(_run)=20Worker=20stopped=0A= MainThread::INFO::2016-04-04=2015:07:44,569::vdsm::162::vds::(run)=20= (PID:=205100)=20I=20am=20the=20actual=20vdsm=204.17.23.2-0.el7.centos=20= twinp105.mgt.exam.org=20(3.10.0-327.10.1.el7.x86_64)=0A= MainThread::INFO::2016-04-04=20= 15:07:44,569::vdsm::285::vds::(__set_cpu_affinity)=20VDSM=20will=20run=20= with=20cpu=20affinity:=20frozenset([1])=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,569::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --all-tasks=20--pid=20--cpu-list=201=205100=20(cwd=20None)=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,571::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,572::resourceManager::424::Storage.ResourceManager::(registerName= space)=20Registering=20namespace=20'Storage'=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,572::threadPool::29::Storage.ThreadPool::(__init__)=20Enter=20-=20= numThreads:=2010,=20waitTimeout:=203,=20maxTasks:=20500=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,574::fileUtils::143::Storage.fileUtils::(createdir)=20Creating=20= directory:=20/rhev/data-center/mnt=20mode:=20None=0A= MainThread::WARNING::2016-04-04=20= 15:07:44,574::fileUtils::152::Storage.fileUtils::(createdir)=20Dir=20= /rhev/data-center/mnt=20already=20exists=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,603::hsm::409::Storage.Misc.excCmd::(__validateLvmLockingType)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/sbin/lvm=20dumpconfig=20global/locking_type=20(cwd=20None)=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,612::hsm::409::Storage.Misc.excCmd::(__validateLvmLockingType)=20= SUCCESS:=20<err>=20=3D=20'';=20<rc>=20=3D=200=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,612::hsm::433::Storage.HSM::(__cleanStorageRepository)=20= Started=20cleaning=20storage=20repository=20at=20'/rhev/data-center'=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,614::hsm::465::Storage.HSM::(__cleanStorageRepository)=20White=20= list:=20['/rhev/data-center/hsm-tasks',=20= '/rhev/data-center/hsm-tasks/*',=20'/rhev/data-center/mnt']=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,614::hsm::466::Storage.HSM::(__cleanStorageRepository)=20Mount=20= list:=20[]=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,614::hsm::468::Storage.HSM::(__cleanStorageRepository)=20= Cleaning=20leftovers=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,615::hsm::511::Storage.HSM::(__cleanStorageRepository)=20= Finished=20cleaning=20storage=20repository=20at=20'/rhev/data-center'=0A= MainThread::INFO::2016-04-04=20= 15:07:44,616::dispatcher::46::Storage.Dispatcher::(__init__)=20Starting=20= StorageDispatcher...=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,616::schedule::98::Scheduler::(start)=20Starting=20scheduler=20= vdsm.Scheduler=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,616::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.sdc.refreshStorage)=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,616::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,616::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.iscsi.rescan)=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,616::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,616::iscsi::434::Storage.ISCSI::(rescan)=20Performing=20SCSI=20= scan,=20this=20will=20take=20up=20to=2030=20seconds=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,616::iscsiadm::97::Storage.Misc.excCmd::(_runCmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /sbin/iscsiadm=20-m=20session=20-R=20(cwd=20None)=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,652::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`cc66d0a2-2063-4126-b0b9-2d12e44ef4d2`::moving=20from=20state=20= init=20->=20state=20preparing=0AMainThread::INFO::2016-04-04=20= 15:07:44,652::logUtils::48::dispatcher::(wrapper)=20Run=20and=20protect:=20= registerDomainStateChangeCallback(callbackFunc=3D<functools.partial=20= object=20at=200x1fb9158>)=0AMainThread::INFO::2016-04-04=20= 15:07:44,652::logUtils::51::dispatcher::(wrapper)=20Run=20and=20protect:=20= registerDomainStateChangeCallback,=20Return=20response:=20None=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,652::task::1191::Storage.TaskManager.Task::(prepare)=20= Task=3D`cc66d0a2-2063-4126-b0b9-2d12e44ef4d2`::finished:=20None=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,652::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`cc66d0a2-2063-4126-b0b9-2d12e44ef4d2`::moving=20from=20state=20= preparing=20->=20state=20finished=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,653::resourceManager::943::Storage.ResourceManager.Owner::(releas= eAll)=20Owner.releaseAll=20requests=20{}=20resources=20{}=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,653::resourceManager::980::Storage.ResourceManager.Owner::(cancel= All)=20Owner.cancelAll=20requests=20{}=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,653::task::993::Storage.TaskManager.Task::(_decref)=20= Task=3D`cc66d0a2-2063-4126-b0b9-2d12e44ef4d2`::ref=200=20aborting=20= False=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,653::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,653::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.hba.rescan)=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,653::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,653::hba::56::Storage.HBA::(rescan)=20Starting=20scan=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,653::supervdsm::76::SuperVdsmProxy::(_connect)=20Trying=20to=20= connect=20to=20Super=20Vdsm=0Avdsm.Scheduler::DEBUG::2016-04-04=20= 15:07:44,654::schedule::142::Scheduler::(_run)=20started=0A= MainThread::INFO::2016-04-04=2015:07:44,654::momIF::46::MOM::(__init__)=20= Preparing=20MOM=20interface=0AMainThread::INFO::2016-04-04=20= 15:07:44,654::momIF::55::MOM::(__init__)=20Using=20named=20unix=20socket=20= /var/run/vdsm/mom-vdsm.sock=0AMainThread::INFO::2016-04-04=20= 15:07:44,655::secret::90::root::(clear)=20Unregistering=20all=20secrests=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,655::libvirtconnection::161::root::(get)=20trying=20to=20= connect=20libvirt=0AMainThread::INFO::2016-04-04=20= 15:07:44,660::vmchannels::211::vds::(settimeout)=20Setting=20channels'=20= timeout=20to=2030=20seconds.=0AclientIFinit::DEBUG::2016-04-04=20= 15:07:44,660::clientIF::462::vds::(_recoverExistingVms)=20recovery:=20= started=0AMainThread::INFO::2016-04-04=20= 15:07:44,661::protocoldetector::172::vds.MultiProtocolAcceptor::(__init__)= =20Listening=20at=200.0.0.0:54321=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,679::protocoldetector::199::vds.MultiProtocolAcceptor::(add_detec= tor)=20Adding=20detector=20<rpc.bindingxmlrpc.XmlDetector=20instance=20= at=200x2261710>=0AVM=20Channels=20Listener::DEBUG::2016-04-04=20= 15:07:44,720::vmchannels::193::vds::(run)=20Starting=20VM=20channels=20= listener=20thread.=0AclientIFinit::INFO::2016-04-04=20= 15:07:44,721::clientIF::553::vds::(_recoverExistingVms)=20recovery:=20= completed=20in=200s=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,721::protocoldetector::199::vds.MultiProtocolAcceptor::(add_detec= tor)=20Adding=20detector=20<yajsonrpc.stompreactor.StompDetector=20= instance=20at=200x1fd3ab8>=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,721::executor::69::Executor::(start)=20Starting=20executor=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,721::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/0=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,721::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/1=0Ajsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:44,721::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,722::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/2=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:44,722::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,722::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/3=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:44,722::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,722::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/4=0Ajsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:44,722::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,722::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/5=0Ajsonrpc.Executor/4::DEBUG::2016-04-04=20= 15:07:44,723::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,723::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/6=0Ajsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:44,723::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,723::executor::157::Executor::(__init__)=20Starting=20worker=20= jsonrpc.Executor/7=0Ajsonrpc.Executor/6::DEBUG::2016-04-04=20= 15:07:44,723::executor::171::Executor::(_run)=20Worker=20started=0A= jsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:44,724::executor::171::Executor::(_run)=20Worker=20started=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:07:44,724::bindingxmlrpc::62::vds::(threaded_start)=20XMLRPC=20server=20= running=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,724::executor::69::Executor::(start)=20Starting=20executor=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,724::executor::157::Executor::(__init__)=20Starting=20worker=20= periodic/0=0AMainThread::DEBUG::2016-04-04=20= 15:07:44,725::executor::157::Executor::(__init__)=20Starting=20worker=20= periodic/1=0Aperiodic/0::DEBUG::2016-04-04=20= 15:07:44,725::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,725::executor::157::Executor::(__init__)=20Starting=20worker=20= periodic/2=0Aperiodic/1::DEBUG::2016-04-04=20= 15:07:44,725::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,725::executor::157::Executor::(__init__)=20Starting=20worker=20= periodic/3=0Aperiodic/2::DEBUG::2016-04-04=20= 15:07:44,726::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,726::libvirtconnection::161::root::(get)=20trying=20to=20= connect=20libvirt=0Aperiodic/3::DEBUG::2016-04-04=20= 15:07:44,726::executor::171::Executor::(_run)=20Worker=20started=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,728::periodic::153::virt.periodic.Operation::(start)=20starting=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.UpdateVolumes'>=20at=200x7fd66000e910>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,729::periodic::153::virt.periodic.Operation::(start)=20starting=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.NumaInfoMonitor'>=20at=200x7fd66000eb50>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,729::periodic::153::virt.periodic.Operation::(start)=20starting=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.BlockjobMonitor'>=20at=200x7fd66000ebd0>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,729::periodic::153::virt.periodic.Operation::(start)=20starting=20= operation=20<VMBulkSampler=20at=200x7fd66000ec50>=0A= MainThread::DEBUG::2016-04-04=20= 15:07:44,729::periodic::153::virt.periodic.Operation::(start)=20starting=20= operation=20<VmDispatcher=20operation=3D<class=20= 'virt.periodic.DriveWatermarkMonitor'>=20at=200x7fd66000ef90>=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,766::hba::62::Storage.HBA::(rescan)=20Scan=20finished=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,766::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,766::multipath::77::Storage.Misc.excCmd::(rescan)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/sbin/multipath=20(cwd=20None)=0AReactor=20thread::INFO::2016-04-04=20= 15:07:44,806::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=2010.149.133.23:57191=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,813::multipath::77::Storage.Misc.excCmd::(rescan)=20SUCCESS:=20= <err>=20=3D=20'';=20<rc>=20=3D=200=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,814::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/sbin/udevadm=20settle=20--timeout=3D5=20(cwd=20= None)=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,819::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,819::lvm::497::Storage.OperationMutex::(_invalidateAllPvs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::lvm::499::Storage.OperationMutex::(_invalidateAllPvs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::lvm::508::Storage.OperationMutex::(_invalidateAllVgs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::lvm::510::Storage.OperationMutex::(_invalidateAllVgs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::lvm::528::Storage.OperationMutex::(_invalidateAllLvs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::lvm::530::Storage.OperationMutex::(_invalidateAllLvs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::lvm::319::Storage.OperationMutex::(_reloadpvs)=20Operation=20= 'lvm=20reload=20operation'=20got=20the=20operation=20mutex=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,820::lvm::290::Storage.Misc.excCmd::(cmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/bin/sudo=20-n=20/usr/sbin/lvm=20pvs=20--config=20= '=20devices=20{=20preferred_names=20=3D=20["^/dev/mapper/"]=20= ignore_suspended_devices=3D1=20write_cache_state=3D0=20= disable_after_error_count=3D3=20filter=20=3D=20[=20'\''r|.*|'\''=20]=20}=20= =20global=20{=20=20locking_type=3D1=20=20prioritise_write_locks=3D1=20=20= wait_for_locks=3D1=20=20use_lvmetad=3D0=20}=20=20backup=20{=20=20= retain_min=20=3D=2050=20=20retain_days=20=3D=200=20}=20'=20--noheadings=20= --units=20b=20--nosuffix=20--separator=20'|'=20--ignoreskippedcluster=20= -o=20= uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,= dev_size=20(cwd=20None)=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,837::lvm::290::Storage.Misc.excCmd::(cmd)=20SUCCESS:=20<err>=20= =3D=20'=20=20WARNING:=20lvmetad=20is=20running=20but=20disabled.=20= Restart=20lvmetad=20before=20enabling=20it!\n';=20<rc>=20=3D=200=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,837::lvm::347::Storage.OperationMutex::(_reloadpvs)=20Operation=20= 'lvm=20reload=20operation'=20released=20the=20operation=20mutex=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,838::lvm::370::Storage.OperationMutex::(_reloadvgs)=20Operation=20= 'lvm=20reload=20operation'=20got=20the=20operation=20mutex=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,838::lvm::290::Storage.Misc.excCmd::(cmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/bin/sudo=20-n=20/usr/sbin/lvm=20vgs=20--config=20= '=20devices=20{=20preferred_names=20=3D=20["^/dev/mapper/"]=20= ignore_suspended_devices=3D1=20write_cache_state=3D0=20= disable_after_error_count=3D3=20filter=20=3D=20[=20'\''r|.*|'\''=20]=20}=20= =20global=20{=20=20locking_type=3D1=20=20prioritise_write_locks=3D1=20=20= wait_for_locks=3D1=20=20use_lvmetad=3D0=20}=20=20backup=20{=20=20= retain_min=20=3D=2050=20=20retain_days=20=3D=200=20}=20'=20--noheadings=20= --units=20b=20--nosuffix=20--separator=20'|'=20--ignoreskippedcluster=20= -o=20= uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_s= ize,vg_mda_free,lv_count,pv_count,pv_name=20(cwd=20None)=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,851::lvm::290::Storage.Misc.excCmd::(cmd)=20SUCCESS:=20<err>=20= =3D=20'=20=20WARNING:=20lvmetad=20is=20running=20but=20disabled.=20= Restart=20lvmetad=20before=20enabling=20it!\n';=20<rc>=20=3D=200=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,852::lvm::415::Storage.OperationMutex::(_reloadvgs)=20Operation=20= 'lvm=20reload=20operation'=20released=20the=20operation=20mutex=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,852::lvm::290::Storage.Misc.excCmd::(cmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/bin/sudo=20-n=20/usr/sbin/lvm=20lvs=20--config=20= '=20devices=20{=20preferred_names=20=3D=20["^/dev/mapper/"]=20= ignore_suspended_devices=3D1=20write_cache_state=3D0=20= disable_after_error_count=3D3=20filter=20=3D=20[=20'\''r|.*|'\''=20]=20}=20= =20global=20{=20=20locking_type=3D1=20=20prioritise_write_locks=3D1=20=20= wait_for_locks=3D1=20=20use_lvmetad=3D0=20}=20=20backup=20{=20=20= retain_min=20=3D=2050=20=20retain_days=20=3D=200=20}=20'=20--noheadings=20= --units=20b=20--nosuffix=20--separator=20'|'=20--ignoreskippedcluster=20= -o=20uuid,name,vg_name,attr,size,seg_start_pe,devices,tags=20(cwd=20= None)=0AstorageRefresh::DEBUG::2016-04-04=20= 15:07:44,866::lvm::290::Storage.Misc.excCmd::(cmd)=20SUCCESS:=20<err>=20= =3D=20'=20=20WARNING:=20lvmetad=20is=20running=20but=20disabled.=20= Restart=20lvmetad=20before=20enabling=20it!\n';=20<rc>=20=3D=200=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,866::lvm::370::Storage.OperationMutex::(_reloadvgs)=20Operation=20= 'lvm=20reload=20operation'=20got=20the=20operation=20mutex=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,867::lvm::290::Storage.Misc.excCmd::(cmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/bin/sudo=20-n=20/usr/sbin/lvm=20vgs=20--config=20= '=20devices=20{=20preferred_names=20=3D=20["^/dev/mapper/"]=20= ignore_suspended_devices=3D1=20write_cache_state=3D0=20= disable_after_error_count=3D3=20filter=20=3D=20[=20'\''r|.*|'\''=20]=20}=20= =20global=20{=20=20locking_type=3D1=20=20prioritise_write_locks=3D1=20=20= wait_for_locks=3D1=20=20use_lvmetad=3D0=20}=20=20backup=20{=20=20= retain_min=20=3D=2050=20=20retain_days=20=3D=200=20}=20'=20--noheadings=20= --units=20b=20--nosuffix=20--separator=20'|'=20--ignoreskippedcluster=20= -o=20= uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_s= ize,vg_mda_free,lv_count,pv_count,pv_name=20(cwd=20None)=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:07:44,871::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:07:44,872::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20stomp=20from=2010.149.133.23:57191=0AReactor=20= thread::INFO::2016-04-04=20= 15:07:44,873::stompreactor::101::Broker.StompAdapter::(_cmd_connect)=20= Processing=20CONNECT=20request=0AJsonRpc=20= (StompReactor)::INFO::2016-04-04=20= 15:07:44,873::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe)=20= Subscribe=20command=20received=0AJsonRpc=20= (StompReactor)::INFO::2016-04-04=20= 15:07:44,873::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe)=20= Subscribe=20command=20received=0AReactor=20thread::DEBUG::2016-04-04=20= 15:07:44,874::stompreactor::470::protocoldetector.StompDetector::(handle_s= ocket)=20Stomp=20detected=20from=20('10.149.133.23',=2057191)=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,880::lvm::290::Storage.Misc.excCmd::(cmd)=20SUCCESS:=20<err>=20= =3D=20'=20=20WARNING:=20lvmetad=20is=20running=20but=20disabled.=20= Restart=20lvmetad=20before=20enabling=20it!\n';=20<rc>=20=3D=200=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,880::lvm::415::Storage.OperationMutex::(_reloadvgs)=20Operation=20= 'lvm=20reload=20operation'=20released=20the=20operation=20mutex=0A= storageRefresh::DEBUG::2016-04-04=20= 15:07:44,880::hsm::379::Storage.HSM::(storageRefresh)=20HSM=20is=20ready=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:45,237::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.ping'=20in=20bridge=20with=20{}=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:45,238::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.ping'=20in=20bridge=20with=20True=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:45,244::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.ping'=20in=20bridge=20with=20{}=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:45,244::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.ping'=20in=20bridge=20with=20True=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,324::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.getCapabilities'=20in=20bridge=20with=20{}=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,348::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20ens1f0.=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,348::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20ens1f0.=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,348::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20bond0.602.=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,348::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20bond0.602.=0A= jsonrpc.Executor/2::INFO::2016-04-04=20= 15:07:45,348::netinfo::495::root::(_getNetInfo)=20Obtaining=20info=20for=20= net=20bond0.602.=0ATraceback=20(most=20recent=20call=20last):=0A=20=20= File=20"/usr/lib/python2.7/site-packages/vdsm/netinfo.py",=20line=20492,=20= in=20_getNetInfo=0A=20=20=20=20'mtu':=20str(getMtu(iface))})=0A=20=20= File=20"/usr/lib/python2.7/site-packages/vdsm/netinfo.py",=20line=20191,=20= in=20getMtu=0A=20=20=20=20with=20open('/sys/class/net/%s/mtu'=20%=20= iface)=20as=20f:=0AIOError:=20[Errno=202]=20No=20such=20file=20or=20= directory:=20'/sys/class/net/bond0.602/mtu'=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,352::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/sbin/tc=20qdisc=20show=20(cwd=20None)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,359::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,359::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/sbin/tc=20class=20show=20dev=20ens1f0=20classid=20= 0:1388=20(cwd=20None)=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,364::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,386::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20numactl=20--hardware=20(cwd=20None)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,391::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,392::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20sysctl=20-n=20-e=20kernel.numa_balancing=20(cwd=20= None)=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,396::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:45,400::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.getCapabilities'=20in=20bridge=20with=20{'HBAInventory':=20= {'iSCSI':=20[{'InitiatorName':=20'iqn.1994-05.com.redhat:82cdb6fe1f7'}],=20= 'FC':=20[]},=20'packages2':=20{'kernel':=20{'release':=20= '327.10.1.el7.x86_64',=20'buildtime':=201455638630.0,=20'version':=20= '3.10.0'},=20'spice-server':=20{'release':=20'15.el7',=20'buildtime':=20= 1448026017L,=20'version':=20'0.12.4'},=20'librbd1':=20{'release':=20= '3.el7',=20'buildtime':=201448036424L,=20'version':=20'0.80.7'},=20= 'vdsm':=20{'release':=20'0.el7.centos',=20'buildtime':=201459239759L,=20= 'version':=20'4.17.23.2'},=20'qemu-kvm':=20{'release':=20'31.el7_2.7.1',=20= 'buildtime':=201454322367L,=20'version':=20'2.3.0'},=20'libvirt':=20= {'release':=20'13.el7_2.3',=20'buildtime':=201455642692L,=20'version':=20= '1.2.17'},=20'qemu-img':=20{'release':=20'31.el7_2.7.1',=20'buildtime':=20= 1454322367L,=20'version':=20'2.3.0'},=20'mom':=20{'release':=20= '1.el7.centos',=20'buildtime':=201453738202L,=20'version':=20'0.5.2'},=20= 'glusterfs-cli':=20{'release':=20'16.0.1.el7.centos',=20'buildtime':=20= 1457616516L,=20'version':=20'3.7.1'}},=20'numaNodeDistance':=20{'1':=20= [21,=2010],=20'0':=20[10,=2021]},=20'cpuModel':=20'Intel(R)=20Xeon(R)=20= CPU=20E5-2660=20v3=20@=202.60GHz',=20'liveMerge':=20'true',=20'hooks':=20= {'before_nic_hotplug':=20{'50_vmfex':=20{'md5':=20= 'e05994261acaea7dcf4b88ea0e81f1f5'}},=20'before_vdsm_start':=20= {'lro_off.sh':=20{'md5':=20'4f8117819fc321e9f7f0c94020b6938b'}},=20= 'before_device_migrate_destination':=20{'50_vmfex':=20{'md5':=20= 'e05994261acaea7dcf4b88ea0e81f1f5'}},=20'before_device_create':=20= {'50_vmfex':=20{'md5':=20'e05994261acaea7dcf4b88ea0e81f1f5'}}},=20= 'vmTypes':=20['kvm'],=20'selinux':=20{'mode':=20'-1'},=20'liveSnapshot':=20= 'true',=20'kdumpStatus':=201,=20'networks':=20{'ovirtmgmt':=20{'iface':=20= 'ens1f0',=20'addr':=20'10.149.133.27',=20'bridged':=20False,=20= 'ipv6addrs':=20['fe80::ec4:7aff:fe52:de06/64'],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'255.255.255.0',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20['10.149.133.27/24'],=20'interface':=20'ens1f0',=20= 'ipv6gateway':=20'::',=20'gateway':=20'10.149.133.1'}},=20'bridges':=20= {},=20'uuid':=20'00000000-0000-0000-0000-002590FA5BF8',=20'onlineCpus':=20= '0,1,2,3,4,5,6,7,8,9,20,21,22,23,24,25,26,27,28,29,10,11,12,13,14,15,16,17= ,18,19,30,31,32,33,34,35,36,37,38,39',=20'nics':=20{'ens1f1':=20{'addr':=20= '',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20'IPV6INIT':=20= 'yes',=20'NAME':=20'ens1f1',=20'IPV6_PEERDNS':=20'yes',=20'DEFROUTE':=20= 'yes',=20'UUID':=20'9083efc6-b54f-4584-92cc-272e0ee2feaf',=20'PEERDNS':=20= 'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20'yes',=20= 'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '0C:C4:7A:52:DE:07',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'0c:c4:7a:52:de:07',=20'speed':=200,=20'gateway':=20''},=20= 'ens1f0':=20{'addr':=20'10.149.133.27',=20'ipv6gateway':=20'::',=20= 'ipv6addrs':=20['fe80::ec4:7aff:fe52:de06/64'],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'255.255.255.0',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20['10.149.133.27/24'],=20'cfg':=20{'HWADDR':=20= '0C:C4:7A:52:DE:06',=20'IPV6INIT':=20'yes',=20'NAME':=20'ens1f0',=20= 'IPV6_PEERROUTES':=20'yes',=20'DEFROUTE':=20'yes',=20'NETBOOT':=20'yes',=20= 'IPV6_PEERDNS':=20'yes',=20'IPADDR':=20'10.149.133.27',=20'GATEWAY':=20= '10.149.133.1',=20'IPV6_AUTOCONF':=20'yes',=20'PREFIX':=20'24',=20= 'BOOTPROTO':=20'none',=20'IPV6_DEFROUTE':=20'yes',=20'DEVICE':=20= 'ens1f0',=20'IPV6_FAILURE_FATAL':=20'no',=20'DNS1':=20'10.148.125.237',=20= 'IPV4_FAILURE_FATAL':=20'no',=20'TYPE':=20'Ethernet',=20'ONBOOT':=20= 'yes',=20'UUID':=20'a6e93be4-5fa5-4567-b205-adfd3fa96c45'},=20'hwaddr':=20= '0c:c4:7a:52:de:06',=20'speed':=201000,=20'gateway':=20'10.149.133.1'},=20= 'eno2':=20{'addr':=20'',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20= 'mtu':=20'1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20= False,=20'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20= 'IPV6INIT':=20'yes',=20'NAME':=20'eno2',=20'IPV6_PEERDNS':=20'yes',=20= 'DEFROUTE':=20'yes',=20'UUID':=20'9d958f30-d3c9-42a5-96f9-6362268ce048',=20= 'PEERDNS':=20'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20= 'yes',=20'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '00:25:90:FA:5B:F9',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'00:25:90:fa:5b:f9',=20'speed':=200,=20'gateway':=20''},=20= 'eno1':=20{'addr':=20'',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20= 'mtu':=20'1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20= False,=20'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20= 'IPV6INIT':=20'yes',=20'NAME':=20'eno1',=20'IPV6_PEERDNS':=20'yes',=20= 'DEFROUTE':=20'yes',=20'UUID':=20'ad1e4309-419c-4b94-a75c-ebeacf7c811b',=20= 'PEERDNS':=20'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20= 'yes',=20'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '00:25:90:FA:5B:F8',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'00:25:90:fa:5b:f8',=20'speed':=200,=20'gateway':=20''}},=20= 'software_revision':=20'0',=20'hostdevPassthrough':=20'false',=20= 'clusterLevels':=20['3.4',=20'3.5',=20'3.6'],=20'cpuFlags':=20= 'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,c= lflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp,l= m,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,a= perfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,smx,est,tm2,sss= e3,fma,cx16,xtpr,pdcm,pcid,dca,sse4_1,sse4_2,x2apic,movbe,popcnt,tsc_deadl= ine_timer,xsave,avx,f16c,rdrand,lahf_lm,abm,ida,arat,epb,pln,pts,dtherm,tp= r_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi= 2,erms,invpcid,cqm,xsaveopt,cqm_llc,cqm_occup_llc,model_Nehalem,model_Conr= oe,model_coreduo,model_core2duo,model_Penryn,model_n270',=20= 'ISCSIInitiatorName':=20'iqn.1994-05.com.redhat:82cdb6fe1f7',=20= 'netConfigDirty':=20'False',=20'supportedENGINEs':=20['3.4',=20'3.5',=20= '3.6'],=20'autoNumaBalancing':=201,=20'additionalFeatures':=20[],=20= 'reservedMem':=20'321',=20'bondings':=20{'bond0':=20{'ipv4addrs':=20[],=20= 'addr':=20'',=20'cfg':=20{'BOOTPROTO':=20'none',=20'BONDING_OPTS':=20= 'mode=3D0'},=20'ipv6addrs':=20[],=20'active_slave':=20'',=20'mtu':=20= '1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20False,=20= 'slaves':=20[],=20'hwaddr':=20'9a:35:a4:87:95:9d',=20'ipv6gateway':=20= '::',=20'gateway':=20'',=20'opts':=20{'mode':=20'0'}}},=20= 'software_version':=20'4.17',=20'memSize':=20'128665',=20'cpuSpeed':=20= '2822.421',=20'numaNodes':=20{'1':=20{'totalMemory':=20'65536',=20= 'cpus':=20[10,=2011,=2012,=2013,=2014,=2015,=2016,=2017,=2018,=2019,=20= 30,=2031,=2032,=2033,=2034,=2035,=2036,=2037,=2038,=2039]},=20'0':=20= {'totalMemory':=20'65428',=20'cpus':=20[0,=201,=202,=203,=204,=205,=206,=20= 7,=208,=209,=2020,=2021,=2022,=2023,=2024,=2025,=2026,=2027,=2028,=20= 29]}},=20'cpuSockets':=20'2',=20'vlans':=20{},=20'lastClientIface':=20= 'ens1f0',=20'cpuCores':=20'20',=20'kvmEnabled':=20'true',=20= 'guestOverhead':=20'65',=20'version_name':=20'Snow=20Man',=20= 'cpuThreads':=20'40',=20'emulatedMachines':=20['pc-i440fx-rhel7.1.0',=20= 'rhel6.3.0',=20'pc-q35-rhel7.2.0',=20'pc-i440fx-rhel7.0.0',=20= 'rhel6.1.0',=20'rhel6.6.0',=20'rhel6.2.0',=20'pc',=20'pc-q35-rhel7.0.0',=20= 'pc-q35-rhel7.1.0',=20'q35',=20'pc-i440fx-rhel7.2.0',=20'rhel6.4.0',=20= 'rhel6.0.0',=20'rhel6.5.0'],=20'rngSources':=20['random'],=20= 'operatingSystem':=20{'release':=20'2.1511.el7.centos.2.10',=20= 'version':=20'7',=20'name':=20'RHEL'}}=0A= jsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,285::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.getCapabilities'=20in=20bridge=20with=20{}=0A= jsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,298::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20ens1f0.=0A= jsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,298::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20ens1f0.=0A= jsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,298::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20bond0.602.=0A= jsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,298::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20bond0.602.=0A= jsonrpc.Executor/3::INFO::2016-04-04=20= 15:07:47,298::netinfo::495::root::(_getNetInfo)=20Obtaining=20info=20for=20= net=20bond0.602.=0ATraceback=20(most=20recent=20call=20last):=0A=20=20= File=20"/usr/lib/python2.7/site-packages/vdsm/netinfo.py",=20line=20492,=20= in=20_getNetInfo=0A=20=20=20=20'mtu':=20str(getMtu(iface))})=0A=20=20= File=20"/usr/lib/python2.7/site-packages/vdsm/netinfo.py",=20line=20191,=20= in=20getMtu=0A=20=20=20=20with=20open('/sys/class/net/%s/mtu'=20%=20= iface)=20as=20f:=0AIOError:=20[Errno=202]=20No=20such=20file=20or=20= directory:=20'/sys/class/net/bond0.602/mtu'=0A= jsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,302::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/sbin/tc=20qdisc=20show=20(cwd=20None)=0A= jsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,309::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,309::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/sbin/tc=20class=20show=20dev=20ens1f0=20classid=20= 0:1388=20(cwd=20None)=0Ajsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,314::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/3::DEBUG::2016-04-04=20= 15:07:47,323::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.getCapabilities'=20in=20bridge=20with=20{'HBAInventory':=20= {'iSCSI':=20[{'InitiatorName':=20'iqn.1994-05.com.redhat:82cdb6fe1f7'}],=20= 'FC':=20[]},=20'packages2':=20{'kernel':=20{'release':=20= '327.10.1.el7.x86_64',=20'buildtime':=201455638630.0,=20'version':=20= '3.10.0'},=20'spice-server':=20{'release':=20'15.el7',=20'buildtime':=20= 1448026017L,=20'version':=20'0.12.4'},=20'librbd1':=20{'release':=20= '3.el7',=20'buildtime':=201448036424L,=20'version':=20'0.80.7'},=20= 'vdsm':=20{'release':=20'0.el7.centos',=20'buildtime':=201459239759L,=20= 'version':=20'4.17.23.2'},=20'qemu-kvm':=20{'release':=20'31.el7_2.7.1',=20= 'buildtime':=201454322367L,=20'version':=20'2.3.0'},=20'libvirt':=20= {'release':=20'13.el7_2.3',=20'buildtime':=201455642692L,=20'version':=20= '1.2.17'},=20'qemu-img':=20{'release':=20'31.el7_2.7.1',=20'buildtime':=20= 1454322367L,=20'version':=20'2.3.0'},=20'mom':=20{'release':=20= '1.el7.centos',=20'buildtime':=201453738202L,=20'version':=20'0.5.2'},=20= 'glusterfs-cli':=20{'release':=20'16.0.1.el7.centos',=20'buildtime':=20= 1457616516L,=20'version':=20'3.7.1'}},=20'numaNodeDistance':=20{'1':=20= [21,=2010],=20'0':=20[10,=2021]},=20'cpuModel':=20'Intel(R)=20Xeon(R)=20= CPU=20E5-2660=20v3=20@=202.60GHz',=20'liveMerge':=20'true',=20'hooks':=20= {'before_nic_hotplug':=20{'50_vmfex':=20{'md5':=20= 'e05994261acaea7dcf4b88ea0e81f1f5'}},=20'before_vdsm_start':=20= {'lro_off.sh':=20{'md5':=20'4f8117819fc321e9f7f0c94020b6938b'}},=20= 'before_device_migrate_destination':=20{'50_vmfex':=20{'md5':=20= 'e05994261acaea7dcf4b88ea0e81f1f5'}},=20'before_device_create':=20= {'50_vmfex':=20{'md5':=20'e05994261acaea7dcf4b88ea0e81f1f5'}}},=20= 'vmTypes':=20['kvm'],=20'selinux':=20{'mode':=20'-1'},=20'liveSnapshot':=20= 'true',=20'kdumpStatus':=201,=20'networks':=20{'ovirtmgmt':=20{'iface':=20= 'ens1f0',=20'addr':=20'10.149.133.27',=20'bridged':=20False,=20= 'ipv6addrs':=20['fe80::ec4:7aff:fe52:de06/64'],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'255.255.255.0',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20['10.149.133.27/24'],=20'interface':=20'ens1f0',=20= 'ipv6gateway':=20'::',=20'gateway':=20'10.149.133.1'}},=20'bridges':=20= {},=20'uuid':=20'00000000-0000-0000-0000-002590FA5BF8',=20'onlineCpus':=20= '0,1,2,3,4,5,6,7,8,9,20,21,22,23,24,25,26,27,28,29,10,11,12,13,14,15,16,17= ,18,19,30,31,32,33,34,35,36,37,38,39',=20'nics':=20{'ens1f1':=20{'addr':=20= '',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20'IPV6INIT':=20= 'yes',=20'NAME':=20'ens1f1',=20'IPV6_PEERDNS':=20'yes',=20'DEFROUTE':=20= 'yes',=20'UUID':=20'9083efc6-b54f-4584-92cc-272e0ee2feaf',=20'PEERDNS':=20= 'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20'yes',=20= 'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '0C:C4:7A:52:DE:07',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'0c:c4:7a:52:de:07',=20'speed':=200,=20'gateway':=20''},=20= 'ens1f0':=20{'addr':=20'10.149.133.27',=20'ipv6gateway':=20'::',=20= 'ipv6addrs':=20['fe80::ec4:7aff:fe52:de06/64'],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'255.255.255.0',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20['10.149.133.27/24'],=20'cfg':=20{'HWADDR':=20= '0C:C4:7A:52:DE:06',=20'IPV6INIT':=20'yes',=20'NAME':=20'ens1f0',=20= 'IPV6_PEERROUTES':=20'yes',=20'DEFROUTE':=20'yes',=20'NETBOOT':=20'yes',=20= 'IPV6_PEERDNS':=20'yes',=20'IPADDR':=20'10.149.133.27',=20'GATEWAY':=20= '10.149.133.1',=20'IPV6_AUTOCONF':=20'yes',=20'PREFIX':=20'24',=20= 'BOOTPROTO':=20'none',=20'IPV6_DEFROUTE':=20'yes',=20'DEVICE':=20= 'ens1f0',=20'IPV6_FAILURE_FATAL':=20'no',=20'DNS1':=20'10.148.125.237',=20= 'IPV4_FAILURE_FATAL':=20'no',=20'TYPE':=20'Ethernet',=20'ONBOOT':=20= 'yes',=20'UUID':=20'a6e93be4-5fa5-4567-b205-adfd3fa96c45'},=20'hwaddr':=20= '0c:c4:7a:52:de:06',=20'speed':=201000,=20'gateway':=20'10.149.133.1'},=20= 'eno2':=20{'addr':=20'',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20= 'mtu':=20'1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20= False,=20'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20= 'IPV6INIT':=20'yes',=20'NAME':=20'eno2',=20'IPV6_PEERDNS':=20'yes',=20= 'DEFROUTE':=20'yes',=20'UUID':=20'9d958f30-d3c9-42a5-96f9-6362268ce048',=20= 'PEERDNS':=20'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20= 'yes',=20'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '00:25:90:FA:5B:F9',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'00:25:90:fa:5b:f9',=20'speed':=200,=20'gateway':=20''},=20= 'eno1':=20{'addr':=20'',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20= 'mtu':=20'1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20= False,=20'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20= 'IPV6INIT':=20'yes',=20'NAME':=20'eno1',=20'IPV6_PEERDNS':=20'yes',=20= 'DEFROUTE':=20'yes',=20'UUID':=20'ad1e4309-419c-4b94-a75c-ebeacf7c811b',=20= 'PEERDNS':=20'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20= 'yes',=20'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '00:25:90:FA:5B:F8',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'00:25:90:fa:5b:f8',=20'speed':=200,=20'gateway':=20''}},=20= 'software_revision':=20'0',=20'hostdevPassthrough':=20'false',=20= 'clusterLevels':=20['3.4',=20'3.5',=20'3.6'],=20'cpuFlags':=20= 'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,c= lflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp,l= m,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,a= perfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,smx,est,tm2,sss= e3,fma,cx16,xtpr,pdcm,pcid,dca,sse4_1,sse4_2,x2apic,movbe,popcnt,tsc_deadl= ine_timer,xsave,avx,f16c,rdrand,lahf_lm,abm,ida,arat,epb,pln,pts,dtherm,tp= r_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi= 2,erms,invpcid,cqm,xsaveopt,cqm_llc,cqm_occup_llc,model_Nehalem,model_Conr= oe,model_coreduo,model_core2duo,model_Penryn,model_n270',=20= 'ISCSIInitiatorName':=20'iqn.1994-05.com.redhat:82cdb6fe1f7',=20= 'netConfigDirty':=20'False',=20'supportedENGINEs':=20['3.4',=20'3.5',=20= '3.6'],=20'autoNumaBalancing':=201,=20'additionalFeatures':=20[],=20= 'reservedMem':=20'321',=20'bondings':=20{'bond0':=20{'ipv4addrs':=20[],=20= 'addr':=20'',=20'cfg':=20{'BOOTPROTO':=20'none',=20'BONDING_OPTS':=20= 'mode=3D0'},=20'ipv6addrs':=20[],=20'active_slave':=20'',=20'mtu':=20= '1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20False,=20= 'slaves':=20[],=20'hwaddr':=20'9a:35:a4:87:95:9d',=20'ipv6gateway':=20= '::',=20'gateway':=20'',=20'opts':=20{'mode':=20'0'}}},=20= 'software_version':=20'4.17',=20'memSize':=20'128665',=20'cpuSpeed':=20= '1640.742',=20'numaNodes':=20{'1':=20{'totalMemory':=20'65536',=20= 'cpus':=20[10,=2011,=2012,=2013,=2014,=2015,=2016,=2017,=2018,=2019,=20= 30,=2031,=2032,=2033,=2034,=2035,=2036,=2037,=2038,=2039]},=20'0':=20= {'totalMemory':=20'65428',=20'cpus':=20[0,=201,=202,=203,=204,=205,=206,=20= 7,=208,=209,=2020,=2021,=2022,=2023,=2024,=2025,=2026,=2027,=2028,=20= 29]}},=20'cpuSockets':=20'2',=20'vlans':=20{},=20'lastClientIface':=20= 'ens1f0',=20'cpuCores':=20'20',=20'kvmEnabled':=20'true',=20= 'guestOverhead':=20'65',=20'version_name':=20'Snow=20Man',=20= 'cpuThreads':=20'40',=20'emulatedMachines':=20['pc-i440fx-rhel7.1.0',=20= 'rhel6.3.0',=20'pc-q35-rhel7.2.0',=20'pc-i440fx-rhel7.0.0',=20= 'rhel6.1.0',=20'rhel6.6.0',=20'rhel6.2.0',=20'pc',=20'pc-q35-rhel7.0.0',=20= 'pc-q35-rhel7.1.0',=20'q35',=20'pc-i440fx-rhel7.2.0',=20'rhel6.4.0',=20= 'rhel6.0.0',=20'rhel6.5.0'],=20'rngSources':=20['random'],=20= 'operatingSystem':=20{'release':=20'2.1511.el7.centos.2.10',=20= 'version':=20'7',=20'name':=20'RHEL'}}=0A= jsonrpc.Executor/4::DEBUG::2016-04-04=20= 15:07:47,398::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.getHardwareInfo'=20in=20bridge=20with=20{}=0A= jsonrpc.Executor/4::DEBUG::2016-04-04=20= 15:07:47,437::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.getHardwareInfo'=20in=20bridge=20with=20= {'systemProductName':=20'SYS-2028TP-HC1TR',=20'systemSerialNumber':=20= 'S16549025214470',=20'systemFamily':=20'To=20be=20filled=20by=20O.E.M.',=20= 'systemVersion':=20'0123456789',=20'systemUUID':=20= '00000000-0000-0000-0000-002590FA5BF8',=20'systemManufacturer':=20= 'Supermicro'}=0Ajsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,894::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.getCapabilities'=20in=20bridge=20with=20{}=0A= jsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,906::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20ens1f0.=0A= jsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,906::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20ens1f0.=0A= jsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,907::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20bond0.602.=0A= jsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,907::netinfo::453::root::(_dhcp_used)=20There=20is=20no=20VDSM=20= network=20configured=20on=20bond0.602.=0A= jsonrpc.Executor/5::INFO::2016-04-04=20= 15:07:47,907::netinfo::495::root::(_getNetInfo)=20Obtaining=20info=20for=20= net=20bond0.602.=0ATraceback=20(most=20recent=20call=20last):=0A=20=20= File=20"/usr/lib/python2.7/site-packages/vdsm/netinfo.py",=20line=20492,=20= in=20_getNetInfo=0A=20=20=20=20'mtu':=20str(getMtu(iface))})=0A=20=20= File=20"/usr/lib/python2.7/site-packages/vdsm/netinfo.py",=20line=20191,=20= in=20getMtu=0A=20=20=20=20with=20open('/sys/class/net/%s/mtu'=20%=20= iface)=20as=20f:=0AIOError:=20[Errno=202]=20No=20such=20file=20or=20= directory:=20'/sys/class/net/bond0.602/mtu'=0A= jsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,910::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/sbin/tc=20qdisc=20show=20(cwd=20None)=0A= jsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,916::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,916::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/usr/sbin/tc=20class=20show=20dev=20ens1f0=20classid=20= 0:1388=20(cwd=20None)=0Ajsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,921::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/5::DEBUG::2016-04-04=20= 15:07:47,928::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.getCapabilities'=20in=20bridge=20with=20{'HBAInventory':=20= {'iSCSI':=20[{'InitiatorName':=20'iqn.1994-05.com.redhat:82cdb6fe1f7'}],=20= 'FC':=20[]},=20'packages2':=20{'kernel':=20{'release':=20= '327.10.1.el7.x86_64',=20'buildtime':=201455638630.0,=20'version':=20= '3.10.0'},=20'spice-server':=20{'release':=20'15.el7',=20'buildtime':=20= 1448026017L,=20'version':=20'0.12.4'},=20'librbd1':=20{'release':=20= '3.el7',=20'buildtime':=201448036424L,=20'version':=20'0.80.7'},=20= 'vdsm':=20{'release':=20'0.el7.centos',=20'buildtime':=201459239759L,=20= 'version':=20'4.17.23.2'},=20'qemu-kvm':=20{'release':=20'31.el7_2.7.1',=20= 'buildtime':=201454322367L,=20'version':=20'2.3.0'},=20'libvirt':=20= {'release':=20'13.el7_2.3',=20'buildtime':=201455642692L,=20'version':=20= '1.2.17'},=20'qemu-img':=20{'release':=20'31.el7_2.7.1',=20'buildtime':=20= 1454322367L,=20'version':=20'2.3.0'},=20'mom':=20{'release':=20= '1.el7.centos',=20'buildtime':=201453738202L,=20'version':=20'0.5.2'},=20= 'glusterfs-cli':=20{'release':=20'16.0.1.el7.centos',=20'buildtime':=20= 1457616516L,=20'version':=20'3.7.1'}},=20'numaNodeDistance':=20{'1':=20= [21,=2010],=20'0':=20[10,=2021]},=20'cpuModel':=20'Intel(R)=20Xeon(R)=20= CPU=20E5-2660=20v3=20@=202.60GHz',=20'liveMerge':=20'true',=20'hooks':=20= {'before_nic_hotplug':=20{'50_vmfex':=20{'md5':=20= 'e05994261acaea7dcf4b88ea0e81f1f5'}},=20'before_vdsm_start':=20= {'lro_off.sh':=20{'md5':=20'4f8117819fc321e9f7f0c94020b6938b'}},=20= 'before_device_migrate_destination':=20{'50_vmfex':=20{'md5':=20= 'e05994261acaea7dcf4b88ea0e81f1f5'}},=20'before_device_create':=20= {'50_vmfex':=20{'md5':=20'e05994261acaea7dcf4b88ea0e81f1f5'}}},=20= 'vmTypes':=20['kvm'],=20'selinux':=20{'mode':=20'-1'},=20'liveSnapshot':=20= 'true',=20'kdumpStatus':=201,=20'networks':=20{'ovirtmgmt':=20{'iface':=20= 'ens1f0',=20'addr':=20'10.149.133.27',=20'bridged':=20False,=20= 'ipv6addrs':=20['fe80::ec4:7aff:fe52:de06/64'],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'255.255.255.0',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20['10.149.133.27/24'],=20'interface':=20'ens1f0',=20= 'ipv6gateway':=20'::',=20'gateway':=20'10.149.133.1'}},=20'bridges':=20= {},=20'uuid':=20'00000000-0000-0000-0000-002590FA5BF8',=20'onlineCpus':=20= '0,1,2,3,4,5,6,7,8,9,20,21,22,23,24,25,26,27,28,29,10,11,12,13,14,15,16,17= ,18,19,30,31,32,33,34,35,36,37,38,39',=20'nics':=20{'ens1f1':=20{'addr':=20= '',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20'IPV6INIT':=20= 'yes',=20'NAME':=20'ens1f1',=20'IPV6_PEERDNS':=20'yes',=20'DEFROUTE':=20= 'yes',=20'UUID':=20'9083efc6-b54f-4584-92cc-272e0ee2feaf',=20'PEERDNS':=20= 'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20'yes',=20= 'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '0C:C4:7A:52:DE:07',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'0c:c4:7a:52:de:07',=20'speed':=200,=20'gateway':=20''},=20= 'ens1f0':=20{'addr':=20'10.149.133.27',=20'ipv6gateway':=20'::',=20= 'ipv6addrs':=20['fe80::ec4:7aff:fe52:de06/64'],=20'mtu':=20'1500',=20= 'dhcpv4':=20False,=20'netmask':=20'255.255.255.0',=20'dhcpv6':=20False,=20= 'ipv4addrs':=20['10.149.133.27/24'],=20'cfg':=20{'HWADDR':=20= '0C:C4:7A:52:DE:06',=20'IPV6INIT':=20'yes',=20'NAME':=20'ens1f0',=20= 'IPV6_PEERROUTES':=20'yes',=20'DEFROUTE':=20'yes',=20'NETBOOT':=20'yes',=20= 'IPV6_PEERDNS':=20'yes',=20'IPADDR':=20'10.149.133.27',=20'GATEWAY':=20= '10.149.133.1',=20'IPV6_AUTOCONF':=20'yes',=20'PREFIX':=20'24',=20= 'BOOTPROTO':=20'none',=20'IPV6_DEFROUTE':=20'yes',=20'DEVICE':=20= 'ens1f0',=20'IPV6_FAILURE_FATAL':=20'no',=20'DNS1':=20'10.148.125.237',=20= 'IPV4_FAILURE_FATAL':=20'no',=20'TYPE':=20'Ethernet',=20'ONBOOT':=20= 'yes',=20'UUID':=20'a6e93be4-5fa5-4567-b205-adfd3fa96c45'},=20'hwaddr':=20= '0c:c4:7a:52:de:06',=20'speed':=201000,=20'gateway':=20'10.149.133.1'},=20= 'eno2':=20{'addr':=20'',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20= 'mtu':=20'1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20= False,=20'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20= 'IPV6INIT':=20'yes',=20'NAME':=20'eno2',=20'IPV6_PEERDNS':=20'yes',=20= 'DEFROUTE':=20'yes',=20'UUID':=20'9d958f30-d3c9-42a5-96f9-6362268ce048',=20= 'PEERDNS':=20'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20= 'yes',=20'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '00:25:90:FA:5B:F9',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'00:25:90:fa:5b:f9',=20'speed':=200,=20'gateway':=20''},=20= 'eno1':=20{'addr':=20'',=20'ipv6gateway':=20'::',=20'ipv6addrs':=20[],=20= 'mtu':=20'1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20= False,=20'ipv4addrs':=20[],=20'cfg':=20{'PEERROUTES':=20'yes',=20= 'IPV6INIT':=20'yes',=20'NAME':=20'eno1',=20'IPV6_PEERDNS':=20'yes',=20= 'DEFROUTE':=20'yes',=20'UUID':=20'ad1e4309-419c-4b94-a75c-ebeacf7c811b',=20= 'PEERDNS':=20'yes',=20'IPV4_FAILURE_FATAL':=20'no',=20'IPV6_AUTOCONF':=20= 'yes',=20'BOOTPROTO':=20'dhcp',=20'IPV6_DEFROUTE':=20'yes',=20'HWADDR':=20= '00:25:90:FA:5B:F8',=20'IPV6_FAILURE_FATAL':=20'no',=20'TYPE':=20= 'Ethernet',=20'ONBOOT':=20'no',=20'IPV6_PEERROUTES':=20'yes'},=20= 'hwaddr':=20'00:25:90:fa:5b:f8',=20'speed':=200,=20'gateway':=20''}},=20= 'software_revision':=20'0',=20'hostdevPassthrough':=20'false',=20= 'clusterLevels':=20['3.4',=20'3.5',=20'3.6'],=20'cpuFlags':=20= 'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,c= lflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp,l= m,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,a= perfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,smx,est,tm2,sss= e3,fma,cx16,xtpr,pdcm,pcid,dca,sse4_1,sse4_2,x2apic,movbe,popcnt,tsc_deadl= ine_timer,xsave,avx,f16c,rdrand,lahf_lm,abm,ida,arat,epb,pln,pts,dtherm,tp= r_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi= 2,erms,invpcid,cqm,xsaveopt,cqm_llc,cqm_occup_llc,model_Nehalem,model_Conr= oe,model_coreduo,model_core2duo,model_Penryn,model_n270',=20= 'ISCSIInitiatorName':=20'iqn.1994-05.com.redhat:82cdb6fe1f7',=20= 'netConfigDirty':=20'False',=20'supportedENGINEs':=20['3.4',=20'3.5',=20= '3.6'],=20'autoNumaBalancing':=201,=20'additionalFeatures':=20[],=20= 'reservedMem':=20'321',=20'bondings':=20{'bond0':=20{'ipv4addrs':=20[],=20= 'addr':=20'',=20'cfg':=20{'BOOTPROTO':=20'none',=20'BONDING_OPTS':=20= 'mode=3D0'},=20'ipv6addrs':=20[],=20'active_slave':=20'',=20'mtu':=20= '1500',=20'dhcpv4':=20False,=20'netmask':=20'',=20'dhcpv6':=20False,=20= 'slaves':=20[],=20'hwaddr':=20'9a:35:a4:87:95:9d',=20'ipv6gateway':=20= '::',=20'gateway':=20'',=20'opts':=20{'mode':=20'0'}}},=20= 'software_version':=20'4.17',=20'memSize':=20'128665',=20'cpuSpeed':=20= '1411.312',=20'numaNodes':=20{'1':=20{'totalMemory':=20'65536',=20= 'cpus':=20[10,=2011,=2012,=2013,=2014,=2015,=2016,=2017,=2018,=2019,=20= 30,=2031,=2032,=2033,=2034,=2035,=2036,=2037,=2038,=2039]},=20'0':=20= {'totalMemory':=20'65428',=20'cpus':=20[0,=201,=202,=203,=204,=205,=206,=20= 7,=208,=209,=2020,=2021,=2022,=2023,=2024,=2025,=2026,=2027,=2028,=20= 29]}},=20'cpuSockets':=20'2',=20'vlans':=20{},=20'lastClientIface':=20= 'ens1f0',=20'cpuCores':=20'20',=20'kvmEnabled':=20'true',=20= 'guestOverhead':=20'65',=20'version_name':=20'Snow=20Man',=20= 'cpuThreads':=20'40',=20'emulatedMachines':=20['pc-i440fx-rhel7.1.0',=20= 'rhel6.3.0',=20'pc-q35-rhel7.2.0',=20'pc-i440fx-rhel7.0.0',=20= 'rhel6.1.0',=20'rhel6.6.0',=20'rhel6.2.0',=20'pc',=20'pc-q35-rhel7.0.0',=20= 'pc-q35-rhel7.1.0',=20'q35',=20'pc-i440fx-rhel7.2.0',=20'rhel6.4.0',=20= 'rhel6.0.0',=20'rhel6.5.0'],=20'rngSources':=20['random'],=20= 'operatingSystem':=20{'release':=20'2.1511.el7.centos.2.10',=20= 'version':=20'7',=20'name':=20'RHEL'}}=0A= jsonrpc.Executor/6::DEBUG::2016-04-04=20= 15:07:47,937::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.getHardwareInfo'=20in=20bridge=20with=20{}=0A= jsonrpc.Executor/6::DEBUG::2016-04-04=20= 15:07:47,938::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.getHardwareInfo'=20in=20bridge=20with=20= {'systemProductName':=20'SYS-2028TP-HC1TR',=20'systemSerialNumber':=20= 'S16549025214470',=20'systemFamily':=20'To=20be=20filled=20by=20O.E.M.',=20= 'systemVersion':=20'0123456789',=20'systemUUID':=20= '00000000-0000-0000-0000-002590FA5BF8',=20'systemManufacturer':=20= 'Supermicro'}=0Ajsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:55,287::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`e10f06fe-72fb-445e-bcb1-d0f12266f1bf`::moving=20from=20state=20= init=20->=20state=20preparing=0Ajsonrpc.Executor/7::INFO::2016-04-04=20= 15:07:55,288::logUtils::48::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats(options=3DNone)=0Ajsonrpc.Executor/7::INFO::2016-04-04=20= 15:07:55,288::logUtils::51::dispatcher::(wrapper)=20Run=20and=20protect:=20= repoStats,=20Return=20response:=20{}=0A= jsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:55,288::task::1191::Storage.TaskManager.Task::(prepare)=20= Task=3D`e10f06fe-72fb-445e-bcb1-d0f12266f1bf`::finished:=20{}=0A= jsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:55,288::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`e10f06fe-72fb-445e-bcb1-d0f12266f1bf`::moving=20from=20state=20= preparing=20->=20state=20finished=0A= jsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:55,288::resourceManager::943::Storage.ResourceManager.Owner::(releas= eAll)=20Owner.releaseAll=20requests=20{}=20resources=20{}=0A= jsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:55,288::resourceManager::980::Storage.ResourceManager.Owner::(cancel= All)=20Owner.cancelAll=20requests=20{}=0A= jsonrpc.Executor/7::DEBUG::2016-04-04=20= 15:07:55,288::task::993::Storage.TaskManager.Task::(_decref)=20= Task=3D`e10f06fe-72fb-445e-bcb1-d0f12266f1bf`::ref=200=20aborting=20= False=0Ajsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,387::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'StoragePool.disconnect'=20in=20bridge=20with=20= {u'storagepoolID':=20u'5498ceb7-80b1-4d8f-9e74-938478109289',=20= u'scsiKey':=20u'5498ceb7-80b1-4d8f-9e74-938478109289',=20u'hostID':=204}=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,387::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`ba1fe393-2193-4be5-a122-3492eba664b4`::moving=20from=20state=20= init=20->=20state=20preparing=0Ajsonrpc.Executor/0::INFO::2016-04-04=20= 15:07:55,388::logUtils::48::dispatcher::(wrapper)=20Run=20and=20protect:=20= disconnectStoragePool(spUUID=3Du'5498ceb7-80b1-4d8f-9e74-938478109289',=20= hostID=3D4,=20remove=3DFalse,=20options=3DNone)=0A= jsonrpc.Executor/0::WARNING::2016-04-04=20= 15:07:55,388::hsm::1136::Storage.HSM::(disconnectStoragePool)=20= disconnect=20sp:=205498ceb7-80b1-4d8f-9e74-938478109289=20failed.=20= Known=20pools=20{}=0Ajsonrpc.Executor/0::INFO::2016-04-04=20= 15:07:55,388::logUtils::51::dispatcher::(wrapper)=20Run=20and=20protect:=20= disconnectStoragePool,=20Return=20response:=20None=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,388::task::1191::Storage.TaskManager.Task::(prepare)=20= Task=3D`ba1fe393-2193-4be5-a122-3492eba664b4`::finished:=20None=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,388::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`ba1fe393-2193-4be5-a122-3492eba664b4`::moving=20from=20state=20= preparing=20->=20state=20finished=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,388::resourceManager::943::Storage.ResourceManager.Owner::(releas= eAll)=20Owner.releaseAll=20requests=20{}=20resources=20{}=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,388::resourceManager::980::Storage.ResourceManager.Owner::(cancel= All)=20Owner.cancelAll=20requests=20{}=0A= jsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,388::task::993::Storage.TaskManager.Task::(_decref)=20= Task=3D`ba1fe393-2193-4be5-a122-3492eba664b4`::ref=200=20aborting=20= False=0Ajsonrpc.Executor/0::DEBUG::2016-04-04=20= 15:07:55,388::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'StoragePool.disconnect'=20in=20bridge=20with=20True=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,468::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'StoragePool.disconnectStorageServer'=20in=20bridge=20with=20= {u'connectionParams':=20[{u'id':=20= u'b8287ae9-9831-4e4a-ad5d-72d3d064d973',=20u'connection':=20= u'superstore235.svc.exam.org:/ovirtiso001',=20u'iqn':=20u'',=20u'user':=20= u'',=20u'protocol_version':=20u'3',=20u'tpgt':=20u'1',=20u'password':=20= '********',=20u'port':=20u''}],=20u'storagepoolID':=20= u'5498ceb7-80b1-4d8f-9e74-938478109289',=20u'domainType':=201}=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,468::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`972c09c0-53e2-4b6a-883e-df0b87559c29`::moving=20from=20state=20= init=20->=20state=20preparing=0Ajsonrpc.Executor/1::INFO::2016-04-04=20= 15:07:55,468::logUtils::48::dispatcher::(wrapper)=20Run=20and=20protect:=20= disconnectStorageServer(domType=3D1,=20= spUUID=3Du'5498ceb7-80b1-4d8f-9e74-938478109289',=20conList=3D[{u'id':=20= u'b8287ae9-9831-4e4a-ad5d-72d3d064d973',=20u'connection':=20= u'superstore235.svc.exam.org:/ovirtiso001',=20u'iqn':=20u'',=20u'user':=20= u'',=20u'protocol_version':=20u'3',=20u'tpgt':=20u'1',=20u'password':=20= '********',=20u'port':=20u''}],=20options=3DNone)=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,469::mount::229::Storage.Misc.excCmd::(_runcmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/bin/umount=20-f=20-l=20= /rhev/data-center/mnt/superstore235.svc.exam.org:_ovirtiso001=20(cwd=20= None)=0Ajsonrpc.Executor/1::ERROR::2016-04-04=20= 15:07:55,482::hsm::2557::Storage.HSM::(disconnectStorageServer)=20Could=20= not=20disconnect=20from=20storageServer=0ATraceback=20(most=20recent=20= call=20last):=0A=20=20File=20"/usr/share/vdsm/storage/hsm.py",=20line=20= 2553,=20in=20disconnectStorageServer=0A=20=20=20=20conObj.disconnect()=0A= =20=20File=20"/usr/share/vdsm/storage/storageServer.py",=20line=20449,=20= in=20disconnect=0A=20=20=20=20return=20self._mountCon.disconnect()=0A=20=20= File=20"/usr/share/vdsm/storage/storageServer.py",=20line=20258,=20in=20= disconnect=0A=20=20=20=20self._mount.umount(True,=20True)=0A=20=20File=20= "/usr/share/vdsm/storage/mount.py",=20line=20256,=20in=20umount=0A=20=20=20= =20return=20self._runcmd(cmd,=20timeout)=0A=20=20File=20= "/usr/share/vdsm/storage/mount.py",=20line=20241,=20in=20_runcmd=0A=20=20= =20=20raise=20MountError(rc,=20";".join((out,=20err)))=0AMountError:=20= (32,=20';umount:=20= /rhev/data-center/mnt/superstore235.svc.exam.org:_ovirtiso001:=20= mountpoint=20not=20found\n')=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,488::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.sdc.refreshStorage)=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,488::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,488::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.iscsi.rescan)=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,489::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,489::iscsi::434::Storage.ISCSI::(rescan)=20Performing=20SCSI=20= scan,=20this=20will=20take=20up=20to=2030=20seconds=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,489::iscsiadm::97::Storage.Misc.excCmd::(_runCmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /sbin/iscsiadm=20-m=20session=20-R=20(cwd=20None)=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,500::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,500::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.hba.rescan)=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,500::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,500::hba::56::Storage.HBA::(rescan)=20Starting=20scan=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,554::hba::62::Storage.HBA::(rescan)=20Scan=20finished=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,554::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,554::multipath::77::Storage.Misc.excCmd::(rescan)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/sbin/multipath=20(cwd=20None)=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,589::multipath::77::Storage.Misc.excCmd::(rescan)=20SUCCESS:=20= <err>=20=3D=20'';=20<rc>=20=3D=200=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,590::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/sbin/udevadm=20settle=20--timeout=3D5=20(cwd=20= None)=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,595::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,595::lvm::497::Storage.OperationMutex::(_invalidateAllPvs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,595::lvm::499::Storage.OperationMutex::(_invalidateAllPvs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,595::lvm::508::Storage.OperationMutex::(_invalidateAllVgs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,595::lvm::510::Storage.OperationMutex::(_invalidateAllVgs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::lvm::528::Storage.OperationMutex::(_invalidateAllLvs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::lvm::530::Storage.OperationMutex::(_invalidateAllLvs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0Ajsonrpc.Executor/1::INFO::2016-04-04=20= 15:07:55,596::logUtils::51::dispatcher::(wrapper)=20Run=20and=20protect:=20= disconnectStorageServer,=20Return=20response:=20{'statuslist':=20= [{'status':=20477,=20'id':=20u'b8287ae9-9831-4e4a-ad5d-72d3d064d973'}]}=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::task::1191::Storage.TaskManager.Task::(prepare)=20= Task=3D`972c09c0-53e2-4b6a-883e-df0b87559c29`::finished:=20= {'statuslist':=20[{'status':=20477,=20'id':=20= u'b8287ae9-9831-4e4a-ad5d-72d3d064d973'}]}=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`972c09c0-53e2-4b6a-883e-df0b87559c29`::moving=20from=20state=20= preparing=20->=20state=20finished=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::resourceManager::943::Storage.ResourceManager.Owner::(releas= eAll)=20Owner.releaseAll=20requests=20{}=20resources=20{}=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::resourceManager::980::Storage.ResourceManager.Owner::(cancel= All)=20Owner.cancelAll=20requests=20{}=0A= jsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,596::task::993::Storage.TaskManager.Task::(_decref)=20= Task=3D`972c09c0-53e2-4b6a-883e-df0b87559c29`::ref=200=20aborting=20= False=0Ajsonrpc.Executor/1::DEBUG::2016-04-04=20= 15:07:55,597::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'StoragePool.disconnectStorageServer'=20in=20bridge=20with=20= [{'status':=20477,=20'id':=20u'b8287ae9-9831-4e4a-ad5d-72d3d064d973'}]=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,644::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'StoragePool.disconnectStorageServer'=20in=20bridge=20with=20= {u'connectionParams':=20[{u'password':=20'********',=20u'id':=20= u'5ea6a340-8e8b-43f5-b7a3-e9786429d968',=20u'connection':=20= u'superstore235.svc.exam.org:ovirtp001',=20u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'154c63b8-eee1-4e6d-a801-d51bfc93b720',=20= u'connection':=20u'superstore235.svc.exam.org:ovirtd001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'c3fb1655-0e9e-4e5c-be26-411638a3700b',=20= u'connection':=20u'superstore235.svc.exam.org:ovirtt001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'79549c5d-2b6d-4ef7-94ff-c041170952a9',=20= u'connection':=20u'superstore235.svc.exam.org:ovirta001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'b2ea5cf8-4772-45c9-8ca4-e12c7bf3f6c7',=20= u'connection':=20u'superstore235.svc.exam.org:ovirtb001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''}],=20u'storagepoolID':=20= u'5498ceb7-80b1-4d8f-9e74-938478109289',=20u'domainType':=207}=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,645::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`7b579463-bb63-47d8-b750-a21de9233226`::moving=20from=20state=20= init=20->=20state=20preparing=0Ajsonrpc.Executor/2::INFO::2016-04-04=20= 15:07:55,645::logUtils::48::dispatcher::(wrapper)=20Run=20and=20protect:=20= disconnectStorageServer(domType=3D7,=20= spUUID=3Du'5498ceb7-80b1-4d8f-9e74-938478109289',=20= conList=3D[{u'password':=20'********',=20u'id':=20= u'5ea6a340-8e8b-43f5-b7a3-e9786429d968',=20u'connection':=20= u'superstore235.svc.exam.org:ovirtp001',=20u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'154c63b8-eee1-4e6d-a801-d51bfc93b720',=20= u'connection':=20u'superstore235.svc.exam.org:ovirtd001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'c3fb1655-0e9e-4e5c-be26-411638a3700b',=20= u'connection':=20u'superstore235.svc.exam.org:ovirtt001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'79549c5d-2b6d-4ef7-94ff-c041170952a9',=20= u'connection':=20u'superstore235.svc.exam.org:ovirta001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''},=20{u'password':=20= '********',=20u'id':=20u'b2ea5cf8-4772-45c9-8ca4-e12c7bf3f6c7',=20= u'connection':=20u'superstore235.svc.exam.org:ovirtb001',=20= u'mnt_options':=20= u'backupvolfile-server=3Dsuperstore236.svc.exam.org:superstore237.svc.exam= .org',=20u'user':=20u'',=20u'tpgt':=20u'1',=20u'vfs_type':=20= u'glusterfs',=20u'iqn':=20u'',=20u'port':=20u''}],=20options=3DNone)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,646::mount::229::Storage.Misc.excCmd::(_runcmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/bin/umount=20-f=20-l=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtp001=20= (cwd=20None)=0Ajsonrpc.Executor/2::ERROR::2016-04-04=20= 15:07:55,656::hsm::2557::Storage.HSM::(disconnectStorageServer)=20Could=20= not=20disconnect=20from=20storageServer=0ATraceback=20(most=20recent=20= call=20last):=0A=20=20File=20"/usr/share/vdsm/storage/hsm.py",=20line=20= 2553,=20in=20disconnectStorageServer=0A=20=20=20=20conObj.disconnect()=0A= =20=20File=20"/usr/share/vdsm/storage/storageServer.py",=20line=20258,=20= in=20disconnect=0A=20=20=20=20self._mount.umount(True,=20True)=0A=20=20= File=20"/usr/share/vdsm/storage/mount.py",=20line=20256,=20in=20umount=0A= =20=20=20=20return=20self._runcmd(cmd,=20timeout)=0A=20=20File=20= "/usr/share/vdsm/storage/mount.py",=20line=20241,=20in=20_runcmd=0A=20=20= =20=20raise=20MountError(rc,=20";".join((out,=20err)))=0AMountError:=20= (32,=20';umount:=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtp001:=20= mountpoint=20not=20found\n')=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,656::mount::229::Storage.Misc.excCmd::(_runcmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/bin/umount=20-f=20-l=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtd001=20= (cwd=20None)=0Ajsonrpc.Executor/2::ERROR::2016-04-04=20= 15:07:55,665::hsm::2557::Storage.HSM::(disconnectStorageServer)=20Could=20= not=20disconnect=20from=20storageServer=0ATraceback=20(most=20recent=20= call=20last):=0A=20=20File=20"/usr/share/vdsm/storage/hsm.py",=20line=20= 2553,=20in=20disconnectStorageServer=0A=20=20=20=20conObj.disconnect()=0A= =20=20File=20"/usr/share/vdsm/storage/storageServer.py",=20line=20258,=20= in=20disconnect=0A=20=20=20=20self._mount.umount(True,=20True)=0A=20=20= File=20"/usr/share/vdsm/storage/mount.py",=20line=20256,=20in=20umount=0A= =20=20=20=20return=20self._runcmd(cmd,=20timeout)=0A=20=20File=20= "/usr/share/vdsm/storage/mount.py",=20line=20241,=20in=20_runcmd=0A=20=20= =20=20raise=20MountError(rc,=20";".join((out,=20err)))=0AMountError:=20= (32,=20';umount:=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtd001:=20= mountpoint=20not=20found\n')=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,666::mount::229::Storage.Misc.excCmd::(_runcmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/bin/umount=20-f=20-l=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtt001=20= (cwd=20None)=0Ajsonrpc.Executor/2::ERROR::2016-04-04=20= 15:07:55,675::hsm::2557::Storage.HSM::(disconnectStorageServer)=20Could=20= not=20disconnect=20from=20storageServer=0ATraceback=20(most=20recent=20= call=20last):=0A=20=20File=20"/usr/share/vdsm/storage/hsm.py",=20line=20= 2553,=20in=20disconnectStorageServer=0A=20=20=20=20conObj.disconnect()=0A= =20=20File=20"/usr/share/vdsm/storage/storageServer.py",=20line=20258,=20= in=20disconnect=0A=20=20=20=20self._mount.umount(True,=20True)=0A=20=20= File=20"/usr/share/vdsm/storage/mount.py",=20line=20256,=20in=20umount=0A= =20=20=20=20return=20self._runcmd(cmd,=20timeout)=0A=20=20File=20= "/usr/share/vdsm/storage/mount.py",=20line=20241,=20in=20_runcmd=0A=20=20= =20=20raise=20MountError(rc,=20";".join((out,=20err)))=0AMountError:=20= (32,=20';umount:=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtt001:=20= mountpoint=20not=20found\n')=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,676::mount::229::Storage.Misc.excCmd::(_runcmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/bin/umount=20-f=20-l=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirta001=20= (cwd=20None)=0Ajsonrpc.Executor/2::ERROR::2016-04-04=20= 15:07:55,685::hsm::2557::Storage.HSM::(disconnectStorageServer)=20Could=20= not=20disconnect=20from=20storageServer=0ATraceback=20(most=20recent=20= call=20last):=0A=20=20File=20"/usr/share/vdsm/storage/hsm.py",=20line=20= 2553,=20in=20disconnectStorageServer=0A=20=20=20=20conObj.disconnect()=0A= =20=20File=20"/usr/share/vdsm/storage/storageServer.py",=20line=20258,=20= in=20disconnect=0A=20=20=20=20self._mount.umount(True,=20True)=0A=20=20= File=20"/usr/share/vdsm/storage/mount.py",=20line=20256,=20in=20umount=0A= =20=20=20=20return=20self._runcmd(cmd,=20timeout)=0A=20=20File=20= "/usr/share/vdsm/storage/mount.py",=20line=20241,=20in=20_runcmd=0A=20=20= =20=20raise=20MountError(rc,=20";".join((out,=20err)))=0AMountError:=20= (32,=20';umount:=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirta001:=20= mountpoint=20not=20found\n')=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,685::mount::229::Storage.Misc.excCmd::(_runcmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/bin/umount=20-f=20-l=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtb001=20= (cwd=20None)=0Ajsonrpc.Executor/2::ERROR::2016-04-04=20= 15:07:55,694::hsm::2557::Storage.HSM::(disconnectStorageServer)=20Could=20= not=20disconnect=20from=20storageServer=0ATraceback=20(most=20recent=20= call=20last):=0A=20=20File=20"/usr/share/vdsm/storage/hsm.py",=20line=20= 2553,=20in=20disconnectStorageServer=0A=20=20=20=20conObj.disconnect()=0A= =20=20File=20"/usr/share/vdsm/storage/storageServer.py",=20line=20258,=20= in=20disconnect=0A=20=20=20=20self._mount.umount(True,=20True)=0A=20=20= File=20"/usr/share/vdsm/storage/mount.py",=20line=20256,=20in=20umount=0A= =20=20=20=20return=20self._runcmd(cmd,=20timeout)=0A=20=20File=20= "/usr/share/vdsm/storage/mount.py",=20line=20241,=20in=20_runcmd=0A=20=20= =20=20raise=20MountError(rc,=20";".join((out,=20err)))=0AMountError:=20= (32,=20';umount:=20= /rhev/data-center/mnt/glusterSD/superstore235.svc.exam.org:ovirtb001:=20= mountpoint=20not=20found\n')=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,694::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.sdc.refreshStorage)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,694::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,694::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.iscsi.rescan)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,694::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,694::iscsi::434::Storage.ISCSI::(rescan)=20Performing=20SCSI=20= scan,=20this=20will=20take=20up=20to=2030=20seconds=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,695::iscsiadm::97::Storage.Misc.excCmd::(_runCmd)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /sbin/iscsiadm=20-m=20session=20-R=20(cwd=20None)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,703::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,703::misc::750::Storage.SamplingMethod::(__call__)=20Trying=20= to=20enter=20sampling=20method=20(storage.hba.rescan)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,703::misc::753::Storage.SamplingMethod::(__call__)=20Got=20in=20= to=20sampling=20method=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,703::hba::56::Storage.HBA::(rescan)=20Starting=20scan=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,751::hba::62::Storage.HBA::(rescan)=20Scan=20finished=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,751::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,751::multipath::77::Storage.Misc.excCmd::(rescan)=20= /usr/bin/taskset=20--cpu-list=200-39=20/usr/bin/sudo=20-n=20= /usr/sbin/multipath=20(cwd=20None)=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,784::multipath::77::Storage.Misc.excCmd::(rescan)=20SUCCESS:=20= <err>=20=3D=20'';=20<rc>=20=3D=200=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,784::utils::671::root::(execCmd)=20/usr/bin/taskset=20= --cpu-list=200-39=20/sbin/udevadm=20settle=20--timeout=3D5=20(cwd=20= None)=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,789::utils::689::root::(execCmd)=20SUCCESS:=20<err>=20=3D=20'';=20= <rc>=20=3D=200=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,789::lvm::497::Storage.OperationMutex::(_invalidateAllPvs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::lvm::499::Storage.OperationMutex::(_invalidateAllPvs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::lvm::508::Storage.OperationMutex::(_invalidateAllVgs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::lvm::510::Storage.OperationMutex::(_invalidateAllVgs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::lvm::528::Storage.OperationMutex::(_invalidateAllLvs)=20= Operation=20'lvm=20invalidate=20operation'=20got=20the=20operation=20= mutex=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::lvm::530::Storage.OperationMutex::(_invalidateAllLvs)=20= Operation=20'lvm=20invalidate=20operation'=20released=20the=20operation=20= mutex=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::misc::760::Storage.SamplingMethod::(__call__)=20Returning=20= last=20result=0Ajsonrpc.Executor/2::INFO::2016-04-04=20= 15:07:55,790::logUtils::51::dispatcher::(wrapper)=20Run=20and=20protect:=20= disconnectStorageServer,=20Return=20response:=20{'statuslist':=20= [{'status':=20477,=20'id':=20u'5ea6a340-8e8b-43f5-b7a3-e9786429d968'},=20= {'status':=20477,=20'id':=20u'154c63b8-eee1-4e6d-a801-d51bfc93b720'},=20= {'status':=20477,=20'id':=20u'c3fb1655-0e9e-4e5c-be26-411638a3700b'},=20= {'status':=20477,=20'id':=20u'79549c5d-2b6d-4ef7-94ff-c041170952a9'},=20= {'status':=20477,=20'id':=20u'b2ea5cf8-4772-45c9-8ca4-e12c7bf3f6c7'}]}=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::task::1191::Storage.TaskManager.Task::(prepare)=20= Task=3D`7b579463-bb63-47d8-b750-a21de9233226`::finished:=20= {'statuslist':=20[{'status':=20477,=20'id':=20= u'5ea6a340-8e8b-43f5-b7a3-e9786429d968'},=20{'status':=20477,=20'id':=20= u'154c63b8-eee1-4e6d-a801-d51bfc93b720'},=20{'status':=20477,=20'id':=20= u'c3fb1655-0e9e-4e5c-be26-411638a3700b'},=20{'status':=20477,=20'id':=20= u'79549c5d-2b6d-4ef7-94ff-c041170952a9'},=20{'status':=20477,=20'id':=20= u'b2ea5cf8-4772-45c9-8ca4-e12c7bf3f6c7'}]}=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::task::595::Storage.TaskManager.Task::(_updateState)=20= Task=3D`7b579463-bb63-47d8-b750-a21de9233226`::moving=20from=20state=20= preparing=20->=20state=20finished=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::resourceManager::943::Storage.ResourceManager.Owner::(releas= eAll)=20Owner.releaseAll=20requests=20{}=20resources=20{}=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,790::resourceManager::980::Storage.ResourceManager.Owner::(cancel= All)=20Owner.cancelAll=20requests=20{}=0A= jsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,791::task::993::Storage.TaskManager.Task::(_decref)=20= Task=3D`7b579463-bb63-47d8-b750-a21de9233226`::ref=200=20aborting=20= False=0Ajsonrpc.Executor/2::DEBUG::2016-04-04=20= 15:07:55,791::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'StoragePool.disconnectStorageServer'=20in=20bridge=20with=20= [{'status':=20477,=20'id':=20u'5ea6a340-8e8b-43f5-b7a3-e9786429d968'},=20= {'status':=20477,=20'id':=20u'154c63b8-eee1-4e6d-a801-d51bfc93b720'},=20= {'status':=20477,=20'id':=20u'c3fb1655-0e9e-4e5c-be26-411638a3700b'},=20= {'status':=20477,=20'id':=20u'79549c5d-2b6d-4ef7-94ff-c041170952a9'},=20= {'status':=20477,=20'id':=20u'b2ea5cf8-4772-45c9-8ca4-e12c7bf3f6c7'}]=0A= Reactor=20thread::INFO::2016-04-04=20= 15:07:59,487::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37258=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:07:59,492::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:07:59,492::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37258=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:07:59,492::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37258=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:07:59,493::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037258)=0A= Thread-13::INFO::2016-04-04=20= 15:07:59,493::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37258=20started=0A= Thread-13::INFO::2016-04-04=20= 15:07:59,494::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37258=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:07:59,607::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37259=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:07:59,612::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:07:59,612::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37259=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:07:59,612::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37259=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:07:59,613::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037259)=0A= Thread-14::INFO::2016-04-04=20= 15:07:59,613::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37259=20started=0A= Thread-14::INFO::2016-04-04=20= 15:07:59,619::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37259=20stopped=0AJsonRpc=20= (StompReactor)::ERROR::2016-04-04=20= 15:08:01,595::betterAsyncore::124::vds.dispatcher::(recv)=20SSL=20error=20= during=20reading=20data:=20unexpected=20eof=0AReactor=20= thread::INFO::2016-04-04=20= 15:08:01,595::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=2010.149.133.23:57193=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:01,662::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:08:01,663::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20stomp=20from=2010.149.133.23:57193=0AReactor=20= thread::INFO::2016-04-04=20= 15:08:01,663::stompreactor::101::Broker.StompAdapter::(_cmd_connect)=20= Processing=20CONNECT=20request=0AJsonRpc=20= (StompReactor)::INFO::2016-04-04=20= 15:08:01,664::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe)=20= Subscribe=20command=20received=0AJsonRpc=20= (StompReactor)::INFO::2016-04-04=20= 15:08:01,664::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe)=20= Subscribe=20command=20received=0AReactor=20thread::DEBUG::2016-04-04=20= 15:08:01,664::stompreactor::470::protocoldetector.StompDetector::(handle_s= ocket)=20Stomp=20detected=20from=20('10.149.133.23',=2057193)=0A= jsonrpc.Executor/4::DEBUG::2016-04-04=20= 15:08:02,086::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest)=20= Calling=20'Host.setupNetworks'=20in=20bridge=20with=20{u'bondings':=20= {u'bond0':=20{u'nics':=20[u'eno1',=20u'eno2'],=20u'options':=20= u'miimon=3D100=20mode=3D4'}},=20u'networks':=20{u'ovirtmgmt':=20{u'nic':=20= u'ens1f0',=20u'custom':=20{},=20u'bridged':=20u'false',=20u'mtu':=20= u'1500'},=20u'VLAN602':=20{u'custom':=20{},=20u'bonding':=20u'bond0',=20= u'vlan':=20u'602',=20u'bridged':=20u'false',=20u'mtu':=20u'1500'}},=20= u'options':=20{u'connectivityCheck':=20u'false'}}=0A= jsonrpc.Executor/4::INFO::2016-04-04=20= 15:08:02,087::API::1489::vds::(setupNetworks)=20API=20setupNetworks=20= with:=20networks:=20{u'ovirtmgmt':=20{u'nic':=20u'ens1f0',=20u'custom':=20= {},=20u'bridged':=20u'false',=20u'mtu':=20u'1500'},=20u'VLAN602':=20= {u'custom':=20{},=20u'bonding':=20u'bond0',=20u'vlan':=20u'602',=20= u'bridged':=20u'false',=20u'mtu':=20u'1500'}},=20bondings:=20{u'bond0':=20= {u'nics':=20[u'eno1',=20u'eno2'],=20u'options':=20u'miimon=3D100=20= mode=3D4'}},=20options:=20{u'connectivityCheck':=20u'false'}=0A= jsonrpc.Executor/4::DEBUG::2016-04-04=20= 15:08:10,622::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest)=20= Return=20'Host.setupNetworks'=20in=20bridge=20with=20{'message':=20= 'Done',=20'code':=200}=0AReactor=20thread::INFO::2016-04-04=20= 15:08:14,510::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37260=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:14,515::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:08:14,515::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37260=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:14,515::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037260)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:08:14,515::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37260=0AThread-15::INFO::2016-04-04=20= 15:08:14,515::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37260=20started=0A= Thread-15::INFO::2016-04-04=20= 15:08:14,517::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37260=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:08:29,533::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37261=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:29,537::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:08:29,537::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37261=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:29,537::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037261)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:08:29,537::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37261=0AThread-16::INFO::2016-04-04=20= 15:08:29,538::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37261=20started=0A= Thread-16::INFO::2016-04-04=20= 15:08:29,539::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37261=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:08:44,554::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37262=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:44,559::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:08:44,559::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37262=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:44,559::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037262)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:08:44,559::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37262=0AThread-17::INFO::2016-04-04=20= 15:08:44,560::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37262=20started=0A= Thread-17::INFO::2016-04-04=20= 15:08:44,561::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37262=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:08:59,577::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37263=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:59,581::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:08:59,581::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37263=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:08:59,581::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037263)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:08:59,581::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37263=0AThread-18::INFO::2016-04-04=20= 15:08:59,582::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37263=20started=0A= Thread-18::INFO::2016-04-04=20= 15:08:59,583::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37263=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:09:14,599::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37264=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:14,603::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:09:14,603::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37264=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:14,604::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037264)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:09:14,604::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37264=0AThread-19::INFO::2016-04-04=20= 15:09:14,604::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37264=20started=0A= Thread-19::INFO::2016-04-04=20= 15:09:14,605::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37264=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:09:29,621::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37265=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:29,625::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:09:29,625::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37265=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:29,625::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037265)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:09:29,626::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37265=0AThread-20::INFO::2016-04-04=20= 15:09:29,626::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37265=20started=0A= Thread-20::INFO::2016-04-04=20= 15:09:29,627::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37265=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:09:44,643::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37266=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:44,647::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:09:44,647::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37266=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:44,647::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037266)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:09:44,647::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37266=0AThread-21::INFO::2016-04-04=20= 15:09:44,647::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37266=20started=0A= Thread-21::INFO::2016-04-04=20= 15:09:44,648::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37266=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:09:59,664::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37267=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:59,668::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:09:59,669::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37267=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:09:59,669::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037267)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:09:59,669::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37267=0AThread-22::INFO::2016-04-04=20= 15:09:59,669::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37267=20started=0A= Thread-22::INFO::2016-04-04=20= 15:09:59,670::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37267=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:10:14,686::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37268=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:14,690::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:10:14,690::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37268=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:14,691::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037268)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:10:14,691::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37268=0AThread-23::INFO::2016-04-04=20= 15:10:14,691::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37268=20started=0A= Thread-23::INFO::2016-04-04=20= 15:10:14,692::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37268=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:10:29,708::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37269=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:29,712::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:10:29,712::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37269=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:29,712::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037269)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:10:29,712::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37269=0AThread-24::INFO::2016-04-04=20= 15:10:29,713::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37269=20started=0A= Thread-24::INFO::2016-04-04=20= 15:10:29,714::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37269=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:10:44,730::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37270=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:44,734::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:10:44,734::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37270=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:44,734::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037270)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:10:44,735::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37270=0AThread-25::INFO::2016-04-04=20= 15:10:44,735::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37270=20started=0A= Thread-25::INFO::2016-04-04=20= 15:10:44,736::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37270=20stopped=0AReactor=20= thread::INFO::2016-04-04=20= 15:10:59,752::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle= _accept)=20Accepting=20connection=20from=20127.0.0.1:37271=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:59,756::protocoldetector::82::ProtocolDetector.Detector::(__init__)=20= Using=20required_size=3D11=0AReactor=20thread::INFO::2016-04-04=20= 15:10:59,756::protocoldetector::118::ProtocolDetector.Detector::(handle_re= ad)=20Detected=20protocol=20xml=20from=20127.0.0.1:37271=0AReactor=20= thread::DEBUG::2016-04-04=20= 15:10:59,757::bindingxmlrpc::1298::XmlDetector::(handle_socket)=20xml=20= over=20http=20detected=20from=20('127.0.0.1',=2037271)=0A= BindingXMLRPC::INFO::2016-04-04=20= 15:10:59,757::xmlrpc::73::vds.XMLRPCServer::(handle_request)=20Starting=20= request=20handler=20for=20127.0.0.1:37271=0AThread-26::INFO::2016-04-04=20= 15:10:59,757::xmlrpc::84::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37271=20started=0A= Thread-26::INFO::2016-04-04=20= 15:10:59,758::xmlrpc::92::vds.XMLRPCServer::(_process_requests)=20= Request=20handler=20for=20127.0.0.1:37271=20stopped=0A= --Apple-Mail=_B565CEDD-E856-4748-912B-7B9679AB82CE Content-Transfer-Encoding: quoted-printable Content-Type: text/plain; charset=us-ascii ->Rein. proprietary information of Blackboard that is for the sole use of the = intended recipient. If you are not the intended recipient, disclosure, = copying, re-distribution or other use of any of this information is = strictly prohibited. Please immediately notify the sender and delete = this transmission if you received this email in error. --Apple-Mail=_B565CEDD-E856-4748-912B-7B9679AB82CE--