Attachment 854125 Details for Bug 1055454 – vdsm.log

vdsm.log

vdsm.log (text/x-log), 9.28 KB, created by Andrew Lau on 2014-01-23 00:06:59 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Andrew Lau

Created: 2014-01-23 00:06:59 UTC

Size: 9.28 KB

patch

obsolete

>Thread-349510::DEBUG::2014-01-23 11:00:56,013::BindingXMLRPC::969::vds::(wrapper) client [172.16.0.10]::call volumesList with () {} flowID [38ea2b86]
>Thread-349510::DEBUG::2014-01-23 11:00:56,067::BindingXMLRPC::976::vds::(wrapper) return volumesList with {'status': {'message': 'Done', 'code': 0}, 'volumes': {'HOSTED-ENGINE': {'transportType': ['TCP'], 'uuid': '224dc5cc-3ce9-4db2-84dc-4694c8bd6759', 'bricks': ['gs01.melb.example.net:/data1/hosted-engine'], 'volumeName': 'HOSTED-ENGINE', 'volumeType': 'DISTRIBUTE', 'replicaCount': '1', 'brickCount': '1', 'distCount': '1', 'volumeStatus': 'ONLINE', 'stripeCount': '1', 'options': {'cluster.server-quorum-type': 'server', 'cluster.eager-lock': 'enable', 'network.remote-dio': 'enable', 'auth.allow': '172.16.*.*', 'cluster.quorum-type': 'auto', 'performance.quick-read': 'off', 'nfs.disable': 'off', 'performance.io-cache': 'off', 'storage.owner-uid': '36', 'performance.read-ahead': 'off', 'performance.stat-prefetch': 'off', 'storage.owner-gid': '36'}}, 'VM-DATA': {'transportType': ['TCP'], 'uuid': 'f8e51af1-a0ca-411b-9738-c3e673d6c4e3', 'bricks': ['gs01.melb.example.net:/data1/vm-data'], 'volumeName': 'VM-DATA', 'volumeType': 'DISTRIBUTE', 'replicaCount': '1', 'brickCount': '1', 'distCount': '1', 'volumeStatus': 'ONLINE', 'stripeCount': '1', 'options': {'cluster.server-quorum-type': 'server', 'cluster.eager-lock': 'enable', 'performance.stat-prefetch': 'off', 'auth.allow': '172.16.*.*', 'performance.cache-size': '1GB', 'cluster.quorum-type': 'auto', 'performance.quick-read': 'off', 'network.remote-dio': 'enable', 'performance.io-cache': 'off', 'storage.owner-uid': '36', 'performance.read-ahead': 'off', 'storage.owner-gid': '36'}}}}
>Thread-349512::INFO::2014-01-23 11:00:57,149::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-349512::INFO::2014-01-23 11:00:57,150::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'562f0160-7b80-42e2-b248-5754455c40fc': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000825938', 'lastCheck': '1.9', 'valid': True}, '436c344c-bc57-441b-b311-c9595c6039e1': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.000512216', 'lastCheck': '5.1', 'valid': True}, 'ff3a0c94-11dc-446d-b093-5f68ad81520d': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00095357', 'lastCheck': '3.0', 'valid': True}}
>Thread-12::DEBUG::2014-01-23 11:00:57,999::utils::555::root::(execCmd) '/sbin/ip -d -o link' (cwd None)
>Thread-12::DEBUG::2014-01-23 11:00:58,036::utils::575::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-349514::INFO::2014-01-23 11:00:59,202::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-349514::INFO::2014-01-23 11:00:59,202::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'562f0160-7b80-42e2-b248-5754455c40fc': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000825938', 'lastCheck': '4.0', 'valid': True}, '436c344c-bc57-441b-b311-c9595c6039e1': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.000512216', 'lastCheck': '7.1', 'valid': True}, 'ff3a0c94-11dc-446d-b093-5f68ad81520d': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00095357', 'lastCheck': '5.1', 'valid': True}}
>Thread-349513::DEBUG::2014-01-23 11:00:59,210::BindingXMLRPC::969::vds::(wrapper) client [172.16.0.11]::call vmGetStats with ('2d425c4c-91b1-4564-b276-f27cc581e6a1',) {}
>Thread-349513::DEBUG::2014-01-23 11:00:59,211::BindingXMLRPC::976::vds::(wrapper) return vmGetStats with {'status': {'message': 'Done', 'code': 0}, 'statsList': [{'username': u'root', 'memUsage': '48', 'acpiEnable': 'true', 'guestFQDN': u'engine.melb.example.net', 'pid': '18113', 'session': 'Unknown', 'displaySecurePort': '-1', 'timeOffset': '0', 'balloonInfo': {}, 'pauseCode': 'NOERR', 'disksUsage': [{u'path': u'/', u'total': '25323499520', u'used': '2546966528', u'fs': u'ext4'}, {u'path': u'/boot', u'total': '507744256', u'used': '55643136', u'fs': u'ext4'}], 'network': {u'vnet0': {'macAddr': '00:16:3e:0a:b7:55', 'rxDropped': '0', 'rxErrors': '0', 'txDropped': '0', 'txRate': '0.0', 'rxRate': '0.0', 'txErrors': '0', 'state': 'unknown', 'speed': '1000', 'name': u'vnet0'}}, 'vmType': 'kvm', 'guestName': u'engine.melb.example.net', 'elapsedTime': '217643', 'memoryStats': {u'swap_out': '0', u'majflt': '0', u'mem_free': '2154068', u'swap_in': '0', u'pageflt': '14', u'mem_total': '3922728', u'mem_unused': '1705300'}, 'cpuSys': '0.87', 'appsList': [u'ovirt-guest-agent-1.0.8-1.el6', u'kernel-2.6.32-431.el6', u'kernel-2.6.32-431.3.1.el6'], 'guestOs': u'2.6.32-431.3.1.el6.x86_64', 'displayType': 'vnc', 'status': 'Up', 'hash': '2950633987041661405', 'vmId': '2d425c4c-91b1-4564-b276-f27cc581e6a1', 'displayIp': '0', 'displayPort': u'5900', 'clientIp': '', 'kvmEnable': 'true', 'disks': {u'vda': {'readLatency': '234138842', 'apparentsize': '26843545600', 'writeLatency': '4253131', 'imageID': 'daa7fa08-1354-4b19-a80f-8694ea63f914', 'flushLatency': '103610', 'readRate': '944.66', 'truesize': '3913945088', 'writeRate': '40282.95'}, 'hdc': {'readLatency': '0', 'apparentsize': '0', 'writeLatency': '0', 'flushLatency': '0', 'readRate': '0.00', 'truesize': '0', 'writeRate': '0.00'}}, 'monitorResponse': '0', 'statsAge': '2.00', 'cpuUser': '14.92', 'lastLogin': 1390435094.701292, 'guestIPs': u'172.16.0.10', 'netIfaces': [{u'hw': u'00:16:3e:0a:b7:55', u'name': u'eth0', u'inet': [u'172.16.0.10'], u'inet6': [u'fe80::216:3eff:fe0a:b755']}]}]}
>Thread-12::DEBUG::2014-01-23 11:01:00,052::utils::555::root::(execCmd) '/sbin/ip -d -o link' (cwd None)
>Thread-12::DEBUG::2014-01-23 11:01:00,092::utils::575::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>Thread-349516::DEBUG::2014-01-23 11:01:01,101::BindingXMLRPC::159::vds::(wrapper) client [127.0.0.1]
>Thread-349516::INFO::2014-01-23 11:01:01,102::logUtils::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=1, spUUID='7e0cc075-eee7-4268-946e-33a79dd08748', conList=[{'port': '', 'connection': '172.16.1.5:/HOSTED-ENGINE', 'iqn': '', 'portal': '', 'user': 'kvm', 'protocol_version': '3', 'password': '******', 'id': '63249e13-a6df-4915-bf5f-be3da81463fc'}], options=None)
>Thread-349516::DEBUG::2014-01-23 11:01:01,108::hsm::2273::Storage.HSM::(__prefetchDomains) nfs local path: /rhev/data-center/mnt/172.16.1.5:_HOSTED-ENGINE
>Thread-349516::DEBUG::2014-01-23 11:01:01,113::hsm::2297::Storage.HSM::(__prefetchDomains) Found SD uuids: ('562f0160-7b80-42e2-b248-5754455c40fc',)
>Thread-349516::DEBUG::2014-01-23 11:01:01,113::hsm::2353::Storage.HSM::(connectStorageServer) knownSDs: {562f0160-7b80-42e2-b248-5754455c40fc: storage.nfsSD.findDomain, ff3a0c94-11dc-446d-b093-5f68ad81520d: storage.glusterSD.findDomain, 436c344c-bc57-441b-b311-c9595c6039e1: storage.nfsSD.findDomain}
>Thread-349516::INFO::2014-01-23 11:01:01,114::logUtils::47::dispatcher::(wrapper) Run and protect: connectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '63249e13-a6df-4915-bf5f-be3da81463fc'}]}
>Thread-349517::DEBUG::2014-01-23 11:01:01,170::BindingXMLRPC::969::vds::(wrapper) client [172.16.0.10]::call volumesList with () {} flowID [430df2fc]
>Thread-349517::DEBUG::2014-01-23 11:01:01,220::BindingXMLRPC::976::vds::(wrapper) return volumesList with {'status': {'message': 'Done', 'code': 0}, 'volumes': {'HOSTED-ENGINE': {'transportType': ['TCP'], 'uuid': '224dc5cc-3ce9-4db2-84dc-4694c8bd6759', 'bricks': ['gs01.melb.example.net:/data1/hosted-engine'], 'volumeName': 'HOSTED-ENGINE', 'volumeType': 'DISTRIBUTE', 'replicaCount': '1', 'brickCount': '1', 'distCount': '1', 'volumeStatus': 'ONLINE', 'stripeCount': '1', 'options': {'cluster.server-quorum-type': 'server', 'cluster.eager-lock': 'enable', 'network.remote-dio': 'enable', 'auth.allow': '172.16.*.*', 'cluster.quorum-type': 'auto', 'performance.quick-read': 'off', 'nfs.disable': 'off', 'performance.io-cache': 'off', 'storage.owner-uid': '36', 'performance.read-ahead': 'off', 'performance.stat-prefetch': 'off', 'storage.owner-gid': '36'}}, 'VM-DATA': {'transportType': ['TCP'], 'uuid': 'f8e51af1-a0ca-411b-9738-c3e673d6c4e3', 'bricks': ['gs01.melb.example.net:/data1/vm-data'], 'volumeName': 'VM-DATA', 'volumeType': 'DISTRIBUTE', 'replicaCount': '1', 'brickCount': '1', 'distCount': '1', 'volumeStatus': 'ONLINE', 'stripeCount': '1', 'options': {'cluster.server-quorum-type': 'server', 'cluster.eager-lock': 'enable', 'performance.stat-prefetch': 'off', 'auth.allow': '172.16.*.*', 'performance.cache-size': '1GB', 'cluster.quorum-type': 'auto', 'performance.quick-read': 'off', 'network.remote-dio': 'enable', 'performance.io-cache': 'off', 'storage.owner-uid': '36', 'performance.read-ahead': 'off', 'storage.owner-gid': '36'}}}}
>Thread-349518::DEBUG::2014-01-23 11:01:01,382::BindingXMLRPC::159::vds::(wrapper) client [172.16.0.11]
>Thread-349518::INFO::2014-01-23 11:01:01,383::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-349518::INFO::2014-01-23 11:01:01,384::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'562f0160-7b80-42e2-b248-5754455c40fc': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000825938', 'lastCheck': '6.2', 'valid': True}, '436c344c-bc57-441b-b311-c9595c6039e1': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.000512216', 'lastCheck': '9.3', 'valid': True}, 'ff3a0c94-11dc-446d-b093-5f68ad81520d': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00095357', 'lastCheck': '7.3', 'valid': True}}
>

Actions: View

Attachments on bug 1055454: 854125 | 854126 | 854127 | 1052367