Thread-11037::DEBUG::2012-08-28 15:46:25,144::clientIF::239::Storage.Dispatcher.Protect::(wrapper) [10.16.250.216]
Thread-11037::INFO::2012-08-28 15:46:25,146::dispatcher::94::Storage.Dispatcher.Protect::(run) Run and protect: getDeviceList, args: ()
Thread-11037::DEBUG::2012-08-28 15:46:25,146::task::495::TaskManager.Task::(_debug) Task 0715b88e-1fcf-4dda-9328-04653ea8256e: moving from state init -> state preparing
Thread-11037::DEBUG::2012-08-28 15:46:25,147::misc::1010::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
Thread-11037::DEBUG::2012-08-28 15:46:25,148::misc::1012::SamplingMethod::(__call__) Got in to sampling method
Thread-11037::DEBUG::2012-08-28 15:46:25,149::misc::1010::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
Thread-11037::DEBUG::2012-08-28 15:46:25,149::misc::1012::SamplingMethod::(__call__) Got in to sampling method
Thread-11037::DEBUG::2012-08-28 15:46:25,150::iscsi::699::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
Thread-11037::DEBUG::2012-08-28 15:46:25,176::iscsi::699::Storage.Misc.excCmd::(rescan) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
Thread-11037::DEBUG::2012-08-28 15:46:25,177::misc::1020::SamplingMethod::(__call__) Returning last result
Thread-11037::DEBUG::2012-08-28 15:46:25,542::multipath::61::Storage.Misc.excCmd::(rescan) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
Thread-11037::DEBUG::2012-08-28 15:46:25,657::multipath::61::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0
Thread-11037::DEBUG::2012-08-28 15:46:25,658::lvm::547::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
Thread-11037::DEBUG::2012-08-28 15:46:25,659::lvm::549::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
Thread-11037::DEBUG::2012-08-28 15:46:25,659::lvm::559::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
Thread-11037::DEBUG::2012-08-28 15:46:25,660::lvm::561::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
Thread-11037::DEBUG::2012-08-28 15:46:25,660::lvm::580::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
Thread-11037::DEBUG::2012-08-28 15:46:25,660::lvm::582::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
Thread-11037::DEBUG::2012-08-28 15:46:25,661::misc::1020::SamplingMethod::(__call__) Returning last result
Thread-11037::DEBUG::2012-08-28 15:46:25,661::lvm::406::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex
Thread-11037::DEBUG::2012-08-28 15:46:25,664::lvm::374::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \\"a%3600508b1001035333920202020200005|3600601601cde1d00d4cb3734d2ede111%\\", \\"r%.*%\\" ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1 }  backup {  retain_min = 50  retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None)
Thread-11037::DEBUG::2012-08-28 15:46:25,891::lvm::374::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = "  Couldn't find device with uuid 4E7D8I-67pR-sB1X-fjo0-QoQ2-z2Ay-41PHg4.\n"; <rc> = 0
Thread-11037::DEBUG::2012-08-28 15:46:25,892::lvm::429::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex
MainProcess|Thread-11037::DEBUG::2012-08-28 15:46:25,892::devicemapper::144::Storage.Misc.excCmd::(_getPathsStatus) '/sbin/dmsetup status' (cwd None)
MainProcess|Thread-11037::DEBUG::2012-08-28 15:46:25,909::devicemapper::144::Storage.Misc.excCmd::(_getPathsStatus) SUCCESS: <err> = ''; <rc> = 0
MainProcess|Thread-11037::DEBUG::2012-08-28 15:46:25,915::multipath::159::Storage.Misc.excCmd::(getScsiSerial) '/sbin/scsi_id --page=0x80 --whitelisted --export --replace-whitespace --device=/dev/dm-0' (cwd None)
MainProcess|Thread-11037::DEBUG::2012-08-28 15:46:25,925::multipath::159::Storage.Misc.excCmd::(getScsiSerial) SUCCESS: <err> = ''; <rc> = 0
MainProcess|Thread-11037::DEBUG::2012-08-28 15:46:25,936::multipath::159::Storage.Misc.excCmd::(getScsiSerial) '/sbin/scsi_id --page=0x80 --whitelisted --export --replace-whitespace --device=/dev/dm-2' (cwd None)
MainProcess|Thread-11037::DEBUG::2012-08-28 15:46:25,946::multipath::159::Storage.Misc.excCmd::(getScsiSerial) SUCCESS: <err> = ''; <rc> = 0
Thread-11037::WARNING::2012-08-28 15:46:25,947::multipath::261::Storage.Multipath::(pathListIter) Problem getting hbtl from device `cciss!c0d1`
Thread-11037::WARNING::2012-08-28 15:46:25,949::hsm::735::Storage.HSM::(_getDeviceList) Ignoring partitioned device {'product': 'LOGICAL VOLUME', 'dm': 'dm-2', 'devtypes': ['FCP'], 'fwrev': '2.08', 'logicalblocksize': '512', 'connections': [], 'devtype': 'FCP', 'physicalblocksize': '512', 'vendor': 'HP', 'serial': 'SHP_LOGICAL_VOLUME_PH79MW7539', 'guid': '3600508b1001035333920202020200005', 'paths': [{'devnum': DeviceNumber(Major=104, Minor=16), 'physdev': 'cciss!c0d1', 'type': 'FCP', 'state': 'active'}], 'capacity': '220122071040'}
Thread-11037::DEBUG::2012-08-28 15:46:25,950::task::495::TaskManager.Task::(_debug) Task 0715b88e-1fcf-4dda-9328-04653ea8256e: finished: {'devList': [{'fwrev': '0220', 'vgUUID': 'aBzHsl-xJpe-4dAD-9USA-GhcK-rsi2-DZZGoU', 'pathlist': [], 'logicalblocksize': '512', 'devtype': 'FCP', 'physicalblocksize': '512', 'serial': 'SDGC_RAID_5_FCNHW073300009', 'GUID': '3600601601cde1d00d4cb3734d2ede111', 'productID': 'RAID 5', 'vendorID': 'DGC', 'capacity': '697932185600', 'partitioned': False, 'pathstatus': [{'physdev': 'sda', 'state': 'active', 'type': 'FCP', 'lun': '0'}, {'physdev': 'sdb', 'state': 'active', 'type': 'FCP', 'lun': '0'}, {'physdev': 'sde', 'state': 'active', 'type': 'FCP', 'lun': '0'}, {'physdev': 'sdf', 'state': 'active', 'type': 'FCP', 'lun': '0'}, {'physdev': 'sdc', 'state': 'active', 'type': 'FCP', 'lun': '0'}, {'physdev': 'sdd', 'state': 'active', 'type': 'FCP', 'lun': '0'}, {'physdev': 'sdg', 'state': 'active', 'type': 'FCP', 'lun': '0'}, {'physdev': 'sdh', 'state': 'active', 'type': 'FCP', 'lun': '0'}], 'pvUUID': 'MoHM1u-dxeh-GO1V-axCo-LfP2-33uU-F4ifcD'}]}
Thread-11037::DEBUG::2012-08-28 15:46:25,951::task::495::TaskManager.Task::(_debug) Task 0715b88e-1fcf-4dda-9328-04653ea8256e: moving from state preparing -> state finished
Thread-11037::DEBUG::2012-08-28 15:46:25,951::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
Thread-11037::DEBUG::2012-08-28 15:46:25,951::resourceManager::821::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-11037::DEBUG::2012-08-28 15:46:25,952::task::495::TaskManager.Task::(_debug) Task 0715b88e-1fcf-4dda-9328-04653ea8256e: ref 0 aborting False
Thread-11037::INFO::2012-08-28 15:46:25,952::dispatcher::100::Storage.Dispatcher.Protect::(run) Run and protect: getDeviceList, Return response: {'status': {'message': 'OK', 'code': 0}, 'devList': [{'vendorID': 'DGC', 'capacity': '697932185600', 'fwrev': '0220', 'partitioned': False, 'vgUUID': 'aBzHsl-xJpe-4dAD-9USA-GhcK-rsi2-DZZGoU', 'pathlist': [], 'logicalblocksize': '512', 'pathstatus': [{'physdev': 'sda', 'type': 'FCP', 'state': 'active', 'lun': '0'}, {'physdev': 'sdb', 'type': 'FCP', 'state': 'active', 'lun': '0'}, {'physdev': 'sde', 'type': 'FCP', 'state': 'active', 'lun': '0'}, {'physdev': 'sdf', 'type': 'FCP', 'state': 'active', 'lun': '0'}, {'physdev': 'sdc', 'type': 'FCP', 'state': 'active', 'lun': '0'}, {'physdev': 'sdd', 'type': 'FCP', 'state': 'active', 'lun': '0'}, {'physdev': 'sdg', 'type': 'FCP', 'state': 'active', 'lun': '0'}, {'physdev': 'sdh', 'type': 'FCP', 'state': 'active', 'lun': '0'}], 'devtype': 'FCP', 'physicalblocksize': '512', 'pvUUID': 'MoHM1u-dxeh-GO1V-axCo-LfP2-33uU-F4ifcD', 'serial': 'SDGC_RAID_5_FCNHW073300009', 'GUID': '3600601601cde1d00d4cb3734d2ede111', 'productID': 'RAID 5'}]}