Install problem 4.2.6/4.2.7

I am administrating some thousands of computers and experience an error upon upgrading WinSCP.
The error has so far been found on 32-bit Windows OS (XP/Vista). I have so far not found it on Windows 7 (64-bit).
The error is rare (less than 10% of upgrades) and not serious (nothing brakes and workarounds can be made), but make the installation fail near the end with:
2010-03-17 02:09:29.047 Installing the file.
2010-03-17 02:09:29.110 Successfully installed the file.
2010-03-17 02:09:29.110 -- Icon entry --
2010-03-17 02:09:29.110 Filename: C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP.lnk
2010-03-17 02:09:29.376 -- Icon entry --
2010-03-17 02:09:29.376 Filename: C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url
2010-03-17 02:09:29.376 Exception message:
2010-03-17 02:09:29.376 Message box (OK):
Access is denied.

Trying to delete the same files (using Tivoli) gives:
ISSE0116W File C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\Documentation.url is locked.
DISSE0116W File C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url is locked.

The error is so far found when upgrading version 4.2.5 or 4.2.6
(to 4.2.6 or 4.2.7)

Mvh/
JarrE

Were you able to find out what keeps the files locked?

martin wrote:

Were you able to find out what keeps the files locked?

No, and they are only links.

del /F /S (example:)C:\Docume~1\AllUse~1\StartM~1\Programs\WinSCP\Support forum.url
seems to work (as a workaround)

And it never failes on the first link (WinSCP.lnk), but on the others

Regards/
JarrE

Not much to see.
It seems to me that the installation gets a 'Delete pending' the log closes with:
2010-03-21 20:18:08.385 -- Icon entry --
2010-03-21 20:18:08.385 Filename: C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url
2010-03-21 20:18:08.415 Exception message:
2010-03-21 20:18:08.435 Message box (OK):
Access is denied.

So, if the VERYSILENT-installation did not throw a message-window everything would probably end up right...

