Note: This bug is displayed in read-only format because the product is no longer active in Red Hat Bugzilla.

Bug 1361551

Summary: Vdsm logging should be improved
Product: Red Hat Enterprise Virtualization Manager Reporter: Sandro Bonazzola <sbonazzo>
Component: vdsmAssignee: Milan Zamazal <mzamazal>
Status: CLOSED ERRATA QA Contact: Shira Maximov <mshira>
Severity: medium Docs Contact:
Priority: medium    
Version: unspecifiedCC: bazulay, bugs, gklein, lsurette, mavital, mgoldboi, michal.skrivanek, mzamazal, sbonazzo, srevivo, tjelinek, ycui, ykaul
Target Milestone: ovirt-3.6.9Keywords: ZStream
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of: 1351247 Environment:
Last Closed: 2016-09-21 18:07:21 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: Virt RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On: 1351247    
Bug Blocks:    

Comment 1 Michal Skrivanek 2016-07-29 13:12:06 UTC
logging improved

Comment 2 Shira Maximov 2016-08-30 12:53:13 UTC
verified on : 
Red Hat Enterprise Virtualization Manager Version: 3.6.9-0.1.el6


verification steps:
1. check that there are INFO messages of "Current getAllVmStats.." which contains correct information: 

root@RHEL7 ~]# cat /var/log/vdsm/vdsm.log | grep "Current getAllVmStats"
jsonrpc.Executor/2::INFO::2016-08-30 14:54:33,391::throttledlog::105::throttled::(log) Current getAllVmStats: {u'508c2649-a02f-46d2-a2e1-b87e77fb6155': 'Up', u'0ef0a731-c2a9-47fb-8c90-ac946d2cef38': 'Up', u'fa667c4a-c897-4605-9630-2f892d3dc92d': 'Up'}
jsonrpc.Executor/6::INFO::2016-08-30 15:07:11,851::throttledlog::105::throttled::(log) Current getAllVmStats: {u'508c2649-a02f-46d2-a2e1-b87e77fb6155': 'Up', u'0ef0a731-c2a9-47fb-8c90-ac946d2cef38': 'Up'}
jsonrpc.Executor/1::INFO::2016-08-30 15:19:48,869::throttledlog::105::throttled::(log) Current getAllVmStats: {u'508c2649-a02f-46d2-a2e1-b87e77fb6155': 'Up', u'0ef0a731-c2a9-47fb-8c90-ac946d2cef38': 'Up'}



2. check that art INFO messages of RPC call XXX succeeded/failed in YYY seconds" message which contains correct information: 

 [root@RHEL7 ~]# cat /var/log/vdsm/vdsm.log | grep "RPC call"
Thread-818::INFO::2016-08-30 14:54:02,771::bindingxmlrpc::1308::vds::(wrapper) RPC call getAllVmStats finished (code=0) in 0.01 seconds
Thread-819::INFO::2016-08-30 14:54:02,803::bindingxmlrpc::1308::vds::(wrapper) RPC call vmGetIoTunePolicy finished (code=0) in 0.00 seconds
jsonrpc.Executor/5::INFO::2016-08-30 14:54:03,174::__init__::514::jsonrpc.JsonRpcServer::(_serveRequest) RPC call Host.getAllVmStats succeeded in 0.01 seconds
Thread-820::INFO::2016-08-30 14:54:03,722::bindingxmlrpc::1308::vds::(wrapper) RPC call vmGetIoTunePolicy finished (code=0) in 0.00 seconds
Thread-821::INFO::2016-08-30 14:54:04,483::bindingxmlrpc::1308::vds::(wrapper) RPC call vmGetIoTunePolicy finished (code=0) in 0.00 seconds
jsonrpc.Executor/6::INFO::2016-08-30 14:54:10,281::__init__::514::jsonrpc.JsonRpcServer::(_serveRequest) RPC call Host.getStats succeeded in 0.02 seconds
Thread-823::INFO::2016-08-30 14:54:17,817::bindingxmlrpc::1308::vds::(wrapper) RPC call getAllVmStats finished (code=0) in 0.00 seconds
Thread-824::INFO::2016-08-30 14:54:17,849::bindingxmlrpc::1308::vds::(wrapper) RPC call vmGetIoTunePolicy finished (code=0) in 0.00 seconds
jsonrpc.Executor/0::INFO::2016-08-30 14:54:18,289::__init__::514::jsonrpc.JsonRpcServer::(_serveRequest) RPC call Host.getAllVmStats succeeded in 0.00 seconds

Comment 4 errata-xmlrpc 2016-09-21 18:07:21 UTC
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory, and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://rhn.redhat.com/errata/RHBA-2016-1925.html