WinSCP starts very slow - normal?

Questions about Wine on Linux
Post Reply
powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

WinSCP starts very slow - normal?

Post by powi » Wed Dec 25, 2019 3:09 am

I'm on Mint 19.3 with Wine 4.0 running the portable version of WinSCP 5.15.2.
Starting WinSCP takes about 10 seconds until the main window appears and another 5 seconds until the login manager shows up.
Is that perfectly normal that can't be improved or is there s.th. wrong with my config that could be changed to make it load faster?

My CPU is a i5-8520U, which should be far from being slow.

jkfloris
Level 7
Level 7
Posts: 786
Joined: Thu Aug 14, 2014 10:10 am

Re: WinSCP starts very slow - normal?

Post by jkfloris » Wed Dec 25, 2019 10:37 am

I can't reproduce this with WinSCP 5.15.9 and Wine 4.0.3 in a clean wineprefix.
Are there any clues in the terminal output?

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Wed Dec 25, 2019 3:19 pm

Here's the command line output when executing wine WinSCP.exe:

Code: Select all

0009:err:winediag:SECUR32_initNTLMSP ntlm_auth was not found or is outdated. Make sure that ntlm_auth >= 3.0.25 is in your path. Usually, you can find it in the winbind package of your distribution.
0009:fixme:thread:GetThreadUILanguage : stub, returning default language.
0009:fixme:nls:GetThreadPreferredUILanguages 00000038, 0x32d950, (nil) 0x32d978
0009:fixme:nls:get_dummy_preferred_ui_language (0x38 0x32d950 (nil) 0x32d978) returning a dummy value (current locale)
0009:fixme:nls:GetThreadPreferredUILanguages 00000038, 0x32d950, 0x19b3b80 0x32d978
0009:fixme:nls:get_dummy_preferred_ui_language (0x38 0x32d950 0x19b3b80 0x32d978) returning a dummy value (current locale)
0009:fixme:wtsapi:WTSRegisterSessionNotification Stub 0x1007e 0x00000000
0009:fixme:uxtheme:BufferedPaintInit Stub ()
0009:fixme:toolhelp:CreateToolhelp32Snapshot Unimplemented: heap list snapshot
0009:fixme:toolhelp:Heap32ListFirst : stub
002b:fixme:shell:CustomDestinationList_BeginList 0x16aa00 (0x1ecff94 {92ca9dcd-5622-4bba-a805-5e9f541bd8c9} 0x1ecffa8): stub
0009:fixme:msg:ChangeWindowMessageFilterEx 0x1007e c059 1 (nil)
0009:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
0009:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
0009:err:listview:LISTVIEW_WindowProc unknown msg 10bd wp=00d8582c lp=0032f9d0
0009:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
0009:fixme:dwmapi:DwmIsCompositionEnabled 0x32f0e8

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Thu Dec 26, 2019 12:57 am

I've installed winbind to fix the ntlm_auth issue, but it didn't speed up loading.
It's also not subject to WinSCP only: starting the portable version of Notepad++ also takes 4 seconds to load.

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Sun Dec 29, 2019 12:08 pm

I just installed Xubuntu 19.10 in a VirtualBox VM and guess what: WinSCP starts in only 4 seconds instead of 15. :shock:

Attached you can find a screenshot from the loading output on the console, it differs a lot from the one of my Mint installation you can find above.

Any help to fix this super slow start would be much appreciated.
Attachments
wine_xubuntu.png

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Sun Dec 29, 2019 2:00 pm

A first success: after installing the VC Runtime Executables (winetricks vcrun2015) startup time decreased from 15 to 8 seconds.

What else am I missing?
I have a hard time to find out how the fixme's can be fixed. I've googled a lot, but there's seems to be no description on how these fixmes can actually be fixed?

User avatar
Bob Wya
Level 12
Level 12
Posts: 2812
Joined: Sat Oct 16, 2010 7:40 pm
Location: Scotland
Contact:

Re: WinSCP starts very slow - normal?

Post by Bob Wya » Sun Dec 29, 2019 4:36 pm

