Hide Forgot
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
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': []}
Closing old bugs. If this issue is still relevant/important in current version, please re-open the bug.