RE: TSVNChace in endless loop on network file system: 1.5.6, Build 14908
From: Jay Kuhn <jay.kuhn_at_impinj.com>
Date: Wed, 14 Jan 2009 20:55:20 -0800
Hi Stefan,
Good debugging advice. Terry, thanks for jumping in also. I couldn't respond yesterday.
Stefen, I installed process mon, filtered, watched the TSVN window etc your recommended to Terry. Here is what I did and saw.
1. Log out, Log in
2. Setup the process monitor (PM) and TSVNCahe file crawl window, task manager etc to wathc cpu time (CPU)
TSNV is not taking any CPU, no PM events, no crawling messages.
3. Open the explorer window to my "problem" SVN work area. I tried not to touch my keyboard or mouse any more at this point.
4. Now, interesting..
- My browswer opened really slowly on the folder. I think redrew a few times. Didn't notice any flashing icons this time but it the basic issues, things are really slow.
--TSVN CPU up to the 10-20%
- Continuous events in process monitor, I filtered out all registery events. These are all file IO. opening and closing files in by svn constantly.
- NO ACTIVITY on the crawler window. Very interesting.
- I think my explorer window is sometimes
redrawing. I'm not touching anything, just mainly watching the process monitor.
OK, after about 2 minutes I saved the process monitor as a CSV file. 108,000 events over about 3 minutes (7:55:07.3766917 PM -7:57:59.5240263 PM)
I brought in 64K lines to excel and sorted on path. I think it is stuck in a loop. It is visiting many, many different files. Most of them are the "entries" files but there are others. It is going to TSVNCache.exe of course but that is not interesting.
All I can do here is scan around a little and send you some observations I make. The whole file is 20MB. Let me know if there is anything specific you want or if you want the whole file tell me where to FTP.
Example file: T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5t_sim\.svn\entries)
Here is a small snipet of th the entries for this files total of 1600 entries spanning most of the 3 minutes.
7:55:09 PM
TSVNCache.exe
2420
CreateFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Desired Access: Generic Read, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
7:55:09 PM
TSVNCache.exe
2420
CreateFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Desired Access: Read Attributes, Synchronize, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Complete If Oplocked, Random Access, Attributes: N, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
7:55:09 PM
TSVNCache.exe
2420
QueryStandardInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
FAST IO DISALLOWED
7:55:09 PM
TSVNCache.exe
2420
QueryStandardInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
AllocationSize: 4,096, EndOfFile: 1,310, NumberOfLinks: 1, DeletePending: False, Directory: False
7:55:09 PM
TSVNCache.exe
2420
QueryBasicInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
FAST IO DISALLOWED
7:55:09 PM
TSVNCache.exe
2420
QueryBasicInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
CreationTime: 12/15/2008 7:57:46 PM, LastAccessTime: 1/14/2009 7:54:09 PM, LastWriteTime: 12/15/2008 7:57:46 PM, ChangeTime: 12/15/2008 7:57:46 PM, FileAttributes: RA
7:55:09 PM
TSVNCache.exe
2420
QueryFileInternalInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
IndexNumber: 0xe7a56008
7:55:09 PM
TSVNCache.exe
2420
CloseFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
7:55:09 PM
TSVNCache.exe
2420
CreateFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Desired Access: Generic Read, Write Attributes, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Complete If Oplocked, Random Access, Attributes: N, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
7:55:09 PM
TSVNCache.exe
2420
QueryBasicInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
FAST IO DISALLOWED
7:55:09 PM
TSVNCache.exe
2420
QueryBasicInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
CreationTime: 12/15/2008 7:57:46 PM, LastAccessTime: 1/14/2009 7:54:09 PM, LastWriteTime: 12/15/2008 7:57:46 PM, ChangeTime: 12/15/2008 7:57:46 PM, FileAttributes: RA
7:55:09 PM
TSVNCache.exe
2420
SetBasicInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
CreationTime: -1, LastAccessTime: -1, LastWriteTime: -1, ChangeTime: -1, FileAttributes: n/a
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 256
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 1,310, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
About 20 more repeats of this
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 512
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 64
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 512
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 1,024, Length: 286
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 1,310
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 4
7:55:09 PM
TSVNCache.exe
2420
QueryStandardInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
FAST IO DISALLOWED
7:55:09 PM
TSVNCache.exe
2420
QueryStandardInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
AllocationSize: 4,096, EndOfFile: 1,310, NumberOfLinks: 1, DeletePending: False, Directory: False
7:55:09 PM
TSVNCache.exe
2420
CloseFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
7:55:09 PM
TSVNCache.exe
2420
CreateFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Desired Access: Read Attributes, Write Attributes, Disposition: Open, Options: , Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
7:55:09 PM
TSVNCache.exe
2420
QueryBasicInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
CreationTime: 12/15/2008 7:57:46 PM, LastAccessTime: 1/14/2009 7:56:36 PM, LastWriteTime: 12/15/2008 7:57:46 PM, ChangeTime: 12/15/2008 7:57:46 PM, FileAttributes: RA
7:55:09 PM
TSVNCache.exe
2420
CloseFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 80
7:55:09 PM
TSVNCache.exe
2420
ReadFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Offset: 0, Length: 1,310, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
7:55:09 PM
TSVNCache.exe
2420
CloseFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
7:55:11 PM
TSVNCache.exe
2420
CreateFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Desired Access: Generic Read, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
7:55:11 PM
TSVNCache.exe
2420
CreateFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries
SUCCESS
Desired Access: Read Attributes, Synchronize, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Complete If Oplocked, Random Access, Attributes: N, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
That example snippet above just goes on like that for the whole duration of the capture. It just continues like this for the whole three minutes. But it is doing this many other files also, so I assume in each second is rapidly looping around and coming back to this one many times a second. The only error I see bleow s FAST_IO_DISALLOWED.
I do so it is looking at some non-version files at the problem level of my hierarchy and some different results that look "troubling".
7:55:09 PM
TSVNCache.exe
2420
QueryOpen
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
FAST IO DISALLOWED
7:55:09 PM
TSVNCache.exe
2420
CreateFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
SUCCESS
Desired Access: Read Attributes, Disposition: Open, Options: Open For Backup, Open Reparse Point, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
7:55:09 PM
TSVNCache.exe
2420
QueryNetworkOpenInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
FAST IO DISALLOWED
7:55:09 PM
TSVNCache.exe
2420
QueryNetworkOpenInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
INVALID PARAMETER
7:55:09 PM
TSVNCache.exe
2420
QueryBasicInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
SUCCESS
CreationTime: 12/15/2008 1:27:24 PM, LastAccessTime: 12/15/2008 7:57:29 PM, LastWriteTime: 12/15/2008 10:36:25 PM, ChangeTime: 12/15/2008 10:36:25 PM, FileAttributes: A
7:55:09 PM
TSVNCache.exe
2420
QueryStandardInformationFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
SUCCESS
AllocationSize: 16,384, EndOfFile: 14,761, NumberOfLinks: 1, DeletePending: False, Directory: False
7:55:09 PM
TSVNCache.exe
2420
CloseFile
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
SUCCESS
7:55:11 PM
TSVNCache.exe
2420
QueryOpen
T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state
FAST IO DISALLOWED
Hope this is of some value,
Jay Kuhn
-----Original Message-----
www.My-Software.co.uk - Sales wrote:
> Hi
>
> Did this and nothing happens, while it does this. However if I shutdown
> TSVNCache.exe and then it re-opens when I open explorer at root??? But I
> don't have any .svn folders in root and this is when it keeps freshing
> explorer and giving this issue.
The cache is restarted because the explorer does not know whether a
folder is versioned or not. That's why it starts the cache and asks the
cache for that information. It doesn't matter whether you have a working
copy on the root or not - the cache is started anyway.
Stefan
--
___
oo // \\ "De Chelonian Mobile"
(_,\/ \_/ \ TortoiseSVN
\ \_/_\_/> The coolest Interface to (Sub)Version Control
/_/ \_\ http://tortoisesvn.net
------------------------------------------------------
http://tortoisesvn.tigris.org/ds/viewMessage.do?dsForumId=4061&dsMessageId=1024417
To unsubscribe from this discussion, e-mail: [users-unsubscribe_at_tortoisesvn.tigris.org].
------------------------------------------------------
http://tortoisesvn.tigris.org/ds/viewMessage.do?dsForumId=4061&dsMessageId=1025858
To unsubscribe from this discussion, e-mail: [users-unsubscribe_at_tortoisesvn.tigris.org].
Received on 2009-01-15 07:07:10 CET
|
This is an archived mail posted to the TortoiseSVN Users mailing list.
This site is subject to the Apache Privacy Policy and the Apache Public Forum Archive Policy.