@powi

Wine 4.0 is no longer supported.
Please retest with a development release of Wine or Wine Staging (currently 5.0-rc3).
See: WineHQ Wiki: Ubuntu.

Please do not post screenshots of terminal output.
Your earlier post, of terminal output with Code tags, was perfect...
Please lets not start having "forum posting regressions"... :oops: :lol:

Ta
Bob

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Mon Dec 30, 2019 3:10 am

Done: apt purge theoldwinestuff, deleted ~/.wine and installed wine-staging as described in the Wiki article.
Now I'm back at 18 seconds even after the installation of vcrun2017. :cry:

Code: Select all

test@vb:~/Downloads/WinSCP-5.15.9-Portable$ wine start WinSCP.exe
000b:fixme:winediag:__wine_start_process Wine Staging 5.0-rc3 is a testing version containing experimental patches.
000b:fixme:winediag:__wine_start_process Please mention your exact version when filing bug reports on winehq.org.
test@vb:~/Downloads/WinSCP-5.15.9-Portable$ 002c:fixme:wtsapi:WTSRegisterSessionNotification Stub 0001007E 0x00000000
002c:fixme:thread:RtlCreateUserThread stack_commit != 0 is unimplemented
002c:fixme:toolhelp:CreateToolhelp32Snapshot Unimplemented: heap list snapshot
002c:fixme:toolhelp:Heap32ListFirst : stub
002c:fixme:ntdll:NtQuerySystemInformation info_class SYSTEM_PERFORMANCE_INFORMATION
002f:fixme:shell:CustomDestinationList_BeginList 0x1d71e08 (0x2466ea4 {92ca9dcd-5622-4bba-a805-5e9f541bd8c9} 0x2466eb8): stub
002c:fixme:msg:ChangeWindowMessageFilterEx 0x1007e c05b 1 (nil)
0039:err:shell:HCR_GetFolderAttributes should be called for simple PIDL's only!
002c:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
002c:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
002c:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
wine: Read access denied for device L"\\??\\Z:\\", FS volume label and serial are not available.
wine: Read access denied for device L"\\??\\Z:\\", FS volume label and serial are not available.
wine: Read access denied for device L"\\??\\Z:\\", FS volume label and serial are not available.
002c:fixme:win:WINNLSEnableIME hwnd 0x101b8 enable -1: stub!
002c:fixme:wtsapi:WTSUnRegisterSessionNotification Stub 0001007E

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Tue Jan 14, 2020 9:24 am

Any help would be appreciated.

User avatar
Bob Wya
Level 12
Level 12
Posts: 2812
Joined: Sat Oct 16, 2010 7:40 pm
Location: Scotland
Contact:

Re: WinSCP starts very slow - normal?

Post by Bob Wya » Tue Jan 14, 2020 10:01 am

powi wrote:
Tue Jan 14, 2020 9:24 am
Any help would be appreciated.
@powi

You can try enabling the WINEDEBUG channel timestamp to see what component of Wine is sucking up time excessively:
See: WineHQ Wiki: Debug Channels.

Bob

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Thu Jan 16, 2020 2:36 pm

See below, I see no additional runtime information in the console output.
I suppose I'm doing s.th. wrong?

Code: Select all

