Attachment 1448290 Details for Bug 1587962 – VDSM log

VDSM log

ovs-db-sock-vdsm.log (text/plain), 25.49 KB, created by Leon Goldberg on 2018-06-06 11:13:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Leon Goldberg

Created: 2018-06-06 11:13:07 UTC

Size: 25.49 KB

patch

obsolete

>2018-06-06 06:38:12,732-0400 INFO  (MainThread) [vds] (PID: 2843) I am the actual vdsm 4.30.0-405.git7b1e239.el7 lago-network-suite-master-host-2 (3.10.0-862.2.3.el7.x86_64) (vdsmd:149)
>2018-06-06 06:38:12,733-0400 INFO  (MainThread) [vds] VDSM will run with cpu affinity: frozenset([1]) (vdsmd:262)
>2018-06-06 06:38:12,738-0400 INFO  (MainThread) [storage.HSM] START HSM init (hsm:366)
>2018-06-06 06:38:12,743-0400 INFO  (MainThread) [storage.HSM] Creating data-center mount directory '/rhev/data-center/mnt' (hsm:373)
>2018-06-06 06:38:12,743-0400 INFO  (MainThread) [storage.fileUtils] Creating directory: /rhev/data-center/mnt mode: None (fileUtils:199)
>2018-06-06 06:38:12,857-0400 INFO  (MainThread) [storage.udev] Registering multipath event monitor <vdsm.storage.mpathhealth.Monitor object at 0x7f19b62aed50> (udev:182)
>2018-06-06 06:38:12,857-0400 INFO  (MainThread) [storage.udev] Starting multipath event listener (udev:116)
>2018-06-06 06:38:12,891-0400 INFO  (MainThread) [storage.check] Starting check service (check:91)
>2018-06-06 06:38:12,899-0400 INFO  (MainThread) [storage.Dispatcher] Starting StorageDispatcher... (dispatcher:48)
>2018-06-06 06:38:12,900-0400 INFO  (check/loop) [storage.asyncevent] Starting <EventLoop running=True closed=False at 0x139748373333072> (asyncevent:125)
>2018-06-06 06:38:12,925-0400 INFO  (MainThread) [vdsm.api] START registerDomainStateChangeCallback(callbackFunc=<functools.partial object at 0x7f19b428c890>) from=internal, task_id=a8e8e4b8-4697-4af2-a506-74155fac5294 (api:47)
>2018-06-06 06:38:12,925-0400 INFO  (MainThread) [vdsm.api] FINISH registerDomainStateChangeCallback return=None from=internal, task_id=a8e8e4b8-4697-4af2-a506-74155fac5294 (api:53)
>2018-06-06 06:38:12,926-0400 INFO  (MainThread) [MOM] Preparing MOM interface (momIF:46)
>2018-06-06 06:38:12,926-0400 INFO  (MainThread) [MOM] MOM: named unix socket /var/run/vdsm/mom-vdsm.sock missing, not available (momIF:61)
>2018-06-06 06:38:12,926-0400 INFO  (MainThread) [root] Unregistering all secrets (secret:92)
>2018-06-06 06:38:12,963-0400 INFO  (MainThread) [vds] Setting channels' timeout to 30 seconds. (vmchannels:223)
>2018-06-06 06:38:12,967-0400 INFO  (MainThread) [vds] Starting QEMU-GA poller (qemuguestagent:141)
>2018-06-06 06:38:12,969-0400 INFO  (MainThread) [vds.MultiProtocolAcceptor] Listening at :::54321 (protocoldetector:183)
>2018-06-06 06:38:13,016-0400 INFO  (http) [vds] Server running (http:59)
>2018-06-06 06:38:13,049-0400 INFO  (vmrecovery) [vds] recovery: completed in 0s (clientIF:614)
>2018-06-06 06:38:13,052-0400 INFO  (periodic/0) [vdsm.api] START repoStats(domains=()) from=internal, task_id=ccfec7a4-2b23-48aa-85a5-2141195ff5fb (api:47)
>2018-06-06 06:38:13,053-0400 INFO  (periodic/0) [vdsm.api] FINISH repoStats return={} from=internal, task_id=ccfec7a4-2b23-48aa-85a5-2141195ff5fb (api:53)
>2018-06-06 06:38:13,053-0400 WARN  (periodic/0) [throttled] MOM not available. (throttledlog:103)
>2018-06-06 06:38:13,054-0400 WARN  (periodic/0) [throttled] MOM not available, KSM stats will be missing. (throttledlog:103)
>2018-06-06 06:38:13,378-0400 INFO  (hsm/init) [storage.HSM] FINISH HSM init succeeded in 0.64 seconds (hsm:410)
>2018-06-06 06:38:18,327-0400 INFO  (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::1:57266 (protocoldetector:61)
>2018-06-06 06:38:18,343-0400 INFO  (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::1:57266 (protocoldetector:125)
>2018-06-06 06:38:18,343-0400 INFO  (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompserver:94)
>2018-06-06 06:38:18,346-0400 INFO  (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompserver:123)
>2018-06-06 06:38:18,386-0400 INFO  (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:311)
>2018-06-06 06:38:18,418-0400 INFO  (jsonrpc/1) [api.host] START getAllVmStats() from=::1,57266 (api:47)
>2018-06-06 06:38:18,419-0400 INFO  (jsonrpc/1) [throttled] Current getAllVmStats: {} (throttledlog:103)
>2018-06-06 06:38:18,419-0400 INFO  (jsonrpc/1) [api.host] FINISH getAllVmStats return={'status': {'message': 'Done', 'code': 0}, 'statsList': (suppressed)} from=::1,57266 (api:53)
>2018-06-06 06:38:18,419-0400 INFO  (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:311)
>2018-06-06 06:38:28,228-0400 INFO  (periodic/0) [vdsm.api] START repoStats(domains=()) from=internal, task_id=66b85fe0-676c-475e-83ad-7b7367bd705b (api:47)
>2018-06-06 06:38:28,229-0400 INFO  (periodic/0) [vdsm.api] FINISH repoStats return={} from=internal, task_id=66b85fe0-676c-475e-83ad-7b7367bd705b (api:53)
>2018-06-06 06:38:33,451-0400 INFO  (jsonrpc/2) [api.host] START getAllVmStats() from=::1,57266 (api:47)
>2018-06-06 06:38:33,451-0400 INFO  (jsonrpc/2) [api.host] FINISH getAllVmStats return={'status': {'message': 'Done', 'code': 0}, 'statsList': (suppressed)} from=::1,57266 (api:53)
>2018-06-06 06:38:33,451-0400 INFO  (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:311)
>2018-06-06 06:38:33,577-0400 INFO  (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.setKsmTune succeeded in 0.01 seconds (__init__:311)
>2018-06-06 06:38:37,032-0400 INFO  (Reactor thread) [ProtocolDetector.AcceptorImpl] Accepted connection from ::ffff:192.168.201.2:35392 (protocoldetector:61)
>2018-06-06 06:38:37,096-0400 INFO  (Reactor thread) [ProtocolDetector.Detector] Detected protocol stomp from ::ffff:192.168.201.2:35392 (protocoldetector:125)
>2018-06-06 06:38:37,096-0400 INFO  (Reactor thread) [Broker.StompAdapter] Processing CONNECT request (stompserver:94)
>2018-06-06 06:38:37,098-0400 INFO  (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompserver:123)
>2018-06-06 06:38:37,099-0400 INFO  (JsonRpc (StompReactor)) [Broker.StompAdapter] Subscribe command received (stompserver:123)
>2018-06-06 06:38:37,138-0400 INFO  (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:311)
>2018-06-06 06:38:37,147-0400 INFO  (jsonrpc/5) [api.host] START getCapabilities() from=::ffff:192.168.201.2,35392, flow_id=1dd5b69a (api:47)
>2018-06-06 06:38:38,392-0400 INFO  (jsonrpc/5) [root] /usr/libexec/vdsm/hooks/after_get_caps/50_openstacknet: rc=0 err= (hooks:113)
>2018-06-06 06:38:38,650-0400 INFO  (jsonrpc/5) [root] /usr/libexec/vdsm/hooks/after_get_caps/openstacknet_utils.py: rc=0 err= (hooks:113)
>2018-06-06 06:38:38,966-0400 INFO  (jsonrpc/5) [root] /usr/libexec/vdsm/hooks/after_get_caps/ovirt_provider_ovn_hook: rc=0 err=Failed to get Open VSwitch system-id . err = ['ovs-vsctl: unix:/var/run/openvswitch/db.sock: database connection failed (No such file or directory)']
> (hooks:113)
>2018-06-06 06:38:38,968-0400 INFO  (jsonrpc/5) [api.host] FINISH getCapabilities return={'status': {'message': 'Done', 'code': 0}, 'info': {u'HBAInventory': {u'iSCSI': [{u'InitiatorName': u'iqn.2014-07.org.lago:lago-network-suite-master-host-2:lago-network-suite-master-host-2'}], u'FC': []}, u'packages2': {u'kernel': {u'release': u'862.2.3.el7.x86_64', u'version': u'3.10.0'}, u'spice-server': {u'release': u'2.el7_5.3', u'version': u'0.14.0'}, u'librbd1': {u'release': u'2.el7', u'version': u'0.94.5'}, u'vdsm': {u'release': u'405.git7b1e239.el7', u'version': u'4.30.0'}, u'qemu-kvm': {u'release': u'21.el7_5.3.1', u'version': u'2.10.0'}, u'openvswitch': {u'release': u'3.el7', u'version': u'2.9.0'}, u'libvirt': {u'release': u'14.el7_5.5', u'version': u'3.9.0'}, u'ovirt-hosted-engine-ha': {u'release': u'0.0.master.20180530122843.20180530122840.git2e859f1.el7', u'version': u'2.3.0'}, u'qemu-img': {u'release': u'21.el7_5.3.1', u'version': u'2.10.0'}, u'mom': {u'release': u'1.el7', u'version': u'0.5.12'}, u'glusterfs-cli': {u'release': u'1.el7', u'version': u'3.12.9'}}, u'numaNodeDistance': {u'1': [20, 10], u'0': [10, 20]}, u'cpuModel': u'Intel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz', u'nestedVirtualization': False, u'liveMerge': u'true', u'hooks': {u'after_vm_start': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'ea0a5a715da8c1badbcda28e8b8fa00e'}}, u'after_network_setup': {u'30_ethtool_options': {u'md5': u'f04c2ca5dce40663e2ed69806eea917c'}}, u'before_vm_start': {u'50_hostedengine': {u'md5': u'95c810cdcfe4195302a59574a5148289'}, u'50_vhostmd': {u'md5': u'9206bc390bcbf208b06a8e899581be2d'}, u'50_vfio_mdev': {u'md5': u'b77eaad7b8f5ca3825dca1b38b0dd446'}}, u'after_device_migrate_destination': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'f93c12af7454bbba4c1ef445c2bc9860'}}, u'before_device_migrate_destination': {u'50_vmfex': {u'md5': u'49caba1a5faadd8efacef966f79bc30a'}}, u'before_device_create': {u'50_vmfex': {u'md5': u'49caba1a5faadd8efacef966f79bc30a'}, u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'0438de5ff9b6bf8d3160804ff71bf827'}, u'ovirt_provider_ovn_hook': {u'md5': u'ada7e4e757fd0241b682b5cc6a545fc8'}}, u'after_device_create': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'f93c12af7454bbba4c1ef445c2bc9860'}}, u'after_nic_hotplug': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'f93c12af7454bbba4c1ef445c2bc9860'}}, u'before_vm_migrate_destination': {u'50_vhostmd': {u'md5': u'9206bc390bcbf208b06a8e899581be2d'}}, u'before_vm_dehibernate': {u'50_vhostmd': {u'md5': u'9206bc390bcbf208b06a8e899581be2d'}}, u'before_nic_hotplug': {u'50_vmfex': {u'md5': u'49caba1a5faadd8efacef966f79bc30a'}, u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'0438de5ff9b6bf8d3160804ff71bf827'}, u'ovirt_provider_ovn_hook': {u'md5': u'ada7e4e757fd0241b682b5cc6a545fc8'}}, u'after_get_caps': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'0e00c63ab44a952e722209ea31fd7a71'}, u'ovirt_provider_ovn_hook': {u'md5': u'257990644ee6c7824b522e2ab3077ae7'}}, u'before_network_setup': {u'50_fcoe': {u'md5': u'28c352339c8beef1e1b05c67d106d062'}}, u'after_vm_destroy': {u'50_vhostmd': {u'md5': u'd70f7ee0453f632e87c09a157ff8ff66'}, u'50_vfio_mdev': {u'md5': u'35364c6e08ecc58410e31af6c73b17f6'}}}, u'supportsIPv6': True, u'realtimeKernel': False, u'vmTypes': [u'kvm'], u'liveSnapshot': u'true', u'cpuThreads': u'2', u'kdumpStatus': 0, u'networks': {}, u'kernelArgs': u'BOOT_IMAGE=/vmlinuz-3.10.0-862.2.3.el7.x86_64 root=UUID=548a847d-dc36-462c-b6e0-3b3578098519 ro console=tty0 rd_NO_PLYMOUTH crashkernel=auto console=ttyS0,115200 LANG=en_US.UTF-8', u'bridges': {}, u'uuid': u'F49116CE-B695-42AC-B5F8-4602AD7201C5', u'onlineCpus': u'0,1', u'nameservers': [u'192.168.201.1'], u'nics': {u'eth3': {u'ipv6autoconf': True, u'addr': u'192.168.200.7', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.200.7/24'], u'hwaddr': u'54:52:c0:a8:c8:07', u'ipv6gateway': u'::', u'gateway': u'192.168.200.1'}, u'eth2': {u'ipv6autoconf': True, u'addr': u'192.168.200.6', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.200.6/24'], u'hwaddr': u'54:52:c0:a8:c8:06', u'ipv6gateway': u'::', u'gateway': u'192.168.200.1'}, u'eth1': {u'ipv6autoconf': True, u'addr': u'192.168.202.5', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.202.5/24'], u'hwaddr': u'54:52:c0:a8:ca:05', u'ipv6gateway': u'::', u'gateway': u'192.168.202.1'}, u'eth0': {u'ipv6autoconf': True, u'addr': u'192.168.201.5', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.201.5/24'], u'hwaddr': u'54:52:c0:a8:c9:05', u'ipv6gateway': u'fe80::5054:ff:fe3c:c64c', u'gateway': u'192.168.201.1'}}, u'software_revision': u'405', u'hostdevPassthrough': u'false', u'clusterLevels': [u'3.6', u'4.0', u'4.1', u'4.2', u'4.3'], u'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,mmx,fxsr,sse,sse2,ss,syscall,nx,pdpe1gb,rdtscp,lm,constant_tsc,arch_perfmon,rep_good,nopl,xtopology,eagerfpu,pni,pclmulqdq,vmx,ssse3,fma,cx16,pcid,sse4_1,sse4_2,x2apic,movbe,popcnt,tsc_deadline_timer,aes,xsave,avx,f16c,rdrand,hypervisor,lahf_lm,abm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi2,erms,invpcid,xsaveopt,ibpb,ibrs,arat,spec_ctrl,model_SandyBridge-IBRS,model_Nehalem,model_Haswell-noTSX,model_Westmere-IBRS,model_Haswell-noTSX-IBRS,model_Nehalem-IBRS,model_SandyBridge,model_IvyBridge,model_Penryn,model_IvyBridge-IBRS,model_Westmere,model_Conroe', u'kernelFeatures': {u'RETP': 1, u'IBRS': 0, u'PTI': 1}, u'ISCSIInitiatorName': u'iqn.2014-07.org.lago:lago-network-suite-master-host-2:lago-network-suite-master-host-2', u'netConfigDirty': u'False', u'selinux': {u'mode': u'1'}, u'autoNumaBalancing': 1, u'reservedMem': u'321', u'containers': False, u'bondings': {}, u'software_version': u'4.30', u'supportedENGINEs': [u'3.6', u'4.0', u'4.1', u'4.2', u'4.3'], u'cpuSpeed': u'2397.222', u'numaNodes': {u'1': {u'totalMemory': u'1022', u'cpus': [1]}, u'0': {u'totalMemory': u'1022', u'cpus': [0]}}, u'cpuSockets': u'2', u'vlans': {}, u'version_name': u'Snow Man', 'lastClientIface': 'eth0', u'cpuCores': u'2', u'hostedEngineDeployed': True, u'hugepages': [1048576, 2048], u'guestOverhead': u'65', u'additionalFeatures': [u'libgfapi_supported'], u'kvmEnabled': u'true', u'memSize': u'1836', u'emulatedMachines': [u'pc-i440fx-rhel7.1.0', u'pc-q35-rhel7.3.0', u'rhel6.3.0', u'pc-i440fx-rhel7.5.0', u'pc-i440fx-rhel7.0.0', u'rhel6.1.0', u'pc-i440fx-rhel7.4.0', u'rhel6.6.0', u'pc-q35-rhel7.5.0', u'rhel6.2.0', u'pc', u'pc-i440fx-rhel7.3.0', u'q35', u'pc-i440fx-rhel7.2.0', u'rhel6.4.0', u'pc-q35-rhel7.4.0', u'rhel6.0.0', u'rhel6.5.0'], u'rngSources': [u'hwrng', u'random'], u'operatingSystem': {u'release': u'5.1804.el7.centos', u'pretty_name': u'CentOS Linux 7 (Core)', u'version': u'7', u'name': u'RHEL'}}} from=::ffff:192.168.201.2,35392, flow_id=1dd5b69a (api:53)
>2018-06-06 06:38:38,981-0400 INFO  (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 1.83 seconds (__init__:311)
>2018-06-06 06:38:39,059-0400 INFO  (jsonrpc/6) [api.network] START setupNetworks(networks={u'ovirtmgmt': {u'ipv6autoconf': True, u'nic': u'eth0', u'mtu': 1500, u'switch': u'ovs', u'dhcpv6': False, u'STP': u'no', u'bridged': u'true', u'defaultRoute': True, u'bootproto': u'dhcp'}}, bondings={}, options={u'connectivityCheck': u'true', u'connectivityTimeout': 120}) from=::ffff:192.168.201.2,35392, flow_id=217a3930 (api:47)
>2018-06-06 06:38:39,063-0400 INFO  (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call Host.confirmConnectivity succeeded in 0.00 seconds (__init__:311)
>2018-06-06 06:38:39,575-0400 INFO  (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.confirmConnectivity succeeded in 0.00 seconds (__init__:311)
>2018-06-06 06:38:39,618-0400 ERROR (jsonrpc/6) [vds] ovs-vsctl: unix:/var/run/openvswitch/db.sock: database connection failed (No such file or directory) (API:1537)
>Traceback (most recent call last):
>  File "/usr/lib/python2.7/site-packages/vdsm/API.py", line 1534, in setupNetworks
>    supervdsm.getProxy().setupNetworks(networks, bondings, options)
>  File "/usr/lib/python2.7/site-packages/vdsm/common/supervdsm.py", line 56, in __call__
>    return callMethod()
>  File "/usr/lib/python2.7/site-packages/vdsm/common/supervdsm.py", line 54, in <lambda>
>    **kwargs)
>  File "<string>", line 2, in setupNetworks
>  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod
>    raise convert_to_error(kind, result)
>OvsDBConnectionError: (32, 'ovs-vsctl: unix:/var/run/openvswitch/db.sock: database connection failed (No such file or directory)')
>2018-06-06 06:38:39,625-0400 INFO  (jsonrpc/6) [api.network] FINISH setupNetworks return={'status': {'message': 'ovs-vsctl: unix:/var/run/openvswitch/db.sock: database connection failed (No such file or directory)', 'code': 32}} from=::ffff:192.168.201.2,35392, flow_id=217a3930 (api:53)
>2018-06-06 06:38:39,625-0400 INFO  (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.setupNetworks failed (error 32) in 0.57 seconds (__init__:311)
>2018-06-06 06:38:40,084-0400 INFO  (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.confirmConnectivity succeeded in 0.00 seconds (__init__:311)
>2018-06-06 06:38:42,965-0400 INFO  (jsonrpc/2) [api.host] START getCapabilities() from=::ffff:192.168.201.2,35392 (api:47)
>2018-06-06 06:38:43,242-0400 INFO  (periodic/1) [vdsm.api] START repoStats(domains=()) from=internal, task_id=443b3694-11f1-40d9-8db2-63a098e2e604 (api:47)
>2018-06-06 06:38:43,242-0400 INFO  (periodic/1) [vdsm.api] FINISH repoStats return={} from=internal, task_id=443b3694-11f1-40d9-8db2-63a098e2e604 (api:53)
>2018-06-06 06:38:43,394-0400 INFO  (jsonrpc/2) [root] /usr/libexec/vdsm/hooks/after_get_caps/50_openstacknet: rc=0 err= (hooks:113)
>2018-06-06 06:38:43,630-0400 INFO  (jsonrpc/2) [root] /usr/libexec/vdsm/hooks/after_get_caps/openstacknet_utils.py: rc=0 err= (hooks:113)
>2018-06-06 06:38:43,927-0400 INFO  (jsonrpc/2) [root] /usr/libexec/vdsm/hooks/after_get_caps/ovirt_provider_ovn_hook: rc=0 err=Failed to get Open VSwitch system-id . err = ['ovs-vsctl: unix:/var/run/openvswitch/db.sock: database connection failed (No such file or directory)']
> (hooks:113)
>2018-06-06 06:38:43,929-0400 INFO  (jsonrpc/2) [api.host] FINISH getCapabilities return={'status': {'message': 'Done', 'code': 0}, 'info': {u'HBAInventory': {u'iSCSI': [{u'InitiatorName': u'iqn.2014-07.org.lago:lago-network-suite-master-host-2:lago-network-suite-master-host-2'}], u'FC': []}, u'packages2': {u'kernel': {u'release': u'862.2.3.el7.x86_64', u'version': u'3.10.0'}, u'spice-server': {u'release': u'2.el7_5.3', u'version': u'0.14.0'}, u'librbd1': {u'release': u'2.el7', u'version': u'0.94.5'}, u'vdsm': {u'release': u'405.git7b1e239.el7', u'version': u'4.30.0'}, u'qemu-kvm': {u'release': u'21.el7_5.3.1', u'version': u'2.10.0'}, u'openvswitch': {u'release': u'3.el7', u'version': u'2.9.0'}, u'libvirt': {u'release': u'14.el7_5.5', u'version': u'3.9.0'}, u'ovirt-hosted-engine-ha': {u'release': u'0.0.master.20180530122843.20180530122840.git2e859f1.el7', u'version': u'2.3.0'}, u'qemu-img': {u'release': u'21.el7_5.3.1', u'version': u'2.10.0'}, u'mom': {u'release': u'1.el7', u'version': u'0.5.12'}, u'glusterfs-cli': {u'release': u'1.el7', u'version': u'3.12.9'}}, u'numaNodeDistance': {u'1': [20, 10], u'0': [10, 20]}, u'cpuModel': u'Intel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz', u'nestedVirtualization': False, u'liveMerge': u'true', u'hooks': {u'after_vm_start': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'ea0a5a715da8c1badbcda28e8b8fa00e'}}, u'after_network_setup': {u'30_ethtool_options': {u'md5': u'f04c2ca5dce40663e2ed69806eea917c'}}, u'before_vm_start': {u'50_hostedengine': {u'md5': u'95c810cdcfe4195302a59574a5148289'}, u'50_vhostmd': {u'md5': u'9206bc390bcbf208b06a8e899581be2d'}, u'50_vfio_mdev': {u'md5': u'b77eaad7b8f5ca3825dca1b38b0dd446'}}, u'after_device_migrate_destination': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'f93c12af7454bbba4c1ef445c2bc9860'}}, u'before_device_migrate_destination': {u'50_vmfex': {u'md5': u'49caba1a5faadd8efacef966f79bc30a'}}, u'before_device_create': {u'50_vmfex': {u'md5': u'49caba1a5faadd8efacef966f79bc30a'}, u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'0438de5ff9b6bf8d3160804ff71bf827'}, u'ovirt_provider_ovn_hook': {u'md5': u'ada7e4e757fd0241b682b5cc6a545fc8'}}, u'after_device_create': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'f93c12af7454bbba4c1ef445c2bc9860'}}, u'after_nic_hotplug': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'f93c12af7454bbba4c1ef445c2bc9860'}}, u'before_vm_migrate_destination': {u'50_vhostmd': {u'md5': u'9206bc390bcbf208b06a8e899581be2d'}}, u'before_vm_dehibernate': {u'50_vhostmd': {u'md5': u'9206bc390bcbf208b06a8e899581be2d'}}, u'before_nic_hotplug': {u'50_vmfex': {u'md5': u'49caba1a5faadd8efacef966f79bc30a'}, u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'0438de5ff9b6bf8d3160804ff71bf827'}, u'ovirt_provider_ovn_hook': {u'md5': u'ada7e4e757fd0241b682b5cc6a545fc8'}}, u'after_get_caps': {u'openstacknet_utils.py': {u'md5': u'640ef8b473cbc16b102c5ebf57ea533f'}, u'50_openstacknet': {u'md5': u'0e00c63ab44a952e722209ea31fd7a71'}, u'ovirt_provider_ovn_hook': {u'md5': u'257990644ee6c7824b522e2ab3077ae7'}}, u'before_network_setup': {u'50_fcoe': {u'md5': u'28c352339c8beef1e1b05c67d106d062'}}, u'after_vm_destroy': {u'50_vhostmd': {u'md5': u'd70f7ee0453f632e87c09a157ff8ff66'}, u'50_vfio_mdev': {u'md5': u'35364c6e08ecc58410e31af6c73b17f6'}}}, u'supportsIPv6': True, u'realtimeKernel': False, u'vmTypes': [u'kvm'], u'liveSnapshot': u'true', u'cpuThreads': u'2', u'kdumpStatus': 0, u'networks': {}, u'kernelArgs': u'BOOT_IMAGE=/vmlinuz-3.10.0-862.2.3.el7.x86_64 root=UUID=548a847d-dc36-462c-b6e0-3b3578098519 ro console=tty0 rd_NO_PLYMOUTH crashkernel=auto console=ttyS0,115200 LANG=en_US.UTF-8', u'bridges': {}, u'uuid': u'F49116CE-B695-42AC-B5F8-4602AD7201C5', u'onlineCpus': u'0,1', u'nameservers': [u'192.168.201.1'], u'nics': {u'eth3': {u'ipv6autoconf': True, u'addr': u'192.168.200.7', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.200.7/24'], u'hwaddr': u'54:52:c0:a8:c8:07', u'ipv6gateway': u'::', u'gateway': u'192.168.200.1'}, u'eth2': {u'ipv6autoconf': True, u'addr': u'192.168.200.6', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.200.6/24'], u'hwaddr': u'54:52:c0:a8:c8:06', u'ipv6gateway': u'::', u'gateway': u'192.168.200.1'}, u'eth1': {u'ipv6autoconf': True, u'addr': u'192.168.202.5', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.202.5/24'], u'hwaddr': u'54:52:c0:a8:ca:05', u'ipv6gateway': u'::', u'gateway': u'192.168.202.1'}, u'eth0': {u'ipv6autoconf': True, u'addr': u'192.168.201.5', u'speed': 0, u'dhcpv6': False, u'ipv6addrs': [], u'mtu': u'1500', u'dhcpv4': True, u'netmask': u'255.255.255.0', u'ipv4defaultroute': True, u'ipv4addrs': [u'192.168.201.5/24'], u'hwaddr': u'54:52:c0:a8:c9:05', u'ipv6gateway': u'fe80::5054:ff:fe3c:c64c', u'gateway': u'192.168.201.1'}}, u'software_revision': u'405', u'hostdevPassthrough': u'false', u'clusterLevels': [u'3.6', u'4.0', u'4.1', u'4.2', u'4.3'], u'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,mmx,fxsr,sse,sse2,ss,syscall,nx,pdpe1gb,rdtscp,lm,constant_tsc,arch_perfmon,rep_good,nopl,xtopology,eagerfpu,pni,pclmulqdq,vmx,ssse3,fma,cx16,pcid,sse4_1,sse4_2,x2apic,movbe,popcnt,tsc_deadline_timer,aes,xsave,avx,f16c,rdrand,hypervisor,lahf_lm,abm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi2,erms,invpcid,xsaveopt,ibpb,ibrs,arat,spec_ctrl,model_SandyBridge-IBRS,model_Nehalem,model_Haswell-noTSX,model_Westmere-IBRS,model_Haswell-noTSX-IBRS,model_Nehalem-IBRS,model_SandyBridge,model_IvyBridge,model_Penryn,model_IvyBridge-IBRS,model_Westmere,model_Conroe', u'kernelFeatures': {u'RETP': 1, u'IBRS': 0, u'PTI': 1}, u'ISCSIInitiatorName': u'iqn.2014-07.org.lago:lago-network-suite-master-host-2:lago-network-suite-master-host-2', u'netConfigDirty': u'True', u'selinux': {u'mode': u'1'}, u'autoNumaBalancing': 1, u'reservedMem': u'321', u'containers': False, u'bondings': {}, u'software_version': u'4.30', u'supportedENGINEs': [u'3.6', u'4.0', u'4.1', u'4.2', u'4.3'], u'cpuSpeed': u'2397.222', u'numaNodes': {u'1': {u'totalMemory': u'1022', u'cpus': [1]}, u'0': {u'totalMemory': u'1022', u'cpus': [0]}}, u'cpuSockets': u'2', u'vlans': {}, u'version_name': u'Snow Man', 'lastClientIface': 'eth0', u'cpuCores': u'2', u'hostedEngineDeployed': True, u'hugepages': [1048576, 2048], u'guestOverhead': u'65', u'additionalFeatures': [u'libgfapi_supported'], u'kvmEnabled': u'true', u'memSize': u'1836', u'emulatedMachines': [u'pc-i440fx-rhel7.1.0', u'pc-q35-rhel7.3.0', u'rhel6.3.0', u'pc-i440fx-rhel7.5.0', u'pc-i440fx-rhel7.0.0', u'rhel6.1.0', u'pc-i440fx-rhel7.4.0', u'rhel6.6.0', u'pc-q35-rhel7.5.0', u'rhel6.2.0', u'pc', u'pc-i440fx-rhel7.3.0', u'q35', u'pc-i440fx-rhel7.2.0', u'rhel6.4.0', u'pc-q35-rhel7.4.0', u'rhel6.0.0', u'rhel6.5.0'], u'rngSources': [u'hwrng', u'random'], u'operatingSystem': {u'release': u'5.1804.el7.centos', u'pretty_name': u'CentOS Linux 7 (Core)', u'version': u'7', u'name': u'RHEL'}}} from=::ffff:192.168.201.2,35392 (api:53)
>2018-06-06 06:38:43,936-0400 INFO  (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.getCapabilities succeeded in 0.98 seconds (__init__:311)
>2018-06-06 06:38:43,958-0400 INFO  (jsonrpc/3) [api.host] START getHardwareInfo() from=::ffff:192.168.201.2,35392 (api:47)
>2018-06-06 06:38:44,001-0400 INFO  (jsonrpc/3) [api.host] FINISH getHardwareInfo return={'status': {'message': 'Done', 'code': 0}, 'info': {'systemProductName': 'KVM', 'systemUUID': 'F49116CE-B695-42AC-B5F8-4602AD7201C5', 'systemFamily': 'Red Hat Enterprise Linux', 'systemVersion': 'RHEL 7.4.0 PC (i440FX + PIIX, 1996)', 'systemManufacturer': 'Red Hat'}} from=::ffff:192.168.201.2,35392 (api:53)
>2018-06-06 06:38:44,002-0400 INFO  (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call Host.getHardwareInfo succeeded in 0.04 seconds (__init__:311)

Actions: View

Attachments on bug 1587962: 1448290