Hello ,
Windows 2008 R2 SP1
DSM 3.5
Snapdrive6.3.1R1
Ontap 8.0.2P3
We have 10 servers installed, dedicated to an exchange 2010 infrastructure.
Each server has 45 Luns.
8 of those servers takes around 15 minutes to enumerates their LUN (when you click on “disks” in the mmc) while 2 of them only takes between 30 to 45 seconds for the same number of LUNs.
Looking at the “snpdrvdbglog files of both a long enumerating server and a fast enumerating server I have noticed the following things.
On the slow server, we issue 4 times the NetShareEnum Call for every volume. Each call takes 10 seconds so 40 seconds per volume, for a total of 14 minutes due to the number of volumes.
On the “quick” servers, for the same process, it doesn’t take any time.
Any idea why we spend each time 10 seconds for this call on some servers and 0s on 2 others ?
See the output below :
Slow enumerating server :
03/28-12:59:07.666 PID:1496 TID:620 System.cpp@649 CSystem::enumerate_virtual_disks: volume_set.modify(33, 192.91.36.123+192.132.84.101, DRPMSAE207A0011
03/28-12:59:07.666 PID:1496 TID:620 volume_set.cpp@141 Start volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:59:07.666 PID:1496 TID:620 volume_set.cpp@14 Start volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:59:07.666 PID:1496 TID:620 VirtualDiskShare.cpp@2569 Start get_cifs_share_name(192.132.84.101, DRPMSAE207A0011)
03/28-12:59:07.666 PID:1496 TID:620 VirtualDiskShare.cpp@998 Start GetShareName(192.132.84.101, DRPMSAE207A0011)
03/28-12:59:07.666 PID:1496 TID:620 VirtualDiskShare.cpp@1006 GetShareName: calling NetShareEnum
03/28-12:59:17.697 PID:1496 TID:620 VirtualDiskShare.cpp@1058 GetShareName: did not find share the 1st time, try again...
03/28-12:59:17.697 PID:1496 TID:620 VirtualDiskShare.cpp@1070 GetShareName: calling NetShareEnum
03/28-12:59:27.712 PID:1496 TID:620 VirtualDiskShare.cpp@1129 Finish GetShareName: hr = 80070005
03/28-12:59:27.712 PID:1496 TID:620 VirtualDiskShare.cpp@2576 Finish get_cifs_share_name()
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@27 Finish volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@68 Start volume_t::update_volume_clone_status(filer_t& filer)
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@100 Finish volume_t::update_volume_clone_status(filer_t& filer)-volume name:DRPMSAE207A0011, m_is_volume_clone:false
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@33 Start volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@63 Finish volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@106 Start volume_t::update_snapvault_status(filer_t& filer)
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@136 Finish volume_t::update_snapvault_status(filer_t& filer)
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@177 Finish volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:59:27.712 PID:1496 TID:620 System.cpp@649 CSystem::enumerate_virtual_disks: volume_set.modify(34, 192.91.36.123+192.132.84.101, DRPMSAE207A0011
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@141 Start volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:59:27.712 PID:1496 TID:620 volume_set.cpp@14 Start volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:59:27.712 PID:1496 TID:620 VirtualDiskShare.cpp@2569 Start get_cifs_share_name(192.132.84.101, DRPMSAE207A0011)
03/28-12:59:27.712 PID:1496 TID:620 VirtualDiskShare.cpp@998 Start GetShareName(192.132.84.101, DRPMSAE207A0011)
03/28-12:59:27.712 PID:1496 TID:620 VirtualDiskShare.cpp@1006 GetShareName: calling NetShareEnum
03/28-12:59:37.727 PID:1496 TID:620 VirtualDiskShare.cpp@1058 GetShareName: did not find share the 1st time, try again...
03/28-12:59:37.727 PID:1496 TID:620 VirtualDiskShare.cpp@1070 GetShareName: calling NetShareEnum
03/28-12:59:47.758 PID:1496 TID:620 VirtualDiskShare.cpp@1129 Finish GetShareName: hr = 80070005
03/28-12:59:47.758 PID:1496 TID:620 VirtualDiskShare.cpp@2576 Finish get_cifs_share_name()
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@27 Finish volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@68 Start volume_t::update_volume_clone_status(filer_t& filer)
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@100 Finish volume_t::update_volume_clone_status(filer_t& filer)-volume name:DRPMSAE207A0011, m_is_volume_clone:false
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@33 Start volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@63 Finish volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@106 Start volume_t::update_snapvault_status(filer_t& filer)
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@136 Finish volume_t::update_snapvault_status(filer_t& filer)
03/28-12:59:47.758 PID:1496 TID:620 volume_set.cpp@177 Finish volume_t::update_functor::operator()(volume_t* p_volume)
Fast Enumerating server
03/28-12:57:27.548 PID:2128 TID:10336 System.cpp@649 CSystem::enumerate_virtual_disks: volume_set.modify(32, 192.91.36.125+192.132.84.100, DRPMSAE207B0011
03/28-12:57:27.548 PID:2128 TID:10336 volume_set.cpp@141 Start volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:57:27.548 PID:2128 TID:10336 volume_set.cpp@14 Start volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:57:27.548 PID:2128 TID:10336 VirtualDiskShare.cpp@2569 Start get_cifs_share_name(192.132.84.100, DRPMSAE207B0011)
03/28-12:57:27.548 PID:2128 TID:10336 VirtualDiskShare.cpp@998 Start GetShareName(192.132.84.100, DRPMSAE207B0011)
03/28-12:57:27.548 PID:2128 TID:10336 VirtualDiskShare.cpp@1006 GetShareName: calling NetShareEnum
03/28-12:57:27.564 PID:2128 TID:10336 VirtualDiskShare.cpp@1058 GetShareName: did not find share the 1st time, try again...
03/28-12:57:27.564 PID:2128 TID:10336 VirtualDiskShare.cpp@1070 GetShareName: calling NetShareEnum
03/28-12:57:27.564 PID:2128 TID:10336 VirtualDiskShare.cpp@1129 Finish GetShareName: hr = 800706bf
03/28-12:57:27.564 PID:2128 TID:10336 VirtualDiskShare.cpp@2576 Finish get_cifs_share_name()
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@27 Finish volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@68 Start volume_t::update_volume_clone_status(filer_t& filer)
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@100 Finish volume_t::update_volume_clone_status(filer_t& filer)-volume name:DRPMSAE207B0011, m_is_volume_clone:false
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@33 Start volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@63 Finish volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@106 Start volume_t::update_snapvault_status(filer_t& filer)
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@136 Finish volume_t::update_snapvault_status(filer_t& filer)
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@177 Finish volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:57:27.564 PID:2128 TID:10336 System.cpp@649 CSystem::enumerate_virtual_disks: volume_set.modify(33, 192.91.36.125+192.132.84.100, DRPMSAE207B0011
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@141 Start volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:57:27.564 PID:2128 TID:10336 volume_set.cpp@14 Start volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:57:27.564 PID:2128 TID:10336 VirtualDiskShare.cpp@2569 Start get_cifs_share_name(192.132.84.100, DRPMSAE207B0011)
03/28-12:57:27.564 PID:2128 TID:10336 VirtualDiskShare.cpp@998 Start GetShareName(192.132.84.100, DRPMSAE207B0011)
03/28-12:57:27.564 PID:2128 TID:10336 VirtualDiskShare.cpp@1006 GetShareName: calling NetShareEnum
03/28-12:57:27.580 PID:2128 TID:10336 VirtualDiskShare.cpp@1058 GetShareName: did not find share the 1st time, try again...
03/28-12:57:27.580 PID:2128 TID:10336 VirtualDiskShare.cpp@1070 GetShareName: calling NetShareEnum
03/28-12:57:27.595 PID:2128 TID:10336 VirtualDiskShare.cpp@1129 Finish GetShareName: hr = 800706bf
03/28-12:57:27.595 PID:2128 TID:10336 VirtualDiskShare.cpp@2576 Finish get_cifs_share_name()
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@27 Finish volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@68 Start volume_t::update_volume_clone_status(filer_t& filer)
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@100 Finish volume_t::update_volume_clone_status(filer_t& filer)-volume name:DRPMSAE207B0011, m_is_volume_clone:false
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@33 Start volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@63 Finish volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@106 Start volume_t::update_snapvault_status(filer_t& filer)
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@136 Finish volume_t::update_snapvault_status(filer_t& filer)
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@177 Finish volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:57:27.595 PID:2128 TID:10336 System.cpp@649 CSystem::enumerate_virtual_disks: volume_set.modify(34, 192.91.36.125+192.132.84.100, DRPMSAE207B0011
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@141 Start volume_t::update_functor::operator()(volume_t* p_volume)
03/28-12:57:27.595 PID:2128 TID:10336 volume_set.cpp@14 Start volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:57:27.595 PID:2128 TID:10336 VirtualDiskShare.cpp@2569 Start get_cifs_share_name(192.132.84.100, DRPMSAE207B0011)
03/28-12:57:27.595 PID:2128 TID:10336 VirtualDiskShare.cpp@998 Start GetShareName(192.132.84.100, DRPMSAE207B0011)
03/28-12:57:27.595 PID:2128 TID:10336 VirtualDiskShare.cpp@1006 GetShareName: calling NetShareEnum
03/28-12:57:27.595 PID:2128 TID:10336 VirtualDiskShare.cpp@1058 GetShareName: did not find share the 1st time, try again...
03/28-12:57:27.595 PID:2128 TID:10336 VirtualDiskShare.cpp@1070 GetShareName: calling NetShareEnum
03/28-12:57:27.611 PID:2128 TID:10336 VirtualDiskShare.cpp@1129 Finish GetShareName: hr = 800706bf
03/28-12:57:27.611 PID:2128 TID:10336 VirtualDiskShare.cpp@2576 Finish get_cifs_share_name()
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@27 Finish volume_t::update_cifs_share_name(filer_t& filer)
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@68 Start volume_t::update_volume_clone_status(filer_t& filer)
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@100 Finish volume_t::update_volume_clone_status(filer_t& filer)-volume name:DRPMSAE207B0011, m_is_volume_clone:false
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@33 Start volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@63 Finish volume_t::update_snapmirror_status(filer_t& filer)
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@106 Start volume_t::update_snapvault_status(filer_t& filer)
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@136 Finish volume_t::update_snapvault_status(filer_t& filer)
03/28-12:57:27.611 PID:2128 TID:10336 volume_set.cpp@177 Finish volume_t::update_functor::operator()(volume_t* p_volume)
Any help would be very appreciated