Bug 789722

Summary: [ovirt] [engine-core] empty connection list is sent to vdsm after deactivate master flow leaves domain running on hosts
Product: [Retired] oVirt Reporter: Haim <hateya>
Component: ovirt-engine-coreAssignee: lpeer <lpeer>
Status: CLOSED WONTFIX QA Contact:
Severity: high Docs Contact:
Priority: unspecified    
Version: unspecifiedCC: acathrow, amureini, hateya, iheim, mgoldboi, yeylon, ykaul
Target Milestone: ---Keywords: Regression
Target Release: 3.3.4   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard: storage
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2012-12-10 19:10:59 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: Storage RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:

Description Haim 2012-02-12 16:12:56 UTC
Description of problem:

the following describes log snip upon master deactivation flow, as you can see, connection list passed to vdsm is empty, hence, nothing gets disconnected on HSM\SPM side. 

This is true only for this flow.

[root@rhevm-a8c-03 ovirt-engine]# tail -f  /usr/local/jboss-as-7.1.0.Beta1b/standalone/log/engine/engine.log | egrep --color "Deactivate|Discon"
2012-02-12 11:01:49,111 INFO  [org.ovirt.engine.core.bll.storage.DeactivateStorageDomainCommand] (pool-5-thread-1) Running command: DeactivateStorageDomainCommand internal: false. Entities affected :  ID: 7aa228a1-f4aa-4e55-aca1-a3d802ee09ad Type: Storage
2012-02-12 11:01:49,177 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeactivateStorageDomainVDSCommand] (pool-5-thread-1) START, DeactivateStorageDomainVDSCommand(storagePoolId = 2319b7ff-09f6-488c-83c4-aa78139db3b4, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 7aa228a1-f4aa-4e55-aca1-a3d802ee09ad, masterDomainId = 05a78fd0-1f41-43cc-b6cd-490e18ac6715, masterVersion = 13), log id: 5c830b7f
2012-02-12 11:02:14,375 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeactivateStorageDomainVDSCommand] (pool-5-thread-1) FINISH, DeactivateStorageDomainVDSCommand, log id: 5c830b7f
2012-02-12 11:02:14,395 INFO  [org.ovirt.engine.core.bll.storage.AfterDeactivateSingleAsyncOperation] (pool-5-thread-5) After deactivate treatment vds: nott-vds2,pool XtreamIO
2012-02-12 11:02:15,584 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (pool-5-thread-1) START, DisconnectStorageServerVDSCommand(vdsId = 89a9d20e-508b-11e1-897a-ab0c759be3e9, storagePoolId = 2319b7ff-09f6-488c-83c4-aa78139db3b4, storageType = ISCSI, connectionList = []), log id: 18777425
2012-02-12 11:02:17,935 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (pool-5-thread-1) FINISH, DisconnectStorageServerVDSCommand, return: {}, log id: 18777425

Comment 1 Haim 2012-02-12 16:14:33 UTC
git hash: 9dacd053d6d011b374b8fc83b88f8e7de368aabf


log on vdsm side - see that no 'iscsiadm' operation is done as apart of this thread:

Thread-15400::INFO::2012-02-12 18:01:02,214::logUtils::37::dispatcher::(wrapper) Run and protect: disconnectStorageServer(domType=3, spUUID='2319b7ff-09f6-488c-83c4-aa78139db3b4', conList=[], options=None)
Thread-15400::INFO::2012-02-12 18:01:02,215::storage_connection::167::Storage.ServerConnection::(disconnect) Request to disconnect ISCSI storage server
Thread-15400::DEBUG::2012-02-12 18:01:02,215::misc::1017::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
Thread-15400::DEBUG::2012-02-12 18:01:02,215::misc::1019::SamplingMethod::(__call__) Got in to sampling method
Thread-15400::DEBUG::2012-02-12 18:01:02,216::misc::1017::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
Thread-15400::DEBUG::2012-02-12 18:01:02,216::misc::1019::SamplingMethod::(__call__) Got in to sampling method
Thread-15400::DEBUG::2012-02-12 18:01:02,217::iscsi::389::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
Thread-15400::DEBUG::2012-02-12 18:01:02,319::iscsi::389::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0
Thread-15400::DEBUG::2012-02-12 18:01:02,319::misc::1027::SamplingMethod::(__call__) Returning last result
Dummy-14102::DEBUG::2012-02-12 18:01:02,846::storage_mailbox::637::Storage.Misc.excCmd::(_checkForMail) 'dd if=/rhev/data-center/2319b7ff-09f6-488c-83c4-aa78139db3b4/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=1024000' (cwd None)
Dummy-14102::DEBUG::2012-02-12 18:01:03,203::storage_mailbox::637::Storage.Misc.excCmd::(_checkForMail) SUCCESS: <err> = '1+0 records in\n1+0 records out\n1024000 bytes (1.0 MB) copied, 0.0482851 s, 21.2 MB/s\n'; <rc> = 0
Thread-15400::DEBUG::2012-02-12 18:01:03,725::multipath::71::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
Thread-15400::DEBUG::2012-02-12 18:01:03,922::multipath::71::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0
Thread-15400::DEBUG::2012-02-12 18:01:03,923::lvm::460::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
Thread-15400::DEBUG::2012-02-12 18:01:03,923::lvm::462::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
Thread-15400::DEBUG::2012-02-12 18:01:03,924::lvm::472::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
Thread-15400::DEBUG::2012-02-12 18:01:03,924::lvm::474::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
Thread-15400::DEBUG::2012-02-12 18:01:03,924::lvm::493::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
Thread-15400::DEBUG::2012-02-12 18:01:03,924::lvm::495::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
Thread-15400::DEBUG::2012-02-12 18:01:03,925::misc::1027::SamplingMethod::(__call__) Returning last result
Thread-15400::INFO::2012-02-12 18:01:03,925::logUtils::39::dispatcher::(wrapper) Run and protect: disconnectStorageServer, Return response: {'statuslist': []}

Comment 2 Itamar Heim 2012-12-10 19:10:59 UTC
Closing old bugs. If this issue is still relevant/important in current version, please re-open the bug.