From Process Monitor:
20:18:08,3101605 winscp426setup.tmp 3672 CreateFile C:\ SUCCESS Desired Access: Read Data/List Directory, Synchronize, Disposition: Open, Options: Directory, Synchronous IO Non-Alert, Attributes: n/a, ShareMode: Read, Write, AllocationSize: n/a, OpenResult: Opened
20:18:08,3102223 winscp426setup.tmp 3672 QueryDirectory C:\Documents and Settings SUCCESS Filter: Documents and Settings, 1: Documents and Settings
20:18:08,3102896 winscp426setup.tmp 3672 CloseFile C:\ SUCCESS
20:18:08,3104586 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings SUCCESS Desired Access: Read Data/List Directory, Synchronize, Disposition: Open, Options: Directory, Synchronous IO Non-Alert, Attributes: n/a, ShareMode: Read, Write, AllocationSize: n/a, OpenResult: Opened
20:18:08,3104938 winscp426setup.tmp 3672 QueryDirectory C:\Documents and Settings\All Users SUCCESS Filter: All Users, 1: All Users
20:18:08,3105399 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings SUCCESS
20:18:08,3106648 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users SUCCESS Desired Access: Read Data/List Directory, Synchronize, Disposition: Open, Options: Directory, Synchronous IO Non-Alert, Attributes: n/a, ShareMode: Read, Write, AllocationSize: n/a, OpenResult: Opened
20:18:08,3106980 winscp426setup.tmp 3672 QueryDirectory C:\Documents and Settings\All Users\Documents SUCCESS Filter: Documents, 1: Documents
20:18:08,3107402 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users SUCCESS
20:18:08,3109634 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS CreationTime: 02.03.2005 14:01:21, LastAccessTime: 21.03.2010 20:18:08, LastWriteTime: 02.03.2005 14:01:21, ChangeTime: 02.03.2005 14:01:21, AllocationSize: 64, EndOfFile: 62, FileAttributes: HSA
20:18:08,3111894 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Documents\desktop.ini 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
20:18:08,3114084 winscp426setup.tmp 3672 LockFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS Exclusive: False, Offset: 0, Length: 4 294 967 295, Fail Immediately: False
20:18:08,3114956 winscp426setup.tmp 3672 QueryStandardInformationFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS AllocationSize: 64, EndOfFile: 62, NumberOfLinks: 1, DeletePending: False, Directory: False
20:18:08,3116364 winscp426setup.tmp 3672 ReadFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS Offset: 0, Length: 62
20:18:08,3117543 winscp426setup.tmp 3672 UnlockFileSingle C:\Documents and Settings\All Users\Documents\desktop.ini RANGE NOT LOCKED Offset: 0, Length: 4 294 967 295
20:18:08,3118434 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS
20:18:08,3125823 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS CreationTime: 02.03.2005 14:01:21, LastAccessTime: 21.03.2010 20:18:08, LastWriteTime: 02.03.2005 14:01:21, ChangeTime: 02.03.2005 14:01:21, AllocationSize: 64, EndOfFile: 62, FileAttributes: HSA
20:18:08,3127891 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Documents\desktop.ini 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
20:18:08,3130073 winscp426setup.tmp 3672 LockFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS Exclusive: False, Offset: 0, Length: 4 294 967 295, Fail Immediately: False
20:18:08,3131464 winscp426setup.tmp 3672 QueryStandardInformationFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS AllocationSize: 64, EndOfFile: 62, NumberOfLinks: 1, DeletePending: False, Directory: False
20:18:08,3132805 winscp426setup.tmp 3672 ReadFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS Offset: 0, Length: 62
20:18:08,3133995 winscp426setup.tmp 3672 UnlockFileSingle C:\Documents and Settings\All Users\Documents\desktop.ini RANGE NOT LOCKED Offset: 0, Length: 4 294 967 295
20:18:08,3134900 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS
20:18:08,3137152 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS CreationTime: 02.03.2005 14:01:21, LastAccessTime: 21.03.2010 20:18:08, LastWriteTime: 02.03.2005 14:01:21, ChangeTime: 02.03.2005 14:01:21, AllocationSize: 64, EndOfFile: 62, FileAttributes: HSA
20:18:08,3138887 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Documents\desktop.ini 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
20:18:08,3141404 winscp426setup.tmp 3672 LockFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS Exclusive: False, Offset: 0, Length: 4 294 967 295, Fail Immediately: False
20:18:08,3142256 winscp426setup.tmp 3672 QueryStandardInformationFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS AllocationSize: 64, EndOfFile: 62, NumberOfLinks: 1, DeletePending: False, Directory: False
20:18:08,3143457 winscp426setup.tmp 3672 ReadFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS Offset: 0, Length: 62
20:18:08,3144820 winscp426setup.tmp 3672 UnlockFileSingle C:\Documents and Settings\All Users\Documents\desktop.ini RANGE NOT LOCKED Offset: 0, Length: 4 294 967 295
20:18:08,3145711 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Documents\desktop.ini SUCCESS
20:18:08,3147279 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Documents SUCCESS Desired Access: Read Data/List Directory, Synchronize, Disposition: Open, Options: Directory, Synchronous IO Non-Alert, Attributes: n/a, ShareMode: Read, Write, AllocationSize: n/a, OpenResult: Opened
20:18:08,3147969 winscp426setup.tmp 3672 QueryDirectory C:\Documents and Settings\All Users\Documents\My Videos SUCCESS Filter: My Videos, 1: My Videos
20:18:08,3148530 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Documents SUCCESS
20:18:08,3151704 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS CreationTime: 02.03.2005 13:11:08, LastAccessTime: 21.03.2010 20:15:50, LastWriteTime: 02.03.2005 13:11:08, ChangeTime: 02.03.2005 13:11:08, AllocationSize: 152, EndOfFile: 151, FileAttributes: HSA
20:18:08,3154059 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini 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
20:18:08,3156213 winscp426setup.tmp 3672 LockFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS Exclusive: False, Offset: 0, Length: 4 294 967 295, Fail Immediately: False
20:18:08,3157090 winscp426setup.tmp 3672 QueryStandardInformationFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS AllocationSize: 152, EndOfFile: 151, NumberOfLinks: 1, DeletePending: False, Directory: False
20:18:08,3158314 winscp426setup.tmp 3672 ReadFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS Offset: 0, Length: 151
20:18:08,3159009 winscp426setup.tmp 3672 ReadFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS Offset: 0, Length: 151, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
20:18:08,3164915 winscp426setup.tmp 3672 UnlockFileSingle C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini RANGE NOT LOCKED Offset: 0, Length: 4 294 967 295
20:18:08,3166200 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS
20:18:08,3168508 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS CreationTime: 02.03.2005 13:11:08, LastAccessTime: 21.03.2010 20:18:08, LastWriteTime: 02.03.2005 13:11:08, ChangeTime: 02.03.2005 13:11:08, AllocationSize: 152, EndOfFile: 151, FileAttributes: HSA
20:18:08,3170770 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini 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
20:18:08,3172980 winscp426setup.tmp 3672 LockFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS Exclusive: False, Offset: 0, Length: 4 294 967 295, Fail Immediately: False
20:18:08,3173855 winscp426setup.tmp 3672 QueryStandardInformationFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS AllocationSize: 152, EndOfFile: 151, NumberOfLinks: 1, DeletePending: False, Directory: False
20:18:08,3175218 winscp426setup.tmp 3672 ReadFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS Offset: 0, Length: 151
20:18:08,3176464 winscp426setup.tmp 3672 UnlockFileSingle C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini RANGE NOT LOCKED Offset: 0, Length: 4 294 967 295
20:18:08,3177358 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS
20:18:08,3179489 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS CreationTime: 02.03.2005 13:11:08, LastAccessTime: 21.03.2010 20:18:08, LastWriteTime: 02.03.2005 13:11:08, ChangeTime: 02.03.2005 13:11:08, AllocationSize: 152, EndOfFile: 151, FileAttributes: HSA
20:18:08,3181459 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini 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
20:18:08,3183479 winscp426setup.tmp 3672 LockFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS Exclusive: False, Offset: 0, Length: 4 294 967 295, Fail Immediately: False
20:18:08,3184356 winscp426setup.tmp 3672 QueryStandardInformationFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS AllocationSize: 152, EndOfFile: 151, NumberOfLinks: 1, DeletePending: False, Directory: False
20:18:08,3185532 winscp426setup.tmp 3672 ReadFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS Offset: 0, Length: 151
20:18:08,3186683 winscp426setup.tmp 3672 UnlockFileSingle C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini RANGE NOT LOCKED Offset: 0, Length: 4 294 967 295
20:18:08,3187591 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Documents\My Videos\Desktop.ini SUCCESS
20:18:08,3192011 winscp426setup.tmp 3672 WriteFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP.lnk SUCCESS Offset: 0, Length: 1 536
20:18:08,3197704 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP.lnk SUCCESS
20:18:08,6190051 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 778, Length: 26
20:18:08,6192688 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 804, Length: 16
20:18:08,6194227 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 820, Length: 2
20:18:08,6196560 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 822, Length: 26
20:18:08,6198069 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 848, Length: 92
20:18:08,6199560 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 940, Length: 2
20:18:08,6205164 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP SUCCESS CreationTime: 21.03.2010 20:12:35, LastAccessTime: 21.03.2010 20:18:08, LastWriteTime: 21.03.2010 20:18:07, ChangeTime: 21.03.2010 20:18:07, AllocationSize: 0, EndOfFile: 0, FileAttributes: D
20:18:08,6210369 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.lnk NAME NOT FOUND Desired Access: Read Attributes, Delete, Disposition: Open, Options: Non-Directory File, Open Reparse Point, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a
20:18:08,6213249 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.pif NAME NOT FOUND Desired Access: Read Attributes, Delete, Disposition: Open, Options: Non-Directory File, Open Reparse Point, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a
20:18:08,6215660 winscp426setup.tmp 3672 QueryOpen C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url SUCCESS CreationTime: 21.03.2010 20:12:37, LastAccessTime: 21.03.2010 20:14:19, LastWriteTime: 21.03.2010 20:12:37, ChangeTime: 21.03.2010 20:12:37, AllocationSize: 48, EndOfFile: 44, FileAttributes: A
20:18:08,6442094 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url SUCCESS Desired Access: Read Attributes, Delete, Disposition: Open, Options: Non-Directory File, Open Reparse Point, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened
20:18:08,6444393 winscp426setup.tmp 3672 QueryAttributeTagFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url SUCCESS Attributes: A, ReparseTag: 0x0
20:18:08,6453020 winscp426setup.tmp 3672 SetDispositionInformationFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url SUCCESS Delete: True
20:18:08,6456669 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url SUCCESS
20:18:08,6459747 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP\WinSCP Web Site.url DELETE PENDING Desired Access: Generic Read/Write, Disposition: OverwriteIf, Options: Synchronous IO Non-Alert, Non-Directory File, Attributes: N, ShareMode: None, AllocationSize: 0
20:18:08,6476417 winscp426setup.tmp 3672 CreateFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP SUCCESS Desired Access: Synchronize, Disposition: Open, Options: Directory, Synchronous IO Non-Alert, Open For Backup, Attributes: N, ShareMode: Read, Write, AllocationSize: n/a, OpenResult: Opened
20:18:08,6478096 winscp426setup.tmp 3672 CloseFile C:\Documents and Settings\All Users\Start Menu\Programs\WinSCP SUCCESS
20:18:08,6482879 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 942, Length: 26
20:18:08,6484706 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 968, Length: 18
20:18:08,6486242 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 986, Length: 2
20:18:08,6486859 winscp426setup.tmp 3672 ReadFile C:\WINDOWS\Temp\is-UI9DS.tmp\winscp426setup.tmp SUCCESS Offset: 513 024, Length: 12 288, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
20:18:08,6682409 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 5 988, Length: 26
20:18:08,6684169 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 6 014, Length: 19
20:18:08,6685698 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 6 033, Length: 26
20:18:08,6687226 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 6 059, Length: 17
20:18:08,6688690 winscp426setup.tmp 3672 WriteFile C:\tivoli\logs\winscp426.log SUCCESS Offset: 6 076, Length: 2
20:18:08,6689877 winscp426setup.tmp 3672 ReadFile C:\WINDOWS\system32\user32.dll SUCCESS Offset: 259 072, Length: 12 288, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
20:18:08,6881555 winscp426setup.tmp 3672 ReadFile C:\WINDOWS\system32\user32.dll SUCCESS Offset: 349 184, Length: 12 288, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
20:18:08,7429376 winscp426setup.tmp 3672 ReadFile C:\WINDOWS\system32\user32.dll SUCCESS Offset: 168 960, Length: 4 096, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
20:18:08,7665646 winscp426setup.tmp 3672 ReadFile C:\WINDOWS\system32\gdi32.dll SUCCESS Offset: 46 080, Length: 4 096, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O
20:18:08,7941271 winscp426setup.tmp 3672 ReadFile C:\WINDOWS\system32\win32k.sys SUCCESS Offset: 507 904, Length: 16 384, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O