test@vb:~/Downloads/WinSCP-5.15.9-Portable$ WINEDEBUG=+timestamp
test@vb:~/Downloads/WinSCP-5.15.9-Portable$ wine start WinSCP.exe
000b:fixme:winediag:__wine_start_process Wine Staging 5.0-rc5 is a testing version containing experimental patches.
000b:fixme:winediag:__wine_start_process Please mention your exact version when filing bug reports on winehq.org.
test@vb:~/Downloads/WinSCP-5.15.9-Portable$ 002b:fixme:wtsapi:WTSRegisterSessionNotification Stub 0001007E 0x00000000
002b:fixme:thread:RtlCreateUserThread stack_commit != 0 is unimplemented
002b:fixme:toolhelp:CreateToolhelp32Snapshot Unimplemented: heap list snapshot
002b:fixme:toolhelp:Heap32ListFirst : stub
002b:fixme:ntdll:NtQuerySystemInformation info_class SYSTEM_PERFORMANCE_INFORMATION
002e:fixme:shell:CustomDestinationList_BeginList 0x1d71e08 (0x2416ea4 {92ca9dcd-5622-4bba-a805-5e9f541bd8c9} 0x2416eb8): stub
002b:fixme:msg:ChangeWindowMessageFilterEx 0x1007e c059 1 (nil)
0038:err:shell:HCR_GetFolderAttributes should be called for simple PIDL's only!
002b:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
002b:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
002b:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
wine: Read access denied for device L"\\??\\Z:\\", FS volume label and serial are not available.
wine: Read access denied for device L"\\??\\Z:\\", FS volume label and serial are not available.
wine: Read access denied for device L"\\??\\Z:\\", FS volume label and serial are not available.

User avatar
Bob Wya
Level 12
Level 12
Posts: 2812
Joined: Sat Oct 16, 2010 7:40 pm
Location: Scotland
Contact:

Re: WinSCP starts very slow - normal?

Post by Bob Wya » Fri Jan 17, 2020 9:31 am

powi wrote:
Thu Jan 16, 2020 2:36 pm
See below, I see no additional runtime information in the console output.
I suppose I'm doing s.th. wrong?
Yup! :lol:
powi wrote:
Thu Jan 16, 2020 2:36 pm

Code: Select all

test@vb:~/Downloads/WinSCP-5.15.9-Portable$ export WINEDEBUG=+timestamp
test@vb:~/Downloads/WinSCP-5.15.9-Portable$ wine start WinSCP.exe
or
powi wrote:
Thu Jan 16, 2020 2:36 pm

Code: Select all

test@vb:~/Downloads/WinSCP-5.15.9-Portable$ WINEDEBUG=+timestamp wine start WinSCP.exe
The purpose of export is to make variables "be in the environment of subsequently executed commands", whether those commands be subshells or subprocesses.

Bob

powi
Level 1
Level 1
Posts: 9
Joined: Wed Dec 25, 2019 3:00 am

Re: WinSCP starts very slow - normal?

Post by powi » Fri Jan 17, 2020 1:12 pm

OK, my bad. Here's the output:

Code: Select all

test@test-VirtualBox:~/Desktop/Wine/WinSCP$ WINEDEBUG=+timestamp wine start WinSCP.exe
 81.716:0009:fixme:exec:SHELL_execute flags ignored: 0x00000100
test@test-VirtualBox:~/Desktop/Wine/WinSCP$  82.945:002a:fixme:wtsapi:WTSRegisterSessionNotification Stub 0x10078 0x00000000
 83.046:002a:fixme:toolhelp:CreateToolhelp32Snapshot Unimplemented: heap list snapshot
 83.047:002a:fixme:toolhelp:Heap32ListFirst : stub
 83.202:002d:fixme:shell:CustomDestinationList_BeginList 0x1644d0 (0x1ee0094 {92ca9dcd-5622-4bba-a805-5e9f541bd8c9} 0x1ee00a8): stub
 83.212:002a:fixme:msg:ChangeWindowMessageFilterEx 0x10078 c059 1 (nil)
 88.320:002a:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
 89.425:002a:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000
 90.014:002a:err:listview:LISTVIEW_WindowProc unknown msg 109d wp=00000000 lp=00000000

User avatar
Bob Wya
Level 12
Level 12
Posts: 2812
Joined: Sat Oct 16, 2010 7:40 pm
Location: Scotland
Contact:

Re: WinSCP starts very slow - normal?

Post by Bob Wya » Sun Jan 19, 2020 6:10 am

@powi

Hmm, that really isn't great. :cry:

It's not clear what is happening during that initial 83 second startup time.

Could you post a further (more detailed) Wine terminal log, using:

Code: Select all

WINEDEBUG=+timestamp,+tid,warn+all wine start WinSCP.exe
If the log is really big - you might want to post it as a Github Gist (or similar).

Thanks
Bob

Post Reply